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)

[MrBrax]

i've only tested the manual download that adds the vod like once, so it's probably very exact in what works correctly

[olig89]

Yeah, checks out.

I did a clean re-install and pretty close to being there.

If I manually download the vod it will follow my instructions correctly but an automated vod download doesn't. Today was a good example for the logs, a streamer did two streams a couple of hours apart - I manually downloaded the first (s2407e1912...mkv) & the second (s2407e1914...mkv) was grabbed automatically:

2024-07-19T14:21:04.019Z | job.clear <info> Clear job download_vod_2201634429 with PID 54 2024-07-19T14:21:04.020Z | job.clear <success> Job download_vod_2201634429 removed from jobs list 2024-07-19T14:21:04.020Z | helper.execAdvanced <info> Process 54 for download_vod_2201634429 exited with code 0 2024-07-19T14:21:04.020Z | tw.vod.downloadVideo <info> Downloaded VOD 2201634429...} 2024-07-19T14:21:04.933Z | tw.vod.downloadVideo <info> Starting remux of s2407e1912 - more cyberpunk.mkv 2024-07-19T14:21:04.936Z | video.remux <info> Remuxing /usr/local/share/twitchautomator/data/cache/2201634429.ts to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407/s2407e1912 - more cyberpunk.mkv 2024-07-19T14:21:04.941Z | helper.exec <success> Spawned process 69 for remux_2201634429.ts 2024-07-19T14:21:04.943Z | job.save <info> Save job remux_2201634429.ts with PID 69 to /usr/local/share/twitchautomator/data/cache/pids/remux_2201634429.ts.json 2024-07-19T14:21:04.945Z | helper.exec <info> Attached to all streams for process 69 for remux_2201634429.ts 2024-07-19T14:21:47.054Z | job.setProgress <info> Job remux_2201634429.ts progress: 10% 2024-07-19T14:22:13.769Z | job.setProgress <info> Job remux_2201634429.ts progress: 21% 2024-07-19T14:22:19.284Z | job.setProgress <info> Job remux_2201634429.ts progress: 31% 2024-07-19T14:22:26.294Z | job.setProgress <info> Job remux_2201634429.ts progress: 41% 2024-07-19T14:22:32.804Z | job.setProgress <info> Job remux_2201634429.ts progress: 51% 2024-07-19T14:22:40.353Z | job.setProgress <info> Job remux_2201634429.ts progress: 62% 2024-07-19T14:23:00.194Z | job.setProgress <info> Job remux_2201634429.ts progress: 72% 2024-07-19T14:23:19.779Z | job.setProgress <info> Job remux_2201634429.ts progress: 82% 2024-07-19T14:23:41.397Z | job.setProgress <info> Job remux_2201634429.ts progress: 92% 2024-07-19T14:23:59.884Z | job.clear <info> Clear job remux_2201634429.ts with PID 69 2024-07-19T14:23:59.885Z | job.clear <success> Job remux_2201634429.ts removed from jobs list 2024-07-19T14:23:59.885Z | helper.exec <info> Process 69 for remux_2201634429.ts exited with code 0 2024-07-19T14:24:00.612Z | video.remux <success> Remuxed /usr/local/share/twitchautomator/data/cache/2201634429.ts to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407/s2407e1912 - more cyberpunk.mkv 2024-07-19T14:24:00.612Z | tw.vod.downloadVideo <info> Successfully remuxed s2407e1912 - more cyberpunk.mkv, removing /usr/local/share/twitchautomator/data/cache/2201634429.ts 2024-07-19T14:24:01.711Z | tw.vod.downloadVideo <info> Download of s2407e1912 - more cyberpunk.mkv successful 2024-07-19T14:24:01.712Z | tw.channel.createVOD <info> Create VOD JSON for sips_: s2407e1912 - more cyberpunk.json @ /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407 2024-07-19T14:24:01.713Z | vod.saveJSON <success> Saving JSON of s2407e1912 - more cyberpunk (create json) 2024-07-19T14:24:01.717Z | vod.setupAssoc <error> No chapters on s2407e1912 - more cyberpunk! 2024-07-19T14:24:01.719Z | vod.saveJSON <success> Saving JSON of s2407e1912 - more cyberpunk (manual creation) 2024-07-19T14:24:01.737Z | vod.addSegment <info> Adding segment s2407e1912 - more cyberpunk.mkv to s2407e1912 - more cyberpunk 2024-07-19T14:24:01.991Z | vod.finalize <info> Finalize s2407e1912 - more cyberpunk @ /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407 2024-07-19T14:24:01.992Z | vod.getMediainfo <info> Fetching mediainfo of s2407e1912 - more cyberpunk, segment #0 2024-07-19T14:24:01.994Z | helper.mediainfo <info> Run mediainfo on /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407/s2407e1912 - more cyberpunk.mkv 2024-07-19T14:24:02.000Z | helper.exec <success> Spawned process 73 for mediainfo_s2407e1912 - more cyberpunk.mkv 2024-07-19T14:24:02.002Z | job.save <info> Save job mediainfo_s2407e1912 - more cyberpunk.mkv with PID 73 to /usr/local/share/twitchautomator/data/cache/pids/mediainfo_s2407e1912 - more cyberpunk.mkv.json 2024-07-19T14:24:02.003Z | helper.exec <info> Attached to all streams for process 73 for mediainfo_s2407e1912 - more cyberpunk.mkv 2024-07-19T14:24:04.162Z | job.clear <info> Clear job mediainfo_s2407e1912 - more cyberpunk.mkv with PID 73 2024-07-19T14:24:04.163Z | job.clear <success> Job mediainfo_s2407e1912 - more cyberpunk.mkv removed from jobs list 2024-07-19T14:24:04.164Z | helper.exec <info> Process 73 for mediainfo_s2407e1912 - more cyberpunk.mkv exited with code 0 2024-07-19T14:24:04.165Z | helper.videometadata <success> /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407/s2407e1912 - more cyberpunk.mkv is a video file 02:34:08 long at 1080p60. 2024-07-19T14:24:04.166Z | vod.saveLosslessCut <info> Saving lossless cut csv for s2407e1912 - more cyberpunk to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407/s2407e1912 - more cyberpunk-llc-edl.csv 2024-07-19T14:24:04.168Z | vod.saveFFMPEGChapters <info> Saving FFMPEG chapters file for s2407e1912 - more cyberpunk to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407/s2407e1912 - more cyberpunk-ffmpeg-chapters.txt 2024-07-19T14:24:04.170Z | vod.saveVTTChapters <info> Saving VTT chapters file for s2407e1912 - more cyberpunk to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077/Season 2407/s2407e1912 - more cyberpunk.chapters.vtt 2024-07-19T14:24:04.177Z | vod.matchProviderVod <info> Trying to match s2407e1912 - more cyberpunk to provider... 2024-07-19T14:24:04.432Z | vod.matchProviderVod <success> Found matching VOD for s2407e1912 - more cyberpunk (2024-07-19T11:00:05.000Z): 2201634429 (more cyberpunk) 2024-07-19T14:24:04.433Z | vod.saveJSON <success> Saving JSON of s2407e1912 - more cyberpunk (manual finalize) 2024-07-19T17:16:15.325Z | hook.HookTwitch <info> Hook called with message ID 58tJMs1epvSJVaSAwHlAms4ba0tdu4JkdkivoOr36No=, s-version 1, s-type stream.offline, b false (m-retry 0, m-type notification, m-date 2024-07-19T17:16:13.901340276Z) 2024-07-19T17:16:25.357Z | automator.end <info> Stream end 2024-07-19T17:16:25.362Z | automator.end <info> Stream offline for sips_ 2024-07-19T17:16:25.364Z | clientBroker.notify <info> (streamOffline) sips_ has gone offline!: Was streaming for about 6 hours. 2024-07-19T17:16:25.364Z | automator.end <warn> Stream offline notification for sips_ but channel is not capturing. 2024-07-19T17:16:25.389Z | hook.HookTwitch <info> Hook called with message ID dczExig8mx3rMfhf-cmp7ngD5cI01f6Fq5pOrguQ3Ys=, s-version 1, s-type stream.offline, b false (m-retry 0, m-type notification, m-date 2024-07-19T17:16:13.904177306Z) 2024-07-19T17:16:25.389Z | hook.HookTwitch <fatal> Invalid signature check for message! 2024-07-19T17:16:25.390Z | http.api <error> API error 400 returned: "Invalid signature check" 2024-07-19T17:16:25.929Z | tw.vod.downloadVideo <info> Prepare to download VOD 2201720366 2024-07-19T17:16:26.125Z | tw.vod.downloadVideo <info> Downloading VOD 2201720366... 2024-07-19T17:16:26.130Z | helper.execAdvanced <success> Spawned process 93 for download_vod_2201720366 2024-07-19T17:16:26.142Z | job.save <info> Save job download_vod_2201720366 with PID 93 to /usr/local/share/twitchautomator/data/cache/pids/download_vod_2201720366.json 2024-07-19T17:16:26.143Z | helper.execAdvanced <info> Attached to all streams for process 93 for download_vod_2201720366 2024-07-19T17:16:30.176Z | hook.HookTwitch <info> Hook called with message ID dczExig8mx3rMfhf-cmp7ngD5cI01f6Fq5pOrguQ3Ys=, s-version 1, s-type stream.offline, b false (m-retry 1, m-type notification, m-date 2024-07-19T17:16:13.904177306Z) 2024-07-19T17:16:30.177Z | hook.HookTwitch <fatal> Invalid signature check for message! 2024-07-19T17:16:30.177Z | http.api <error> API error 400 returned: "Invalid signature check" 2024-07-19T17:16:32.142Z | hook.HookTwitch <info> Hook called with message ID 58tJMs1epvSJVaSAwHlAms4ba0tdu4JkdkivoOr36No=, s-version 1, s-type stream.offline, b false (m-retry 1, m-type notification, m-date 2024-07-19T17:16:13.901340276Z) 2024-07-19T17:16:32.142Z | automator.handle <warn> Message 58tJMs1epvSJVaSAwHlAms4ba0tdu4JkdkivoOr36No= is a retry (1) 2024-07-19T17:16:32.142Z | automator.handle <warn> Not retrying message 58tJMs1epvSJVaSAwHlAms4ba0tdu4JkdkivoOr36No= 2024-07-19T17:16:50.409Z | hook.HookTwitch <info> Hook called with message ID dczExig8mx3rMfhf-cmp7ngD5cI01f6Fq5pOrguQ3Ys=, s-version 1, s-type stream.offline, b false (m-retry 2, m-type notification, m-date 2024-07-19T17:16:13.904177306Z) 2024-07-19T17:16:50.409Z | hook.HookTwitch <fatal> Invalid signature check for message! 2024-07-19T17:16:50.410Z | http.api <error> API error 400 returned: "Invalid signature check" 2024-07-19T17:17:30.669Z | hook.HookTwitch <info> Hook called with message ID dczExig8mx3rMfhf-cmp7ngD5cI01f6Fq5pOrguQ3Ys=, s-version 1, s-type stream.offline, b false (m-retry 3, m-type notification, m-date 2024-07-19T17:16:13.904177306Z) 2024-07-19T17:17:30.670Z | hook.HookTwitch <fatal> Invalid signature check for message! 2024-07-19T17:17:30.670Z | http.api <error> API error 400 returned: "Invalid signature check" 2024-07-19T17:19:15.192Z | job.clear <info> Clear job download_vod_2201720366 with PID 93 2024-07-19T17:19:15.192Z | job.clear <success> Job download_vod_2201720366 removed from jobs list 2024-07-19T17:19:15.193Z | helper.execAdvanced <info> Process 93 for download_vod_2201720366 exited with code 0 2024-07-19T17:19:15.193Z | tw.vod.downloadVideo <info> Downloaded VOD 2201720366...} 2024-07-19T17:19:16.302Z | tw.vod.downloadVideo <info> Starting remux of s2407e1914 - more cyberpunk.mkv 2024-07-19T17:19:16.304Z | video.remux <info> Remuxing /usr/local/share/twitchautomator/data/cache/2201720366.ts to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407/s2407e1914 - more cyberpunk.mkv 2024-07-19T17:19:16.309Z | helper.exec <success> Spawned process 108 for remux_2201720366.ts 2024-07-19T17:19:16.312Z | job.save <info> Save job remux_2201720366.ts with PID 108 to /usr/local/share/twitchautomator/data/cache/pids/remux_2201720366.ts.json 2024-07-19T17:19:16.315Z | helper.exec <info> Attached to all streams for process 108 for remux_2201720366.ts 2024-07-19T17:20:51.576Z | job.setProgress <info> Job remux_2201720366.ts progress: 11% 2024-07-19T17:21:00.612Z | job.setProgress <info> Job remux_2201720366.ts progress: 21% 2024-07-19T17:21:10.196Z | job.setProgress <info> Job remux_2201720366.ts progress: 31% 2024-07-19T17:21:18.212Z | job.setProgress <info> Job remux_2201720366.ts progress: 41% 2024-07-19T17:21:26.729Z | job.setProgress <info> Job remux_2201720366.ts progress: 51% 2024-07-19T17:21:35.239Z | job.setProgress <info> Job remux_2201720366.ts progress: 61% 2024-07-19T17:21:43.253Z | job.setProgress <info> Job remux_2201720366.ts progress: 71% 2024-07-19T17:21:55.890Z | job.setProgress <info> Job remux_2201720366.ts progress: 81% 2024-07-19T17:22:16.740Z | job.setProgress <info> Job remux_2201720366.ts progress: 92% 2024-07-19T17:22:34.213Z | job.clear <info> Clear job remux_2201720366.ts with PID 108 2024-07-19T17:22:34.214Z | job.clear <success> Job remux_2201720366.ts removed from jobs list 2024-07-19T17:22:34.214Z | helper.exec <info> Process 108 for remux_2201720366.ts exited with code 0 2024-07-19T17:22:35.106Z | video.remux <success> Remuxed /usr/local/share/twitchautomator/data/cache/2201720366.ts to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407/s2407e1914 - more cyberpunk.mkv 2024-07-19T17:22:35.106Z | tw.vod.downloadVideo <info> Successfully remuxed s2407e1914 - more cyberpunk.mkv, removing /usr/local/share/twitchautomator/data/cache/2201720366.ts 2024-07-19T17:22:36.232Z | tw.vod.downloadVideo <info> Download of s2407e1914 - more cyberpunk.mkv successful 2024-07-19T17:22:36.233Z | tw.channel.createVOD <info> Create VOD JSON for sips_: s2407e1914 - more cyberpunk.json @ /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407 2024-07-19T17:22:36.233Z | vod.saveJSON <success> Saving JSON of s2407e1914 - more cyberpunk (create json) 2024-07-19T17:22:36.235Z | vod.setupAssoc <error> No chapters on s2407e1914 - more cyberpunk! 2024-07-19T17:22:36.244Z | vod.saveJSON <success> Saving JSON of s2407e1914 - more cyberpunk (manual creation) 2024-07-19T17:22:46.089Z | vod.addSegment <info> Adding segment s2407e1914 - more cyberpunk.mkv to s2407e1914 - more cyberpunk 2024-07-19T17:22:46.369Z | vod.finalize <info> Finalize s2407e1914 - more cyberpunk @ /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407 2024-07-19T17:22:46.370Z | vod.getMediainfo <info> Fetching mediainfo of s2407e1914 - more cyberpunk, segment #0 2024-07-19T17:22:46.371Z | helper.mediainfo <info> Run mediainfo on /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407/s2407e1914 - more cyberpunk.mkv 2024-07-19T17:22:46.378Z | helper.exec <success> Spawned process 112 for mediainfo_s2407e1914 - more cyberpunk.mkv 2024-07-19T17:22:46.381Z | job.save <info> Save job mediainfo_s2407e1914 - more cyberpunk.mkv with PID 112 to /usr/local/share/twitchautomator/data/cache/pids/mediainfo_s2407e1914 - more cyberpunk.mkv.json 2024-07-19T17:22:46.382Z | helper.exec <info> Attached to all streams for process 112 for mediainfo_s2407e1914 - more cyberpunk.mkv 2024-07-19T17:22:48.938Z | job.clear <info> Clear job mediainfo_s2407e1914 - more cyberpunk.mkv with PID 112 2024-07-19T17:22:48.939Z | job.clear <success> Job mediainfo_s2407e1914 - more cyberpunk.mkv removed from jobs list 2024-07-19T17:22:48.939Z | helper.exec <info> Process 112 for mediainfo_s2407e1914 - more cyberpunk.mkv exited with code 0 2024-07-19T17:22:48.940Z | helper.videometadata <success> /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407/s2407e1914 - more cyberpunk.mkv is a video file 03:17:41 long at 1080p60. 2024-07-19T17:22:48.941Z | vod.saveLosslessCut <info> Saving lossless cut csv for s2407e1914 - more cyberpunk to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407/s2407e1914 - more cyberpunk-llc-edl.csv 2024-07-19T17:22:48.941Z | vod.saveFFMPEGChapters <info> Saving FFMPEG chapters file for s2407e1914 - more cyberpunk to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407/s2407e1914 - more cyberpunk-ffmpeg-chapters.txt 2024-07-19T17:22:48.943Z | vod.saveVTTChapters <info> Saving VTT chapters file for s2407e1914 - more cyberpunk to /usr/local/share/twitchautomator/data/storage/vods/sips_/Cyberpunk 2077Season 2407/s2407e1914 - more cyberpunk.chapters.vtt 2024-07-19T17:22:48.947Z | vod.matchProviderVod <info> Trying to match s2407e1914 - more cyberpunk to provider... 2024-07-19T17:22:49.202Z | vod.matchProviderVod <success> Found matching VOD for s2407e1914 - more cyberpunk (2024-07-19T13:58:16.000Z): 2201720366 (more cyberpunk) 2024-07-19T17:22:49.203Z | vod.saveJSON <success> Saving JSON of s2407e1914 - more cyberpunk (manual finalize) 2024-07-19T17:22:49.211Z | automator.end <info> Downloaded VOD at end: s2407e1914 - back at it 2024-07-19T20:30:22.926Z | hook.HookTwitch <info> Hook called with message ID 8MAy-X8VBz_Kvsr7c7sx4JWzUtUcy6USkOWGJ9Fe-Nc=, s-version 1, s-type stream.online, b false (m-retry 0, m-type notification, m-date 2024-07-19T20:30:21.073694493Z) 2024-07-19T20:30:22.927Z | hook.HookTwitch <fatal> Invalid signature check for message! 2024-07-19T20:30:22.930Z | http.api <error> API error 400 returned: "Invalid signature check"