Post-stream downloads regularly hanging on "waiting to finalize video"

[olig89]

Describe the bug A clear and concise description of what the bug is.

To Reproduce x

Expected behavior Video to download after stream ends

Screenshots

Logs

2024-07-09T23:00:00.006Z | Scheduler.scheduleClipDownload <info> Scheduler: scheduleClipDownload - disabled 2024-07-09T23:00:00.018Z | scheduler.defaultJobs <info> Scheduler: check_muted_vods - disabled 2024-07-09T23:10:00.006Z | scheduler.defaultJobs <info> Scheduler: check_deleted_vods - disabled 2024-07-09T23:21:51.419Z | hook.HookTwitch <info> Hook called with message ID Mk3x5c1KPjH734fsAekw24Kr0JerOTr3rr9HunW8CEE=, s-version 1, s-type stream.offline, b false (m-retry 0, m-type notification, m-date 2024-07-09T23:21:50.784355251Z) 2024-07-09T23:22:01.314Z | automator.end <info> Stream end 2024-07-09T23:22:01.337Z | automator.end <info> Stream offline for cdawgva 2024-07-09T23:22:06.362Z | hook.HookTwitch <info> Hook called with message ID Mk3x5c1KPjH734fsAekw24Kr0JerOTr3rr9HunW8CEE=, s-version 1, s-type stream.offline, b false (m-retry 1, m-type notification, m-date 2024-07-09T23:21:50.784355251Z) 2024-07-09T23:22:06.363Z | automator.handle <warn> Message Mk3x5c1KPjH734fsAekw24Kr0JerOTr3rr9HunW8CEE= is a retry (1) 2024-07-09T23:22:06.363Z | automator.handle <info> Retrying message Mk3x5c1KPjH734fsAekw24Kr0JerOTr3rr9HunW8CEE= 2024-07-09T23:22:06.396Z | automator.end <info> Stream end 2024-07-09T23:22:06.408Z | automator.end <info> Stream offline for cdawgva 2024-07-09T23:30:00.006Z | scheduler.defaultJobs <info> Scheduler: match_vods - disabled 2024-07-10T00:00:00.030Z | scheduler.defaultJobs <info> Scheduler: export_vods - disabled 2024-07-10T06:34:12.368Z | vod.deleteSegment <info> Delete segment #0 of s2407e0915 - cyber punking a bit (12) 2024-07-10T06:34:12.371Z | vod.saveJSON <success> Saving JSON of s2407e0915 - cyber punking a bit (12) (delete segment) 2024-07-10T06:34:15.637Z | webhook.dispatchWebhook <error> Webhook error to 'https://livestream.gillflix.com': 413 Payload Too Large 2024-07-10T06:34:27.796Z | vod.fixIssues <info> VOD s2407e0915 - cyber punking a bit (12) not started yet, skipping fix! 2024-07-10T06:34:33.828Z | vod.getMediainfo <info> Fetching mediainfo of s2407e0915 - cyber punking a bit (12), segment #0 2024-07-10T06:34:33.828Z | vod.getMediainfo <error> No segments available for mediainfo of s2407e0915 - cyber punking a bit (12) 2024-07-10T06:34:33.828Z | http.api <error> API error 500 returned: {"status":"ERROR","message":"Mediainfo error"} 2024-07-10T06:35:12.963Z | http.api <error> API error 400 returned: {"status":"ERROR","message":"Video already downloaded"} 2024-07-10T09:56:22.581Z | hook.HookTwitch <info> Hook called with message ID FLEg_cZXHXhD4FjGdf9f8Jtes5ti73jf76UUwoD1MfA=, s-version 1, s-type stream.online, b false (m-retry 0, m-type notification, m-date 2024-07-10T09:56:20.781466584Z) 2024-07-10T09:56:22.628Z | automator.handle <info> Automator stream.online event for sips_ (retry 0) 2024-07-10T09:56:22.638Z | automator.handle <info> sips_ stream has ID 40841164805, started 2024-07-10T09:56:06Z 2024-07-10T09:56:22.664Z | clientBroker.notify <info> (streamOnline) sips_ is live!: Farming Simulator 22 back at it 2024-07-10T09:56:22.665Z | automator.handle <info> Skip capture for sips_ because no-capture is set 2024-07-10T09:56:29.978Z | webhook.dispatchWebhook <error> Webhook error to 'https://livestream.gillflix.com': 413 Payload Too Large 2024-07-10T11:00:00.007Z | scheduler.defaultJobs <info> Scheduler: check_muted_vods - disabled 2024-07-10T11:10:00.007Z | scheduler.defaultJobs <info> Scheduler: check_deleted_vods - disabled 2024-07-10T11:30:00.015Z | scheduler.defaultJobs <info> Scheduler: match_vods - disabled 2024-07-10T17:01:14.291Z | hook.HookTwitch <info> Hook called with message ID Zk5Qcwqd64sbpqchVBvFMVaclzZvGzhr3guh3i239tQ=, s-version 1, s-type stream.offline, b false (m-retry 0, m-type notification, m-date 2024-07-10T17:01:11.749467972Z) 2024-07-10T17:01:14.332Z | automator.end <info> Stream end 2024-07-10T17:01:14.344Z | automator.end <info> Stream offline for sips_ 2024-07-10T17:01:14.353Z | clientBroker.notify <info> (streamOffline) sips_ has gone offline!: Was streaming for 1 day. 2024-07-10T17:01:14.353Z | automator.end <warn> Stream offline notification for sips_ but channel is not capturing. 2024-07-10T17:01:14.947Z | tw.vod.downloadVideo <info> Prepare to download VOD 2193938680 2024-07-10T17:01:15.151Z | tw.vod.downloadVideo <info> Downloading VOD 2193938680... 2024-07-10T17:01:15.159Z | helper.execAdvanced <success> Spawned process 1353 for download_vod_2193938680 2024-07-10T17:01:15.169Z | job.save <info> Save job download_vod_2193938680 with PID 1353 to /usr/local/share/twitchautomator/data/cache/pids/download_vod_2193938680.json 2024-07-10T17:01:15.171Z | helper.execAdvanced <info> Attached to all streams for process 1353 for download_vod_2193938680 2024-07-10T17:07:51.567Z | job.clear <info> Clear job download_vod_2193938680 with PID 1353 2024-07-10T17:07:51.570Z | job.clear <success> Job download_vod_2193938680 removed from jobs list 2024-07-10T17:07:51.571Z | helper.execAdvanced <info> Process 1353 for download_vod_2193938680 exited with code 0 2024-07-10T17:07:51.579Z | tw.vod.downloadVideo <info> Downloaded VOD 2193938680...} 2024-07-10T17:07:53.399Z | tw.vod.downloadVideo <info> Starting remux of s2407e1010 - a pirate's life for me (13).mkv 2024-07-10T17:07:53.400Z | video.remux <info> Remuxing /usr/local/share/twitchautomator/data/cache/2193938680.ts to /usr/local/share/twitchautomator/data/storage/vods/sips_/Sea of ThievesSeason 2407/s2407e1010 - a pirate's life for me (13).mkv 2024-07-10T17:07:53.406Z | helper.exec <success> Spawned process 1369 for remux_2193938680.ts 2024-07-10T17:07:53.418Z | job.save <info> Save job remux_2193938680.ts with PID 1369 to /usr/local/share/twitchautomator/data/cache/pids/remux_2193938680.ts.json 2024-07-10T17:07:53.422Z | helper.exec <info> Attached to all streams for process 1369 for remux_2193938680.ts 2024-07-10T17:09:35.325Z | job.setProgress <info> Job remux_2193938680.ts progress: 10% 2024-07-10T17:09:53.340Z | job.setProgress <info> Job remux_2193938680.ts progress: 20% 2024-07-10T17:10:33.609Z | job.setProgress <info> Job remux_2193938680.ts progress: 30% 2024-07-10T17:11:30.751Z | job.setProgress <info> Job remux_2193938680.ts progress: 40% 2024-07-10T17:12:10.770Z | job.setProgress <info> Job remux_2193938680.ts progress: 51% 2024-07-10T17:12:56.021Z | job.setProgress <info> Job remux_2193938680.ts progress: 61% 2024-07-10T17:13:41.634Z | job.setProgress <info> Job remux_2193938680.ts progress: 71% 2024-07-10T17:14:27.797Z | job.setProgress <info> Job remux_2193938680.ts progress: 81% 2024-07-10T17:15:18.344Z | job.setProgress <info> Job remux_2193938680.ts progress: 91% 2024-07-10T17:16:12.615Z | job.clear <info> Clear job remux_2193938680.ts with PID 1369 2024-07-10T17:16:12.616Z | job.clear <success> Job remux_2193938680.ts removed from jobs list 2024-07-10T17:16:12.616Z | helper.exec <info> Process 1369 for remux_2193938680.ts exited with code 0 2024-07-10T17:16:12.622Z | video.remux <success> Remuxed /usr/local/share/twitchautomator/data/cache/2193938680.ts to /usr/local/share/twitchautomator/data/storage/vods/sips_/Sea of ThievesSeason 2407/s2407e1010 - a pirate's life for me (13).mkv 2024-07-10T17:16:12.622Z | tw.vod.downloadVideo <info> Successfully remuxed s2407e1010 - a pirate's life for me (13).mkv, removing /usr/local/share/twitchautomator/data/cache/2193938680.ts 2024-07-10T17:16:14.100Z | tw.vod.downloadVideo <info> Download of s2407e1010 - a pirate's life for me (13).mkv successful 2024-07-10T17:16:14.102Z | tw.channel.createVOD <info> Create VOD JSON for sips_: s2407e1010 - a pirate's life for me (13).json @ /usr/local/share/twitchautomator/data/storage/vods/sips_/Sea of ThievesSeason 2407 2024-07-10T17:16:14.109Z | vod.saveJSON <success> Saving JSON of s2407e1010 - a pirate's life for me (13) (create json) 2024-07-10T17:16:14.122Z | vod.setupAssoc <error> No chapters on s2407e1010 - a pirate's life for me (13)! 2024-07-10T17:16:14.127Z | vod.saveJSON <success> Saving JSON of s2407e1010 - a pirate's life for me (13) (manual creation) 2024-07-10T17:16:14.155Z | vod.addSegment <info> Adding segment s2407e1010 - a pirate's life for me (13).mkv to s2407e1010 - a pirate's life for me (13) 2024-07-10T17:16:14.422Z | automator.end <error> Error downloading VOD at end: s2407e1010 - back at it ({episode}) (Cannot read properties of undefined (reading 'game'))

Server:

• OS or Docker: Docker (Unraid)

Additional context Reading the final errors, it could be something you removed/fixed from a long time ago (I only recently restarted using this)