pabloromeo / clusterplex

ClusterPlex is an extended version of Plex, which supports distributed Workers across a cluster to handle transcoding requests.
MIT License
478 stars 36 forks source link

Hardware transcoding between Synology NAS and Linux Laptop with NVIDIA GPU doesn't seem to work. #193

Closed paytah232 closed 1 year ago

paytah232 commented 1 year ago

Hi @pabloromeo, I've been trying my hardest to get this system working smoothly and I think I'm out of luck,so I'm hoping to get your assistance. I'm not sure exactly what you will require from me, but I will get whatever logs / files you need.

My setup:

services: plex: container_name: plex image: ghcr.io/linuxserver/plex:latest environment: DOCKER_MODS: "ghcr.io/pabloromeo/clusterplex_dockermod:latest" VERSION: docker PUID: 1027 PGID: 65538 TZ: Australia/Brisbane ORCHESTRATOR_URL: http://plex-orchestrator:3500 PMS_SERVICE: 192.168.0.2 # This service. If you disable Local Relay then you must use PMS_IP instead PMS_PORT: "32400" TRANSCODE_OPERATING_MODE: both #(local|remote|both) TRANSCODER_VERBOSE: "1" # 1=verbose, 0=silent LOCAL_RELAY_ENABLED: "1" LOCAL_RELAY_PORT: "32499" ADVERTISE_IP: http://192.168.0.2:32400/ healthcheck: test: curl -fsS http://localhost:32400/identity > /dev/null || exit 1 interval: 15s timeout: 15s retries: 5 start_period: 30s volumes:

