Traceback (most recent call last):
  File "/app/client.py", line 465, in _start
    task.run(item, itemType, author, ident, full, queuedFor, ctx)
  File "/app/client.py", line 220, in run
    self._run(*args, **kwargs)
  File "/app/client.py", line 212, in _run
    self._run_vod(item)
  File "/app/client.py", line 104, in _run_vod
    open_and_wait([
  File "/app/client.py", line 35, in open_and_wait
    process = subprocess_with_logging.run_with_log(args, shell=False, preexec_fn=os.setpgrp, check=True)
  File "/app/subprocess_with_logging.py", line 105, in run_with_log
    raise subprocess.CalledProcessError(returncode = p.returncode, cmd = args)
subprocess.CalledProcessError: Command '['yt-dlp', '--ignore-config', '--skip-download', '--trim-filenames', '200', '--ignore-no-formats-error', '--write-info-json', '--write-description', '--write-thumbnail', '--write-all-thumbnails', '--no-check-certificate', '--retries', '4', '--limit-rate', '300k', '-o', 'infojson:%(id)s', '--proxy', 'http://localhost:4553', 'https://twitch.tv/videos/1878034674']' returned non-zero exit status 1.

/data/v18780346741697913005.124701.tmp
Logs:
[2023-10-21 18:30:05,133] INFO PRINT <__main__.Logger object at 0x7f9645a8e850> (417/write/client.py)
[2023-10-21 18:30:05,133] INFO PRINT <_io.TextIOWrapper name='<stdout>' mode='w' encoding='utf-8'> (417/write/client.py)
[2023-10-21 18:30:05,133] INFO PRINT Finished RedirectStdout for item v:1878034674 (417/write/client.py)
[2023-10-21 18:30:05,133] INFO PRINT Starting DownloadData for item v1878034674 (417/write/client.py)
[2023-10-21 18:30:05,133] INFO PRINT Starting warcprox for Item 1878034674 (417/write/client.py)
[2023-10-21 18:30:09,144] DEBUG Starting new HTTP connection (1): localhost:4553 (245/_new_conn/connectionpool.py)
[2023-10-21 18:30:09,146] DEBUG http://localhost:4553 "WARCPROX_WRITE_RECORD /burnthetwitch_client_version HTTP/1.1" 204 0 (547/_make_request/connectionpool.py)
[2023-10-21 18:30:09,147] INFO PRINT Downloading metadata (417/write/client.py)
[2023-10-21 18:30:09,147] INFO Running subprocess: ['yt-dlp', '--ignore-config', '--skip-download', '--trim-filenames', '200', '--ignore-no-formats-error', '--write-info-json', '--write-description', '--write-thumbnail', '--write-all-thumbnails', '--no-check-certificate', '--retries', '4', '--limit-rate', '300k', '-o', 'infojson:%(id)s', '--proxy', 'http://localhost:4553', 'https://twitch.tv/videos/1878034674'] (31/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:30:09,912] INFO STDOUT [twitch:vod] Extracting URL: https://twitch.tv/videos/1878034674 (94/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:30:09,914] INFO STDOUT [twitch:vod] 1878034674: Downloading stream metadata GraphQL (94/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:30:10,265] INFO STDERR ERROR: [twitch:vod] 1878034674: Video 1878034674 does not exist (85/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:30:10,296] INFO Process exited with status 1 (103/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:30:10,296] INFO PRINT Terminating warcprox (417/write/client.py)
[2023-10-21 18:30:12,846] INFO PRINT Caught exception! (417/write/client.py)
[2023-10-21 18:30:12,846] INFO PRINT Sending to server and aborting. (417/write/client.py)


Warcprox log:
2023-10-21 18:30:05,346 172 INFO MainThread warcprox.warcproxy.WarcProxy.__init__(mitmproxy.py:617) 100 proxy threads
2023-10-21 18:30:05,427 172 NOTICE MainThread warcprox.warcproxy.WarcProxy.server_activate(warcproxy.py:493) listening on 127.0.0.1:4553
2023-10-21 18:30:05,428 172 INFO MainThread warcprox.dedup.DedupDb.start(dedup.py:98) creating new deduplication database ./warcprox.sqlite
2023-10-21 18:30:05,430 172 INFO DedupLoader(tid=174) warcprox.BasePostfetchProcessor._run(__init__.py:134) <DedupLoader(DedupLoader(tid=174), started 139870992414464)> starting up
2023-10-21 18:30:05,430 172 INFO WarcWriterProcessor(tid=175) warcprox.writerthread.WarcWriterProcessor._run(__init__.py:134) <WarcWriterProcessor(WarcWriterProcessor(tid=175), started 139870911592192)> starting up
2023-10-21 18:30:05,456 172 INFO DedupDb(tid=176) warcprox.BasePostfetchProcessor._run(__init__.py:134) <ListenerPostfetchProcessor(DedupDb(tid=176), started 139870903199488)> starting up
2023-10-21 18:30:05,457 172 INFO StatsProcessor(tid=177) warcprox.stats.StatsProcessor._run(__init__.py:134) <StatsProcessor(StatsProcessor(tid=177), started 139870894806784)> starting up
2023-10-21 18:30:05,457 172 INFO StatsProcessor(tid=177) warcprox.stats.StatsProcessor._startup(stats.py:108) opening existing stats database ./warcprox.sqlite
2023-10-21 18:30:05,457 172 INFO CrawlLogger(tid=178) warcprox.BasePostfetchProcessor._run(__init__.py:134) <ListenerPostfetchProcessor(CrawlLogger(tid=178), started 139870886414080)> starting up
2023-10-21 18:30:05,457 172 INFO RunningStats(tid=179) warcprox.BasePostfetchProcessor._run(__init__.py:134) <ListenerPostfetchProcessor(RunningStats(tid=179), started 139870878021376)> starting up
2023-10-21 18:30:05,469 172 INFO StatsProcessor(tid=177) warcprox.stats.StatsProcessor._startup(stats.py:125) created table buckets_of_stats in ./warcprox.sqlite
2023-10-21 18:30:09,146 172 INFO WarcWriterProcessor(tid=175) warcprox.writer.WarcWriter.open(writer.py:93) warc destination directory ./warcs doesn't exist, creating it
/usr/local/lib/python3.9/site-packages/hanzo/warctools/record.py:174: FutureWarning: GzipFile was opened for writing, but this will change in future Python releases.  Specify the mode argument for opening it for writing.
  out = GzipFile(fileobj=out)
2023-10-21 18:30:09,147 172 INFO WarcWriterProcessor(tid=175) warcprox.writerthread.WarcWriterProcessor._log(writerthread.py:133) 127.0.0.1 204 WARCPROX_WRITE_RECORD /burnthetwitch_client_version text=plain size=95 sha1:d021c28ded5cc0306d8bb0e3209ae690757c319e resource WARCPROX-20231021183009146-00000-gd0bqvwh.warc.gz offset=339
2023-10-21 18:30:09,647 172 INFO StatsProcessor(tid=177) warcprox.stats.StatsProcessor._get_process_put(__init__.py:207) gathered batch of 1 in 2.18 sec
2023-10-21 18:30:10,262 172 INFO WarcWriterProcessor(tid=175) warcprox.writerthread.WarcWriterProcessor._log(writerthread.py:133) 127.0.0.1 200 POST https://gql.twitch.tv/gql application/json size=351 sha1:72e84c38e7a0478a06e4500b64e9e4805cd860a0 response WARCPROX-20231021183009146-00000-gd0bqvwh.warc.gz offset=719
2023-10-21 18:30:10,917 172 NOTICE MainThread warcprox.warcproxy.WarcProxy.server_close(warcproxy.py:501) shutting down
2023-10-21 18:30:11,262 172 INFO DedupLoader(tid=174) warcprox.BasePostfetchProcessor._run(__init__.py:144) <DedupLoader(DedupLoader(tid=174), started 139870992414464)> shutting down
2023-10-21 18:30:11,763 172 INFO WarcWriterProcessor(tid=175) warcprox.writerthread.WarcWriterProcessor._run(__init__.py:144) <WarcWriterProcessor(WarcWriterProcessor(tid=175), started 139870911592192)> shutting down
2023-10-21 18:30:11,775 172 INFO DedupDb(tid=176) warcprox.BasePostfetchProcessor._run(__init__.py:144) <ListenerPostfetchProcessor(DedupDb(tid=176), started 139870903199488)> shutting down
2023-10-21 18:30:11,775 172 INFO StatsProcessor(tid=177) warcprox.stats.StatsProcessor._get_process_put(__init__.py:207) gathered batch of 1 in 2.10 sec
2023-10-21 18:30:12,314 172 INFO StatsProcessor(tid=177) warcprox.stats.StatsProcessor._run(__init__.py:144) <StatsProcessor(StatsProcessor(tid=177), started 139870894806784)> shutting down
2023-10-21 18:30:12,315 172 INFO CrawlLogger(tid=178) warcprox.BasePostfetchProcessor._run(__init__.py:144) <ListenerPostfetchProcessor(CrawlLogger(tid=178), started 139870886414080)> shutting down
2023-10-21 18:30:12,815 172 INFO RunningStats(tid=179) warcprox.BasePostfetchProcessor._run(__init__.py:144) <ListenerPostfetchProcessor(RunningStats(tid=179), started 139870878021376)> shutting down