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/1897889339']' returned non-zero exit status 1.

/data/v18978893391697911811.6878533.tmp
Logs:
[2023-10-21 18:10:11,695] INFO PRINT <__main__.Logger object at 0x7f6e0ef70550> (417/write/client.py)
[2023-10-21 18:10:11,695] INFO PRINT <_io.TextIOWrapper name='<stdout>' mode='w' encoding='utf-8'> (417/write/client.py)
[2023-10-21 18:10:11,695] INFO PRINT Finished RedirectStdout for item v:1897889339 (417/write/client.py)
[2023-10-21 18:10:11,695] INFO PRINT Starting DownloadData for item v1897889339 (417/write/client.py)
[2023-10-21 18:10:11,695] INFO PRINT Starting warcprox for Item 1897889339 (417/write/client.py)
[2023-10-21 18:10:15,704] DEBUG Starting new HTTP connection (1): localhost:4553 (245/_new_conn/connectionpool.py)
[2023-10-21 18:10:15,705] DEBUG http://localhost:4553 "WARCPROX_WRITE_RECORD /burnthetwitch_client_version HTTP/1.1" 204 0 (547/_make_request/connectionpool.py)
[2023-10-21 18:10:15,706] INFO PRINT Downloading metadata (417/write/client.py)
[2023-10-21 18:10:15,706] 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/1897889339'] (31/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:10:16,458] INFO STDOUT [twitch:vod] Extracting URL: https://twitch.tv/videos/1897889339 (94/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:10:16,459] INFO STDOUT [twitch:vod] 1897889339: Downloading stream metadata GraphQL (94/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:10:16,808] INFO STDERR ERROR: [twitch:vod] 1897889339: Video 1897889339 does not exist (85/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:10:16,837] INFO Process exited with status 1 (103/run_with_log/subprocess_with_logging.py)
[2023-10-21 18:10:16,838] INFO PRINT Terminating warcprox (417/write/client.py)
[2023-10-21 18:10:18,380] INFO PRINT Caught exception! (417/write/client.py)
[2023-10-21 18:10:18,381] INFO PRINT Sending to server and aborting. (417/write/client.py)


Warcprox log:
2023-10-21 18:10:11,910 234 INFO MainThread warcprox.warcproxy.WarcProxy.__init__(mitmproxy.py:617) 100 proxy threads
2023-10-21 18:10:12,065 234 NOTICE MainThread warcprox.warcproxy.WarcProxy.server_activate(warcproxy.py:493) listening on 127.0.0.1:4553
2023-10-21 18:10:12,066 234 INFO MainThread warcprox.dedup.DedupDb.start(dedup.py:98) creating new deduplication database ./warcprox.sqlite
2023-10-21 18:10:12,068 234 INFO DedupLoader(tid=236) warcprox.BasePostfetchProcessor._run(__init__.py:134) <DedupLoader(DedupLoader(tid=236), started 140454809544448)> starting up
2023-10-21 18:10:12,069 234 INFO WarcWriterProcessor(tid=237) warcprox.writerthread.WarcWriterProcessor._run(__init__.py:134) <WarcWriterProcessor(WarcWriterProcessor(tid=237), started 140454801151744)> starting up
2023-10-21 18:10:12,080 234 INFO DedupDb(tid=238) warcprox.BasePostfetchProcessor._run(__init__.py:134) <ListenerPostfetchProcessor(DedupDb(tid=238), started 140454792591104)> starting up
2023-10-21 18:10:12,080 234 INFO StatsProcessor(tid=239) warcprox.stats.StatsProcessor._run(__init__.py:134) <StatsProcessor(StatsProcessor(tid=239), started 140454783936256)> starting up
2023-10-21 18:10:12,080 234 INFO StatsProcessor(tid=239) warcprox.stats.StatsProcessor._startup(stats.py:108) opening existing stats database ./warcprox.sqlite
2023-10-21 18:10:12,081 234 INFO CrawlLogger(tid=240) warcprox.BasePostfetchProcessor._run(__init__.py:134) <ListenerPostfetchProcessor(CrawlLogger(tid=240), started 140454775543552)> starting up
2023-10-21 18:10:12,081 234 INFO RunningStats(tid=241) warcprox.BasePostfetchProcessor._run(__init__.py:134) <ListenerPostfetchProcessor(RunningStats(tid=241), started 140454767150848)> starting up
2023-10-21 18:10:12,101 234 INFO StatsProcessor(tid=239) warcprox.stats.StatsProcessor._startup(stats.py:125) created table buckets_of_stats in ./warcprox.sqlite
2023-10-21 18:10:15,706 234 INFO WarcWriterProcessor(tid=237) 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:10:15,707 234 INFO WarcWriterProcessor(tid=237) 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-20231021181015706-00000-mgvdbejn.warc.gz offset=338
2023-10-21 18:10:16,207 234 INFO StatsProcessor(tid=239) warcprox.stats.StatsProcessor._get_process_put(__init__.py:207) gathered batch of 1 in 2.11 sec
2023-10-21 18:10:16,805 234 INFO WarcWriterProcessor(tid=237) warcprox.writerthread.WarcWriterProcessor._log(writerthread.py:133) 127.0.0.1 200 POST https://gql.twitch.tv/gql application/json size=350 sha1:600b7cd3ea5bb0bdf24b40c5958ecc8c2036cc9e response WARCPROX-20231021181015706-00000-mgvdbejn.warc.gz offset=717
2023-10-21 18:10:17,463 234 NOTICE MainThread warcprox.warcproxy.WarcProxy.server_close(warcproxy.py:501) shutting down
2023-10-21 18:10:17,805 234 INFO DedupLoader(tid=236) warcprox.BasePostfetchProcessor._run(__init__.py:144) <DedupLoader(DedupLoader(tid=236), started 140454809544448)> shutting down
2023-10-21 18:10:17,806 234 INFO WarcWriterProcessor(tid=237) warcprox.writerthread.WarcWriterProcessor._run(__init__.py:144) <WarcWriterProcessor(WarcWriterProcessor(tid=237), started 140454801151744)> shutting down
2023-10-21 18:10:17,820 234 INFO DedupDb(tid=238) warcprox.BasePostfetchProcessor._run(__init__.py:144) <ListenerPostfetchProcessor(DedupDb(tid=238), started 140454792591104)> shutting down
2023-10-21 18:10:17,820 234 INFO StatsProcessor(tid=239) warcprox.stats.StatsProcessor._get_process_put(__init__.py:207) gathered batch of 1 in 1.58 sec
2023-10-21 18:10:18,347 234 INFO StatsProcessor(tid=239) warcprox.stats.StatsProcessor._run(__init__.py:144) <StatsProcessor(StatsProcessor(tid=239), started 140454783936256)> shutting down
2023-10-21 18:10:18,347 234 INFO CrawlLogger(tid=240) warcprox.BasePostfetchProcessor._run(__init__.py:144) <ListenerPostfetchProcessor(CrawlLogger(tid=240), started 140454775543552)> shutting down
2023-10-21 18:10:18,347 234 INFO RunningStats(tid=241) warcprox.BasePostfetchProcessor._run(__init__.py:144) <ListenerPostfetchProcessor(RunningStats(tid=241), started 140454767150848)> shutting down