The following is my worker config, which is running on an old laptop with an NVIDIA GPU,with the drivers installed and reporting CUDA working etc. `version: '3.8'

services: plex-worker: image: ghcr.io/linuxserver/plex:latest hostname: "plex-worker" container_name: plex_worker deploy: mode: replicated replicas: 2 devices:

Using these, I am able to get the workers talking to the orchestrator, however, it appears that the workers do increase the GPU usage by about 200MB, but the CPU is still massively high (strangely reporting 195% usage). If I access the transcode directory,files are appearing, and it seems the size of them should be sufficient for the playback to start, but it is just continuously loading, until it eventually fails.

I also noted like in a previous issue, that the plex log does seem to report the request coming from a non-local IP (my external IP), even though everything is local. Not sure if that is the issue, but if you are able to help me troubleshoot why the setup isn't working for me, I'm hoping it might help others in similar situations fix it too.

Thanks!

pabloromeo commented 1 year ago

Hi! So, given that you are seeing content in the /transcode directory getting generated, I assume it's mostly configured correctly. Can't say for sure regarding the NVIDIA settings, as I don't have an environment to replicate it. But I've seen other users using GPUs in their configurations. Now, one thing I noticed is that you have different PUID and PGID between Plex and the worker. Could it be that you are having permissions issues when Plex is trying to read those files? I assume they are getting created and owned by UID 1000, so Plex might not be able to read them. You could try changing permissions on /volume1/docker/plex/transcode to 777 just to see if Plex is able to pick up the content. The reason for the playback not starting could be multiple, to name a few that I remember:

Also you might find relevant info in Plex's own logs, which are available in their UI, under Settings -> Manage -> Console.

Is this on docker swarm or just standalone docker on each of those two?

paytah232 commented 1 year ago

@pabloromeo, thanks for your fast reply.

I've had anouther try this morning. I double checked permissions and everything is already set to 777, both in the CIFS connection or otherwise. After getting the workers up, I checked the owner of the chunks - it was not my expected plex:docker user:group (this is the user running on the synology NAS), but instead Docker:users. I expect this is because my laptop CIFS connects using the restricted Docker user on my Synology NAS. The users group permissions did not seem to be allowing read/write access to the docker share, so I added that. image The transcode did seem to fire up better after that but it still seems really rather slow. Sometimes it plays for a few seconds and stop, other times, I only get the first frame preview or a total failure. Eventually, the stream shuts itself down.

I could be reading it wrong, but the worker is reporting pts_time:xxx, which I am assuming is the realtime value of where it is up to in the video file. The increases in this time seems like it should be much faster than required, so the stream should be smooth, but it is not. It reports all the chunks being written, so I don't think there's a problem there, although, I'm still not confident it's using the nvidia GPU properly, but the transcode seems fast.

I've jumped into the plex logs (the server still reports as remote - yet original streaming quality (direct play works)), and I think there must be a problem, because it seems to be reporting that is can see the chunks, but isn't able to use them? Log below:

Jan 03, 2023 10:49:55.416 [0x7f76f0859b38] Debug — Request: [127.0.0.1:41930 (Loopback)] POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=3&message=%5Bsegment%20%40%200x7fc9bc0ac380%5D%20segment%3A%27chunk-00086%27%20count%3A86%20ended (15 live) #2e2e3 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => Upgrade / Host => 192.168.0.2 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 10:49:55.421 [0x7f76f0859b38] Debug — [Req#2e2e3/Transcode/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3] [segment @ 0x7fc9bc0ac380] segment:'chunk-00086' count:86 ended Jan 03, 2023 10:49:55.422 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:41930] 200 POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=3&message=%5Bsegment%20%40%200x7fc9bc0ac380%5D%20segment%3A%27chunk-00086%27%20count%3A86%20ended (15 live) 5ms 195 bytes (pipelined: 1) (range: bytes=0-) Jan 03, 2023 10:49:55.422 [0x7f76f486cb38] Verbose — Didn't receive a request from 127.0.0.1:41930: End of file Jan 03, 2023 10:49:55.423 [0x7f76f0656b38] Debug — Request: [127.0.0.1:41932 (Loopback)] POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=3&message=%5BAVIOContext%20%40%200x7fc9bc0bc9c0%5D%20Statistics%3A%2080870%20bytes%20written%2C%200%20seeks%2C%201%20writeouts (15 live) #2e2f7 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => Upgrade / Host => 192.168.0.2 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 10:49:55.424 [0x7f76f0656b38] Debug — [Req#2e2f7/Transcode/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3] [AVIOContext @ 0x7fc9bc0bc9c0] Statistics: 80870 bytes written, 0 seeks, 1 writeouts Jan 03, 2023 10:49:55.424 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:41932] 200 POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=3&message=%5BAVIOContext%20%40%200x7fc9bc0bc9c0%5D%20Statistics%3A%2080870%20bytes%20written%2C%200%20seeks%2C%201%20writeouts (15 live) 0ms 195 bytes (pipelined: 1) (range: bytes=0-) Jan 03, 2023 10:49:55.424 [0x7f76f486cb38] Verbose — Didn't receive a request from 127.0.0.1:41932: End of file Jan 03, 2023 10:49:55.435 [0x7f76f1828b38] Debug — Request: [127.0.0.1:41934 (Loopback)] POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=2&message=%5Bsegment%20%40%200x7fc9bc0ac380%5D%20Opening%20%27chunk-00087%27%20for%20writing (15 live) #2e303 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => Upgrade / Host => 192.168.0.2 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 10:49:55.436 [0x7f76f1828b38] Info — [Req#2e303/Transcode/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3] [segment @ 0x7fc9bc0ac380] Opening 'chunk-00087' for writing Jan 03, 2023 10:49:55.437 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:41934] 200 POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=2&message=%5Bsegment%20%40%200x7fc9bc0ac380%5D%20Opening%20%27chunk-00087%27%20for%20writing (15 live) 1ms 195 bytes (pipelined: 1) (range: bytes=0-) Jan 03, 2023 10:49:55.437 [0x7f76f4a6fb38] Verbose — Didn't receive a request from 127.0.0.1:41934: End of file Jan 03, 2023 10:49:55.440 [0x7f76f4a6fb38] Verbose — [TranscodeOutputStream] Waiting 100ms for more data... Jan 03, 2023 10:49:55.446 [0x7f76f0859b38] Debug — Request: [127.0.0.1:41936 (Loopback)] POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=2&message=%5Bsegment%20%40%200x7fc9bc0ac380%5D%20Opening%20%27http%3A%2F%2F192.168.0.2%3A32499%2Fvideo%2F%3A%2Ftranscode%2Fsession%2Fl44pz9pmbu7amcdxvw2x6tlv%2F63152e78-e599-4b9b-829a-b0c939b54fd3%2Fmanifest%3FX-Plex-Http-Pipeline%3Dinfinite%27%20for%20writing (15 live) #2e30e Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => Upgrade / Host => 192.168.0.2 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 10:49:55.446 [0x7f76f0859b38] Info — [Req#2e30e/Transcode/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3] [segment @ 0x7fc9bc0ac380] Opening 'http://192.168.0.2:32499/video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/manifest?X-Plex-Http-Pipeline=infinite' for writing Jan 03, 2023 10:49:55.446 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:41936] 200 POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=2&message=%5Bsegment%20%40%200x7fc9bc0ac380%5D%20Opening%20%27http%3A%2F%2F192.168.0.2%3A32499%2Fvideo%2F%3A%2Ftranscode%2Fsession%2Fl44pz9pmbu7amcdxvw2x6tlv%2F63152e78-e599-4b9b-829a-b0c939b54fd3%2Fmanifest%3FX-Plex-Http-Pipeline%3Dinfinite%27%20for%20writing (15 live) 0ms 195 bytes (pipelined: 1) (range: bytes=0-) Jan 03, 2023 10:49:55.446 [0x7f76f4a6fb38] Verbose — Didn't receive a request from 127.0.0.1:41936: End of file Jan 03, 2023 10:49:55.448 [0x7f76f0656b38] Debug — Request: [127.0.0.1:41938 (Loopback)] POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=3&message=%5Btcp%20%40%200x7fc9bc0ba340%5D%20Starting%20connection%20attempt%20to%20192.168.0.2%20port%2032499 (15 live) #2e31b Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => Upgrade / Host => 192.168.0.2 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 10:49:55.448 [0x7f76f0656b38] Debug — [Req#2e31b/Transcode/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3] [tcp @ 0x7fc9bc0ba340] Starting connection attempt to 192.168.0.2 port 32499 Jan 03, 2023 10:49:55.449 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:41938] 200 POST /video/:/transcode/session/l44pz9pmbu7amcdxvw2x6tlv/63152e78-e599-4b9b-829a-b0c939b54fd3/progress/log?level=3&message=%5Btcp%20%40%200x7fc9bc0ba340%5D%20Starting%20connection%20attempt%20to%20192.168.0.2%20port%2032499 (15 live) 0ms 195 bytes (pipelined: 1) (range: bytes=0-) Jan 03, 2023 10:49:55.449 [0x7f76f486cb38] Verbose — Didn't receive a request from 127.0.0.1:41938: End of file


I did also notice something in the log that said "We want 400 seconds, last returned was 350.07000" (or similar), so I tried reducing my transcoding buffer down to 100 seconds, and still have the same issue:

Jan 03, 2023 10:55:55.396 [0x7f76eff8bb38] Debug — Request: [127.0.0.1:51538 (Loopback)] PUT /video/:/transcode/session/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18/progress?progress=2.7&size=-22&remaining=1081&vdec_packets=4363&vdec_hw_ok=4353&speed=6.0&vdec_hw_status=1 (13 live) #35073 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => Upgrade / Host => 192.168.0.2 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 10:55:55.396 [0x7f76eff8bb38] Verbose — [Req#35073/Transcode/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18] We want 100 seconds ahead, last returned was 170.879000 and max is 170.879000. Jan 03, 2023 10:55:55.396 [0x7f76eff8bb38] Verbose — [Req#35073/Transcode/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 10:55:55.396 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:51538] 206 PUT /video/:/transcode/session/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18/progress?progress=2.7&size=-22&remaining=1081&vdec_packets=4363&vdec_hw_ok=4353&speed=6.0&vdec_hw_status=1 (13 live) 0ms 355 bytes (pipelined: 1) (range: bytes=0-) Jan 03, 2023 10:55:55.397 [0x7f76f4a6fb38] Verbose — Didn't receive a request from 127.0.0.1:51538: End of file Jan 03, 2023 10:55:55.465 [0x7f76f1e81b38] Debug — Request: [127.0.0.1:51540 (Loopback)] POST /video/:/transcode/session/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18/progress/log?level=2&message=%5Bsegment%20%40%200x7f7f8928c680%5D%20Opening%20%27http%3A%2F%2F192.168.0.2%3A32499%2Fvideo%2F%3A%2Ftranscode%2Fsession%2F68r1zzv1b0nv4ue00gmkseee%2Fdeb868f1-e379-40a5-b403-483cae722c18%2Fmanifest%3FX-Plex-Http-Pipeline%3Dinfinite%27%20for%20writing (13 live) #3508d Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => Upgrade / Host => 192.168.0.2 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 10:55:55.466 [0x7f76f1e81b38] Info — [Req#3508d/Transcode/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18] [segment @ 0x7f7f8928c680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18/manifest?X-Plex-Http-Pipeline=infinite' for writing Jan 03, 2023 10:55:55.466 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:51540] 200 POST /video/:/transcode/session/68r1zzv1b0nv4ue00gmkseee/deb868f1-e379-40a5-b403-483cae722c18/progress/log?level=2&message=%5Bsegment%20%40%200x7f7f8928c680%5D%20Opening%20%27http%3A%2F%2F192.168.0.2%3A32499%2Fvideo%2F%3A%2Ftranscode%2Fsession%2F68r1zzv1b0nv4ue00gmkseee%2Fdeb868f1-e379-40a5-b403-483cae722c18%2Fmanifest%3FX-Plex-Http-Pipeline%3Dinfinite%27%20for%20writing (13 live) 0ms 195 bytes (pipelined: 1) (range: bytes=0-) Jan 03, 2023 10:55:55.466 [0x7f76f4a6fb38] Verbose — Didn't receive a request from 127.0.0.1:51540: End of file


Hopefully this isn't too text heavy, but I think this is probably helping to get closer to the root cause of the issue.

Also, this is just standalone docker. Synology NAS runs a docker container, where the plex and orchestrator are running, and on my linux laptop, it is just running standard docker engine.

pabloromeo commented 1 year ago

That's odd! Now one thing that I just realized, is you are trying to run 2 replicas against the same GPU. I'm pretty sure that won't work. Run it as a single instance, just to discard any issues related to that. Do the logs on the worker seem healthy? Any errors?

pabloromeo commented 1 year ago

Also, I believe @sjdaws was running it on nvidia GPUs too. See https://github.com/pabloromeo/clusterplex/pull/81. But that example is for an older version of clusterplex, which didn't yet have the local relay or EAE support, so config is slightly different now.

paytah232 commented 1 year ago

@pabloromeo - I did read #81 - which is how I stumbled into the use of the NVIDIA and FFMPEG environment variables. I was hoping it would be my solution too. Seemed to help, but not a full solution.

I took the replication out of the docker compose, and am now only running 1 worker on the nvidia laptop. Still the same. I'll add some snippets of the worker's output that I think might be useful. In the snippet,it looks like it successfully connects to the 32499 port, and writes and then continues to open more chunks for writing. There seems to be one line that suggests and EAE error?

Error Line: plex-worker_1 | [eac3_eae @ 0x7fd156f8c680] EAE timeout! EAE not running, or wrong folder? Could not read '/tmp/pms-dd3ee99f-9e09-45d0-8dbe-ab113c3523f4/EasyAudioEncoder/Convert to WAV (to 8ch or less)/k84f8nxojvcp634b53xsjio4_737-0-527.wav'

More of the Log: plex-worker_1 | [AVIOContext @ 0x7f52ee5a69c0] Statistics: 0 bytes read, 0 seeks plex-worker_1 | [tcp @ 0x7f52efac3e80] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52efac3e80] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libvmdvideo_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/librv30_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libdvaudio_decoder.so plex-worker_1 | [eac3_eae @ 0x7fd156f8c680] EAE timeout! EAE not running, or wrong folder? Could not read '/tmp/pms-dd3ee99f-9e09-45d0-8dbe-ab113c3523f4/EasyAudioEncoder/Convert to WAV (to 8ch or less)/k84f8nxojvcp634b53xsjio4_737-0-527.wav' plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libvp6_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/liblibx264_encoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libdsd_msbf_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libadpcm_psx_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libgsm_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libhqx_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libvqa_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] frame I:89 Avg QP:14.12 size: 20272 plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libmmvideo_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] frame P:2058 Avg QP:18.92 size: 4129 plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libtruemotion2rt_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] frame B:5515 Avg QP:20.19 size: 686 plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libflic_decoder.so plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libxwd_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] consecutive B-frames: 2.8% 2.9% 2.9% 91.5% plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libpictor_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] mb I I16..4: 50.2% 0.0% 49.8% plex-worker_1 | [libx264 @ 0x7fd153f1b940] mb P I16..4: 9.4% 0.0% 0.0% P16..4: 34.7% 0.0% 0.0% 0.0% 0.0% skip:55.9% plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libamv_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] mb B I16..4: 0.9% 0.0% 0.0% B16..8: 10.1% 0.0% 0.0% direct: 3.2% skip:85.9% L0:33.4% L1:46.2% BI:20.4% plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libvmnc_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] coded y,uvDC,uvAC intra: 40.9% 45.6% 20.0% inter: 5.4% 4.5% 0.4% plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libwmav2_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] i16 v,h,dc,p: 37% 29% 19% 15% plex-worker_1 | Loading external lib /codecs/583b3e4-4434-linux-x86_64-standard/libs302m_decoder.so plex-worker_1 | [libx264 @ 0x7fd153f1b940] i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 24% 22% 12% 7% 7% 7% 7% 7% 7% plex-worker_1 | [libx264 @ 0x7fd153f1b940] i8c dc,h,v,p: 54% 23% 17% 6% plex-worker_1 | [libx264 @ 0x7fd153f1b940] Weighted P-Frames: Y:0.5% UV:0.1% plex-worker_1 | [libx264 @ 0x7fd153f1b940] kb/s:352.66 plex-worker_1 | [AVIOContext @ 0x7fd158f68b40] Statistics: 381473679 bytes read, 5 seeks plex-worker_1 | Exiting normally, received signal 15. plex-worker_1 | [h264 @ 0x7f52ee787b00] Reinit context to 1920x1088, pix_fmt: yuv420p plex-worker_1 | Completed transcode plex-worker_1 | Removing process from taskMap plex-worker_1 | Input #0, matroska,webm, from '/media/Movies/Violent Night (2022)/Violent Night (2022).mkv': plex-worker_1 | Metadata: plex-worker_1 | title : Violent.Night.2022.WEB-DL.1080p_от New-Team_JNS82 plex-worker_1 | encoder : libebml v1.4.2 + libmatroska v1.6.4 plex-worker_1 | creation_time : 2022-12-23T08:28:51.000000Z plex-worker_1 | Duration: 01:51:38.46, start: 0.000000, bitrate: 9467 kb/s plex-worker_1 | Chapters: plex-worker_1 | Chapter #0:0: start 0.000000, end 292.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 1 plex-worker_1 | Chapter #0:1: start 292.000000, end 475.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 2 plex-worker_1 | Chapter #0:2: start 475.000000, end 794.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 3 plex-worker_1 | Chapter #0:3: start 794.000000, end 1076.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 4 plex-worker_1 | Chapter #0:4: start 1076.000000, end 1284.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 5 plex-worker_1 | Chapter #0:5: start 1284.000000, end 1796.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 6 plex-worker_1 | Chapter #0:6: start 1796.000000, end 2009.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 7 plex-worker_1 | Chapter #0:7: start 2009.000000, end 2322.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 8 plex-worker_1 | Chapter #0:8: start 2322.000000, end 2654.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 9 plex-worker_1 | Chapter #0:9: start 2654.000000, end 3178.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 10 plex-worker_1 | Chapter #0:10: start 3178.000000, end 3532.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 11 plex-worker_1 | Chapter #0:11: start 3532.000000, end 4169.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 12 plex-worker_1 | Chapter #0:12: start 4169.000000, end 4518.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 13 plex-worker_1 | Chapter #0:13: start 4518.000000, end 4801.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 14 plex-worker_1 | Chapter #0:14: start 4801.000000, end 5079.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 15 plex-worker_1 | Chapter #0:15: start 5079.000000, end 5292.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 16 plex-worker_1 | Chapter #0:16: start 5292.000000, end 5572.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 17 plex-worker_1 | Chapter #0:17: start 5572.000000, end 5869.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 18 plex-worker_1 | Chapter #0:18: start 5869.000000, end 6332.000000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 19 plex-worker_1 | Chapter #0:19: start 6332.000000, end 6698.464000 plex-worker_1 | Metadata: plex-worker_1 | title : Chapter 20 plex-worker_1 | [AVIOContext @ 0x7f52ee5a69c0] Statistics: 0 bytes read, 0 seeks plex-worker_1 | [tcp @ 0x7f52e7dd7640] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52e7dd7640] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | Stream #0:0: Video: h264 (High), 1 reference frame, yuv420p(tv, bt709, progressive, left), 1920x1080 (1920x1088) [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn plex-worker_1 | Metadata: plex-worker_1 | BPS : 7936352 plex-worker_1 | DURATION : 01:51:31.769000000 plex-worker_1 | NUMBER_OF_FRAMES: 160440 plex-worker_1 | NUMBER_OF_BYTES : 6638529418 plex-worker_1 | _STATISTICS_WRITING_APP: mkvmerge v59.0.0 ('Shining Star') 64-bit plex-worker_1 | _STATISTICS_WRITING_DATE_UTC: 2022-12-23 08:28:51 plex-worker_1 | _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES plex-worker_1 | Stream #0:1(rus): Audio: ac3, 48000 Hz, 5.1(side), s16p, 384 kb/s (default) plex-worker_1 | Metadata: plex-worker_1 | title : MVO - Jaskier plex-worker_1 | BPS : 384000 plex-worker_1 | DURATION : 01:51:38.464000000 plex-worker_1 | NUMBER_OF_FRAMES: 209327 plex-worker_1 | NUMBER_OF_BYTES : 321526272 plex-worker_1 | _STATISTICS_WRITING_APP: mkvmerge v59.0.0 ('Shining Star') 64-bit plex-worker_1 | _STATISTICS_WRITING_DATE_UTC: 2022-12-23 08:28:51 plex-worker_1 | _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES plex-worker_1 | Stream #0:2(rus): Audio: ac3, 48000 Hz, 5.1(side), s16p, 384 kb/s plex-worker_1 | Metadata: plex-worker_1 | title : AV0 - Яроцкий plex-worker_1 | BPS : 384000 plex-worker_1 | DURATION : 01:51:38.368000000 plex-worker_1 | NUMBER_OF_FRAMES: 209324 plex-worker_1 | NUMBER_OF_BYTES : 321521664 plex-worker_1 | _STATISTICS_WRITING_APP: mkvmerge v59.0.0 ('Shining Star') 64-bit plex-worker_1 | _STATISTICS_WRITING_DATE_UTC: 2022-12-23 08:28:51 plex-worker_1 | _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES plex-worker_1 | Stream #0:3(eng): Audio: eac3, 48000 Hz, 5.1(side), s16p plex-worker_1 | Metadata: plex-worker_1 | BPS : 768000 plex-worker_1 | DURATION : 01:51:38.304000000 plex-worker_1 | NUMBER_OF_FRAMES: 209322 plex-worker_1 | NUMBER_OF_BYTES : 643037184 plex-worker_1 | _STATISTICS_WRITING_APP: mkvmerge v59.0.0 ('Shining Star') 64-bit plex-worker_1 | _STATISTICS_WRITING_DATE_UTC: 2022-12-23 08:28:51 plex-worker_1 | _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES plex-worker_1 | Stream #0:4(eng): Subtitle: subrip plex-worker_1 | Metadata: plex-worker_1 | BPS : 51 plex-worker_1 | DURATION : 01:46:45.858000000 plex-worker_1 | NUMBER_OF_FRAMES: 1510 plex-worker_1 | NUMBER_OF_BYTES : 41306 plex-worker_1 | _STATISTICS_WRITING_APP: mkvmerge v59.0.0 ('Shining Star') 64-bit plex-worker_1 | _STATISTICS_WRITING_DATE_UTC: 2022-12-23 08:28:51 plex-worker_1 | _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES plex-worker_1 | Stream #0:5(eng): Subtitle: subrip (hearing impaired) plex-worker_1 | Metadata: plex-worker_1 | title : SDH plex-worker_1 | BPS : 68 plex-worker_1 | DURATION : 01:51:34.229000000 plex-worker_1 | NUMBER_OF_FRAMES: 2132 plex-worker_1 | NUMBER_OF_BYTES : 57645 plex-worker_1 | _STATISTICS_WRITING_APP: mkvmerge v59.0.0 ('Shining Star') 64-bit plex-worker_1 | _STATISTICS_WRITING_DATE_UTC: 2022-12-23 08:28:51 plex-worker_1 | _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES plex-worker_1 | [Parsed_scale_0 @ 0x7f52efad4640] w:720 h:404 flags:'' interl:0 plex-worker_1 | Stream mapping: plex-worker_1 | Stream #0:0 (h264) -> scale:default (graph 0) plex-worker_1 | Stream #0:3 (eac3_eae) -> aresample:default (graph 1) plex-worker_1 | format:default (graph 0) -> Stream #0:0 (libx264) plex-worker_1 | aresample:default (graph 1) -> Stream #0:1 (libopus) plex-worker_1 | Press [q] to stop, [?] for help plex-worker_1 | [h264 @ 0x7f52e901fdc0] NVDEC capabilities: plex-worker_1 | [h264 @ 0x7f52e901fdc0] format supported: yes, max_mb_count: 65536 plex-worker_1 | [h264 @ 0x7f52e901fdc0] min_width: 48, max_width: 4096 plex-worker_1 | [h264 @ 0x7f52e901fdc0] min_height: 16, max_height: 4096 plex-worker_1 | [h264 @ 0x7f52e901fdc0] Reinit context to 1920x1088, pix_fmt: cuda plex-worker_1 | [Parsed_scale_0 @ 0x7f52e7de5b80] w:720 h:404 flags:'' interl:0 plex-worker_1 | [graph 0 input from stream 0:0 @ 0x7f52e7de5e40] w:1920 h:1080 pixfmt:nv12 tb:1/1000 fr:24000/1001 sar:1/1 plex-worker_1 | [Parsed_scale_0 @ 0x7f52e7de5b80] w:1920 h:1080 fmt:nv12 sar:1/1 -> w:720 h:404 fmt:nv12 sar:404/405 flags:0x0 plex-worker_1 | Last message repeated 3 times plex-worker_1 | [libx264 @ 0x7f52e95c56c0] using SAR=404/405 plex-worker_1 | [libx264 @ 0x7f52e95c56c0] using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2 plex-worker_1 | [libx264 @ 0x7f52e95c56c0] profile Main, level 3.0, 4:2:0, 8-bit plex-worker_1 | [libx264 @ 0x7f52e95c56c0] 264 - core 161 - H.264/MPEG-4 AVC codec - Copyleft 2003-2020 - http://www.videolan.org/x264.html - options: cabac=1 ref=1 deblock=1:0:0 analyse=0x1:0 me=dia subme=2 psy=1 psy_rd=1.00:0.00 mixed_ref=0 me_range=4 chroma_me=0 trellis=0 8x8dct=0 cqm=0 deadzone=21,11 fast_pskip=1 chroma_qp_offset=0 threads=12 lookahead_threads=3 sliced_threads=0 nr=0 decimate=1 interlaced=0 bluray_compat=0 constrained_intra=0 bframes=3 b_pyramid=2 b_adapt=1 b_bias=0 direct=1 weightb=1 open_gop=0 weightp=1 keyint=250 keyint_min=23 scenecut=40 intra_refresh=0 rc_lookahead=10 rc=crf mbtree=1 crf=21.0 qcomp=0.60 qpmin=0 qpmax=69 qpstep=4 vbv_maxrate=1724 vbv_bufsize=3448 crf_max=0.0 nal_hrd=none filler=0 ip_ratio=1.40 aq=1:1.00 plex-worker_1 | *** 2 dup! plex-worker_1 | [graph_1_in_0_3 @ 0x7f52e94465c0] tb:1/48000 samplefmt:s32 samplerate:48000 chlayout:5.1(side) plex-worker_1 | [Parsed_aresample_0 @ 0x7f52e94464c0] ch:6 chl:5.1(side) fmt:s32 r:48000Hz -> ch:2 chl:stereo fmt:flt r:48000Hz plex-worker_1 | [segment @ 0x7f52ee775680] Selected stream id:0 type:video plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'header' for writing plex-worker_1 | Output #0, segment, to 'chunk-%05d': plex-worker_1 | Metadata: plex-worker_1 | duration : 6698.46 plex-worker_1 | encoder : Lavf59.20.101 plex-worker_1 | Stream #0:0: Video: h264, 1 reference frame, nv12(tv, bt709, progressive, left), 720x404 (0x0) [SAR 404:405 DAR 16:9], q=2-31, 23.98 fps, 1k tbn plex-worker_1 | Metadata: plex-worker_1 | encoder : Lavc59.25.100 libx264 plex-worker_1 | Side data: plex-worker_1 | cpb: bitrate max/min/avg: 1724000/0/0 buffer size: 3448000 vbv_delay: N/A plex-worker_1 | Stream #0:1(eng): Audio: opus, 48000 Hz, stereo, flt, delay 312, 162 kb/s plex-worker_1 | Metadata: plex-worker_1 | encoder : Lavc59.25.100 libopus plex-worker_1 | [AVIOContext @ 0x7f52e7de7b40] Statistics: 748 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [AVIOContext @ 0x7f52ee5a6840] Statistics: 83 bytes read, 0 seeks plex-worker_1 | [tcp @ 0x7f52e48cf640] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52e48cf640] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52e7dd7640] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52e7dd7640] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52e7de7800] Statistics: 49 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00000' starts with packet stream:0 pts:0 pts_time:0 frame:0 plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52ee7794c0] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52ee7794c0] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52ee5a6840] Statistics: 48 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00000' count:0 ended plex-worker_1 | [AVIOContext @ 0x7f52e7de7e40] Statistics: 17623 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'chunk-00001' for writing plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52ee786e80] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52ee786e80] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52e7de7340] Statistics: 97 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00001' starts with packet stream:0 pts:2252 pts_time:2.252 frame:54 plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52ee784e80] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52ee784e80] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52e7de7b40] Statistics: 99 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00001' count:1 ended plex-worker_1 | [AVIOContext @ 0x7f52ec5ca680] Statistics: 1462909 bytes written, 0 seeks, 6 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'chunk-00002' for writing plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52ee7847c0] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52ee7847c0] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52ee5a6840] Statistics: 152 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00002' starts with packet stream:0 pts:12679 pts_time:12.679 frame:304 plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52ee784a00] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52ee784a00] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52e7de71c0] Statistics: 151 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00002' count:2 ended plex-worker_1 | [AVIOContext @ 0x7f52e7de7cc0] Statistics: 302046 bytes written, 0 seeks, 2 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'chunk-00003' for writing plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52ee786e80] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52ee786e80] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52e7de7980] Statistics: 204 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00003' starts with packet stream:0 pts:15015 pts_time:15.015 frame:360 plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52e7dd7640] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52e7dd7640] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52ee77c940] Statistics: 203 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00003' count:3 ended plex-worker_1 | [AVIOContext @ 0x7f52e7de7380] Statistics: 1264246 bytes written, 0 seeks, 5 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'chunk-00004' for writing plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f52ee785040] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f52ee785040] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f52ee77ce00] Statistics: 208 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f52ee775680] segment:'chunk-00004' starts with packet stream:0 pts:25442 pts_time:25.442 frame:610 plex-worker_1 | [segment @ 0x7f52ee775680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/manifest?X-Plex-Http-Pipeline=infinite' for writing

Worker seems to report EAE support: plex-worker_1 | EAE_SUPPORT => 1 plex-worker_1 | EAE_EXECUTABLE => /codecs/583b3e4-4434-linux-x86_64-standard/EasyAudioEncoder/EasyAudioEncoder/EasyAudioEncoder plex-worker_1 | FFMPEG_HWACCEL => nvdec plex-worker_1 | ON_DEATH: debug mode enabled for pid [258] plex-worker_1 | Computed CPU ops => 910794 plex-worker_1 | Initializing Worker 98ea31a2-72a1-4f6f-a4fd-bcf16e93a42f|plex-worker plex-worker_1 | Worker listening on port 3501 plex-worker_1 | Worker connected on socket b76KdZv3ks3_eQRwAACf

This time, plex stream ended with error code 4294967280. I should now mention I am using a windows plex player to test this, because interestingly, I just tried it using plex accessed via the chrome webserver, and everything seemed to work...

Worker Log: plex-worker_1 | [dash @ 0x7efcca9fa680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/v151i9nb3p8vvul746w4ge3e/322f33c2-79d0-424c-9d16-2d1cb519385d/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7efcc492e640] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7efcc492e640] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7efcc884e680] Statistics: 2171 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [dash @ 0x7efcca9fa680] Opening 'chunk-stream0-00023.m4s.tmp' for writing plex-worker_1 | [dash @ 0x7efcca9fa680] Opening 'chunk-stream1-00023.m4s.tmp' for writing plex-worker_1 | [AVIOContext @ 0x7efcc406e800] Statistics: 113575 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [dash @ 0x7efcca9fa680] Representation 0 media segment 24 written to: chunk-stream0-00023.m4s plex-worker_1 | [AVIOContext @ 0x7efcc406ee40] Statistics: 165861 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [dash @ 0x7efcca9fa680] Representation 1 media segment 24 written to: chunk-stream1-00023.m4s plex-worker_1 | [dash @ 0x7efcca9fa680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/v151i9nb3p8vvul746w4ge3e/322f33c2-79d0-424c-9d16-2d1cb519385d/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7efcc492ed00] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7efcc492ed00] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7efcc406e980] Statistics: 2177 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [dash @ 0x7efcca9fa680] Opening 'chunk-stream0-00024.m4s.tmp' for writing plex-worker_1 | [AVIOContext @ 0x7efcc406e500] Statistics: 1826 bytes read, 0 seeks plex-worker_1 | [tcp @ 0x7efcc48a5580] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7efcc48a5580] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [dash @ 0x7efcca9fa680] Opening 'chunk-stream1-00024.m4s.tmp' for writing plex-worker_1 | [AVIOContext @ 0x7efcca82b840] Statistics: 246724 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [dash @ 0x7efcca9fa680] Representation 0 media segment 25 written to: chunk-stream0-00024.m4s plex-worker_1 | [AVIOContext @ 0x7efcc406e500] Statistics: 166734 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [dash @ 0x7efcca9fa680] Representation 1 media segment 25 written to: chunk-stream1-00024.m4s plex-worker_1 | [dash @ 0x7efcca9fa680] Opening 'http://192.168.0.2:32499/video/:/transcode/session/v151i9nb3p8vvul746w4ge3e/322f33c2-79d0-424c-9d16-2d1cb519385d/manifest?X-Plex-Http-Pipeline=infinite' for writing

Here's the plex log snippet from the time it was working: progress=8.7&size=-22&remaining=12408&vdec_packets=14037&vdec_sw_ok=14031&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1147) (range: bytes=0-) Jan 03, 2023 14:34:22.364 [0x7f76f11d1b38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14040&vdec_sw_ok=14034&vdec_hw_status=0 (11 live) #60fc8 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:22.364 [0x7f76f11d1b38] Verbose — [Req#60fc8/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:22.364 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14040&vdec_sw_ok=14034&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1148) (range: bytes=0-) Jan 03, 2023 14:34:22.880 [0x7f76f11d1b38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14041&vdec_sw_ok=14035&vdec_hw_status=0 (11 live) #60fce Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:22.881 [0x7f76f11d1b38] Verbose — [Req#60fce/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:22.881 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14041&vdec_sw_ok=14035&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1149) (range: bytes=0-) Jan 03, 2023 14:34:23.407 [0x7f76f13d4b38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14044&vdec_sw_ok=14038&vdec_hw_status=0 (11 live) #60fd5 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:23.407 [0x7f76f13d4b38] Verbose — [Req#60fd5/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:23.407 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14044&vdec_sw_ok=14038&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1150) (range: bytes=0-) Jan 03, 2023 14:34:23.755 [0x7f76f0c8bb38] Debug — Request: [127.0.0.1:52824 (Loopback)] GET /identity (12 live) #60f85 Signed-in / Accept => */* / Host => localhost:32400 / User-Agent => curl/7.81.0 Jan 03, 2023 14:34:23.755 [0x7f76f0c8bb38] Verbose — It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:23.755 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:52824] 200 GET /identity (12 live) 0ms 398 bytes (pipelined: 1) Jan 03, 2023 14:34:23.755 [0x7f76f486cb38] Verbose — Didn't receive a request from 127.0.0.1:52824: End of file Jan 03, 2023 14:34:23.955 [0x7f76f13d4b38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14048&vdec_sw_ok=14042&vdec_hw_status=0 (11 live) #60fdc Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:23.955 [0x7f76f13d4b38] Verbose — [Req#60fdc/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:23.955 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14048&vdec_sw_ok=14042&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1151) (range: bytes=0-) Jan 03, 2023 14:34:24.456 [0x7f76f1ff0b38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14048&vdec_sw_ok=14042&vdec_hw_status=0 (11 live) #60fec Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:24.456 [0x7f76f1ff0b38] Verbose — [Req#60fec/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:24.456 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14048&vdec_sw_ok=14042&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1152) (range: bytes=0-) Jan 03, 2023 14:34:24.972 [0x7f76f1ff0b38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14051&vdec_sw_ok=14045&vdec_hw_status=0 (11 live) #60ff5 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:24.972 [0x7f76f1ff0b38] Verbose — [Req#60ff5/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:24.972 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14051&vdec_sw_ok=14045&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1153) (range: bytes=0-) Jan 03, 2023 14:34:25.495 [0x7f76f0c8bb38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14052&vdec_sw_ok=14046&vdec_hw_status=0 (11 live) #60ff9 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:25.496 [0x7f76f0c8bb38] Verbose — [Req#60ff9/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:25.496 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14052&vdec_sw_ok=14046&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1154) (range: bytes=0-) Jan 03, 2023 14:34:26.002 [0x7f76f0c8bb38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14053&vdec_sw_ok=14047&vdec_hw_status=0 (11 live) #61003 Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:26.002 [0x7f76f0c8bb38] Verbose — [Req#61003/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:26.002 [0x7f76f4a6fb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14053&vdec_sw_ok=14047&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1155) (range: bytes=0-) Jan 03, 2023 14:34:26.529 [0x7f76f0a88b38] Debug — Request: [127.0.0.1:50208 (Loopback)] PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14058&vdec_sw_ok=14052&vdec_hw_status=0 (11 live) #6100a Signed-in Token (Peter Pugno) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/LIBAVFORMAT_VERSION / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx42cf-85c0-4cffaade0a5a Jan 03, 2023 14:34:26.529 [0x7f76f0a88b38] Verbose — [Req#6100a/Transcode/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978] It took 0.0 sec to serialize a list with 0 elements. Jan 03, 2023 14:34:26.529 [0x7f76f486cb38] Debug — Completed: [127.0.0.1:50208] 206 PUT /video/:/transcode/session/a3lkk24kr6td8f4xpu1z1e4r/4ac8cc8f-e114-4da3-88e8-5e8c71abd978/progress?progress=8.7&size=-22&remaining=-1&vdec_packets=14058&vdec_sw_ok=14052&vdec_hw_status=0 (11 live) 0ms 371 bytes (pipelined: 1156) (range: bytes=0-)

Further to this, when using the web portal, I could select original quality. The worker still processed the transcode as the logs were responding - but seemed to not actually be transcoding - no increase in cpu/gpu usage(a true direct stream).

I then switched back to the windows player, and the original stream no longer worked, even though the chunks being written seem to be large enough to handle it...

lex-worker_1 | [segment @ 0x7f28b8a95380] Opening 'chunk-00081' for writing plex-worker_1 | [segment @ 0x7f28b8a95380] Opening 'http://192.168.0.2:32499/video/:/transcode/session/22wq7sw7arsndz3x970mp9b2/f434cbdf-c23d-4920-ae69-bad9e42e908f/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f28b2980100] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f28b2980100] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f28b88c69c0] Statistics: 225 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f28b8a95380] segment:'chunk-00081' starts with packet stream:0 pts:526860 pts_time:526.86 frame:7716 plex-worker_1 | [segment @ 0x7f28b8a95380] Opening 'http://192.168.0.2:32499/video/:/transcode/session/22wq7sw7arsndz3x970mp9b2/f434cbdf-c23d-4920-ae69-bad9e42e908f/manifest?X-Plex-Http-Pipeline=infinite' for writing plex-worker_1 | [tcp @ 0x7f28b8aa4b80] Starting connection attempt to 192.168.0.2 port 32499 plex-worker_1 | [tcp @ 0x7f28b8aa4b80] Successfully connected to 192.168.0.2 port 32499 plex-worker_1 | [AVIOContext @ 0x7f28b88c69c0] Statistics: 224 bytes written, 0 seeks, 1 writeouts plex-worker_1 | [segment @ 0x7f28b8a95380] segment:'chunk-00081' count:81 ended plex-worker_1 | [AVIOContext @ 0x7f28b32d9cc0] Statistics: 456849 bytes written, 0 seeks, 2 writeouts plex-worker_1 | [segment @ 0x7f28b8a95380] Opening 'chunk-00082' for writing plex-worker_1 | [segment @ 0x7f28b8a95380] Opening 'http://192.168.0.2:32499/video/:/transcode/session/22wq7sw7arsndz3x970mp9b2/f434cbdf-c23d-4920-ae69-bad9e42e908f/manifest?X-Plex-Http-Pipeline=infinite' for writing

I then tried forcing the conversion, changing quality to 720p 2Mbps, and again, many chunks are being written, but the stream freezes.

github-actions[bot] commented 1 year ago

This issue is stale because it has been open for 30 days with no activity.

github-actions[bot] commented 1 year ago

This issue was closed because it has been inactive for 14 days since being marked as stale.

paytah232 commented 1 year ago

Long time, but I finally circled back, and using https://github.com/pabloromeo/clusterplex/pull/81, I was able to get this working.

I couldn't see any processes running on nvidia-smi, however, the GPU memory usage increased whilst the transcode was running, and disappeared as it stopped, so I assume everything is now working. Here is the following config that worked for me:

`version: '3.8'

services: plex-worker: image: ghcr.io/linuxserver/plex:latest hostname: "plex_worker" container_name: plex_worker restart: always devices: