Please recreate the problem with DEBUG logging enabled.
Next , attach the debug logs which capture the failure.
Iām right here ready to do as much troubleshooting as you want to do, I would love to get this issue resolved if possible. I donāt run a linux setup, so maybe should I crosspost in a different forum? I just saw this forum when I searched on Google.
I will try to the best of my ability.
If itās part of how Windows does HW transcoding then I wonāt be able to help.
I am willing to give it a look.
At exactly 7:06, I lowered my resolution transcode down to where it would crash. Here are the new logs. Let me know if they have what you need.
You forgot to enable DEBUG logging and then click SAVE.
Care to try again?
All I get is:
Feb 12, 2022 19:05:20.774 [8364] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Feb 12, 2022 19:06:01.761 [1388] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Feb 12, 2022 19:06:01.761 [1388] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Feb 12, 2022 19:06:07.241 [1604] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Feb 12, 2022 19:06:07.597 [1604] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Feb 12, 2022 19:06:08.082 [1604] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Feb 12, 2022 19:06:08.591 [1604] INFO - [Transcode] [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Feb 12, 2022 19:06:08.802 [8364] WARN - [Transcode/vj8wyt4sx4lwrp93hvndm468] Transcode runner appears to have died.
Feb 12, 2022 19:06:08.802 [9260] WARN - [Transcode/vj8wyt4sx4lwrp93hvndm468] Transcode runner appears to have died.
Feb 12, 2022 19:06:08.914 [8364] WARN - [Transcode/vj8wyt4sx4lwrp93hvndm468] Timed out waiting for segment.
Feb 12, 2022 19:06:08.915 [9260] WARN - [Transcode/vj8wyt4sx4lwrp93hvndm468] Timed out waiting for segment.
Feb 12, 2022 19:06:35.724 [8364] INFO - AutoUpdate: no updates available
Feb 12, 2022 19:06:37.902 [2304] INFO - AutoUpdate: no updates available
Which CPU is this please ?
Thatās why Iām confused, I swear I did that. Let me try again.
Itās a dual socket Xeon btw. I have no issues transcoding with that if I have hardware encoding turned off.
Here is a video of me downloading these logs. Let me know if Iām doing something wrong.
YEPā¦ wrong āDEBUGā logs 
Settings - Server - General - Show Advanced
THOSE DEBUG logs 
You were changing the player logs.
Iām a moron. Lmao thanks for being patient
I cant tell if that is the TV that passes transcoding or not. TV works while transcoding this is a Plex Web issue. If that is infact the Plex web āworkingā then what plex assumes is happening and what is happening are different?
Hereās something that might aid you.
This video showing console output and a terminal window showing current nvidia gpu processes. Plex fails to transcode 6 times here and somehow succeeds on the second try only to go back to failing every attempt after that on other files. Attached below is this same log file as what i record in the clip above where it is clearly failing to transcode.
Normally the encoder starts and instantly dies (as can be seen in the terminal output) and produces no output followed by plex either freezing momentarily or giving an error.
Thatās a WHOLE lot better
- Itās finding the Nvidia and accessing it.
Feb 12, 2022 19:21:12.647 [3896] DEBUG - [Transcode] Selecting best audio stream for part ID 1093 (autoselect: 1 language: en)
Feb 12, 2022 19:21:12.647 [3896] DEBUG - [Transcode] Audio Stream: 2383, Subtitle Stream: -1
Feb 12, 2022 19:21:12.656 [3896] DEBUG - [Transcode] Found session GUID of gzcn2fz221n3q9lio8kwrkhh in session start.
Feb 12, 2022 19:21:12.656 [3896] DEBUG - [Transcode] Cleaning directory for session gzcn2fz221n3q9lio8kwrkhh ()
Feb 12, 2022 19:21:12.656 [3896] DEBUG - [Transcode] Starting a transcode session gzcn2fz221n3q9lio8kwrkhh at offset -1.0 (state=3)
Feb 12, 2022 19:21:12.657 [3896] DEBUG - [Transcode] TPU: hardware transcoding: using hardware decode accelerator nvdec
Feb 12, 2022 19:21:12.657 [3896] DEBUG - [Transcode] TPU: hardware transcoding: zero-copy support not present
Feb 12, 2022 19:21:12.657 [3896] DEBUG - [Transcode] [Universal] Using local file path instead of URL: T:\Movies\Black.Widow.2021.2160p.10bit.HDR.WEBRip.6CH.x265.HEVC-PSA\Black.Widow.2021.2160p.10bit.HDR.WEBRip.6CH.x265.HEVC-PSA.mkv
Feb 12, 2022 19:21:12.658 [3896] DEBUG - [Transcode] TPU: hardware transcoding: final decoder: nvdec, final encoder: nvenc
Feb 12, 2022 19:21:12.658 [3896] DEBUG - [Transcode/JobRunner] Job running: set "FFMPEG_EXTERNAL_LIBS=\\\\?\\C\:\\Users\\Administrator\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\62cc2bc-4226-windows-x86\\" & set "X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx" & "C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe" -codec:0 hevc -hwaccel:0 nvdec -hwaccel_fallback_threshold:0 10 -threads:0 1 -hwaccel_device:0 cuda -analyzeduration 20000000 -probesize 20000000 -i T:\Movies\Black.Widow.2021.2160p.10bit.HDR.WEBRip.6CH.x265.HEVC-PSA\Black.Widow.2021.2160p.10bit.HDR.WEBRip.6CH.x265.HEVC-PSA.mkv -filter_complex [0:0]scale=w=3042:h=1280:force_divisible_by=4[0];[0]format=pix_fmts=yuv420p|nv12[1] -map [1] -codec:0 h264_nvenc -b:0 8665k -maxrate:0 11554k -bufsize:0 23108k -preset:0 hp -forced-idr:0 1 -r:0 23.975999999999999 -force_key_frames:0 expr:gte(t,n_forced*1) -map 0:1 -metadata:s:1 language=eng -codec:1 copy -copypriorss:1 0 -f dash -seg_duration 1 -dash_segment_type mp4 -init_seg_name init-stream$RepresentationID$.m4s -media_seg_name chunk-stream$RepresentationID$-$Number%05d$.m4s -window_size 5 -delete_removed false -skip_to_segment 1 -time_delta 0.0625 -manifest_name http://127.0.0.1:32400/video/:/transcode/session/gzcn2fz221n3q9lio8kwrkhh/db20671e-fcff-4144-bf0d-3a1248914309/manifest?X-Plex-Http-Pipeline=infinite -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 dash -start_at_zero -copyts -vsync cfr -init_hw_device cuda=cuda: -filter_hw_device cuda -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/gzcn2fz221n3q9lio8kwrkhh/db20671e-fcff-4144-bf0d-3a1248914309/progress
Itās playing at about 12 Mbps.
Plex/web starts to get the video segments for playback
Feb 12, 2022 19:21:14.965 [9240] DEBUG - Content-Length of R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\init-stream0.m4s,R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\chunk-stream0-00002.m4s is 13222 (of total: 13222).
Feb 12, 2022 19:21:14.966 [7300] DEBUG - Completed: [192.168.1.18:58783] 200 GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/1.m4s (17 live) TLS GZIP 879ms 3471 bytes (pipelined: 3)
Feb 12, 2022 19:21:14.966 [5444] DEBUG - Completed: [192.168.1.18:58779] 200 GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/1.m4s (17 live) TLS GZIP 879ms 13222 bytes (pipelined: 4)
Feb 12, 2022 19:21:14.978 [9240] DEBUG - Request: [192.168.1.18:58783 (Allowed Network (Subnet))] OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/2.m4s (16 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:14.978 [3896] DEBUG - Request: [192.168.1.18:58790 (Allowed Network (Subnet))] OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/2.m4s (15 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:14.978 [5444] DEBUG - Completed: [192.168.1.18:58783] 200 OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/2.m4s (15 live) TLS GZIP 0ms 376 bytes (pipelined: 4)
Feb 12, 2022 19:21:14.978 [7300] DEBUG - Completed: [192.168.1.18:58790] 200 OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/2.m4s (15 live) TLS GZIP 0ms 376 bytes (pipelined: 4)
Feb 12, 2022 19:21:14.982 [3896] DEBUG - Request: [192.168.1.18:58790 (Allowed Network (Subnet))] GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/2.m4s (15 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:14.982 [9240] DEBUG - Request: [192.168.1.18:58783 (Allowed Network (Subnet))] GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/2.m4s (15 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:14.983 [9240] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Asked for segment 2 from session.
Feb 12, 2022 19:21:14.983 [3896] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Asked for segment 2 from session.
Feb 12, 2022 19:21:15.794 [3300] DEBUG - [Transcode] Transcoder segment range: 0 - 2 (2)
Feb 12, 2022 19:21:15.855 [9240] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Returning segment 2 from session
Feb 12, 2022 19:21:15.856 [3896] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Returning segment 2 from session
Feb 12, 2022 19:21:15.856 [9240] DEBUG - Content-Length of R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\init-stream0.m4s,R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\chunk-stream0-00003.m4s is 13216 (of total: 13216).
Feb 12, 2022 19:21:15.856 [3896] DEBUG - Content-Length of R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\init-stream1.m4s,R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\chunk-stream1-00003.m4s is 26858 (of total: 26858).
Feb 12, 2022 19:21:15.857 [7300] DEBUG - Completed: [192.168.1.18:58783] 200 GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/2.m4s (16 live) TLS GZIP 874ms 13216 bytes (pipelined: 5)
Feb 12, 2022 19:21:15.857 [5444] DEBUG - Completed: [192.168.1.18:58790] 200 GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/2.m4s (16 live) TLS GZIP 874ms 26858 bytes (pipelined: 5)
Feb 12, 2022 19:21:15.867 [3896] DEBUG - Request: [192.168.1.18:58783 (Allowed Network (Subnet))] OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/3.m4s (15 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:15.868 [5444] DEBUG - Completed: [192.168.1.18:58783] 200 OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/3.m4s (15 live) TLS GZIP 0ms 376 bytes (pipelined: 6)
Feb 12, 2022 19:21:15.872 [3896] DEBUG - Request: [192.168.1.18:58783 (Allowed Network (Subnet))] OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/3.m4s (14 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:15.873 [5444] DEBUG - Completed: [192.168.1.18:58783] 200 OPTIONS /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/3.m4s (14 live) TLS GZIP 0ms 376 bytes (pipelined: 7)
Feb 12, 2022 19:21:15.877 [3896] DEBUG - Request: [192.168.1.18:58783 (Allowed Network (Subnet))] GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/0/3.m4s (16 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:15.877 [3896] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Asked for segment 3 from session.
Feb 12, 2022 19:21:15.879 [9240] DEBUG - Request: [192.168.1.18:58794 (Allowed Network (Subnet))] GET /video/:/transcode/universal/session/gzcn2fz221n3q9lio8kwrkhh/1/3.m4s (16 live) TLS GZIP Signed-in
Feb 12, 2022 19:21:15.879 [9240] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Asked for segment 3 from session.
Feb 12, 2022 19:21:16.669 [5604] DEBUG - [Transcode] Transcoder segment range: 0 - 3 (3)
Feb 12, 2022 19:21:16.746 [9240] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Returning segment 3 from session
Feb 12, 2022 19:21:16.746 [3896] DEBUG - [Transcode/gzcn2fz221n3q9lio8kwrkhh] Returning segment 3 from session
Then it stops and a new playback session starts.
Is R:\ a network mount?
Feb 12, 2022 19:21:15.856 [9240] DEBUG - Content-Length of R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\init-stream0.m4s,R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\chunk-stream0-00003.m4s is 13216 (of total: 13216).
Feb 12, 2022 19:21:15.856 [3896] DEBUG - Content-Length of R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\init-stream1.m4s,R:\Temp\Transcode\Sessions\plex-transcode-gzcn2fz221n3q9lio8kwrkhh-db20671e-fcff-4144-bf0d-3a1248914309\chunk-stream1-00003.m4s is 26858 (of total: 26858).
if so, thatās why itās failing. You donāt put transcoder temp over the network.
Secondarily, Are you playing on the same machine PMS is running on?
R:\ is a ramdisk I have on my system, but I believe I already tried to change that and the issue still persisted. Let me try again.
I do not know how HDR tone mapping works on Windows but HDR ā Browser requires tone mapping.
What are you actually getting? a blank screen? Bad colors ?
Iām getting a black screen, gpu usage spikes to 100% on the server, then flattens at 0% and nothing ever happens.
On Linux, we need the Beignet and OpenCL drivers in order to do tone mapping.
At this point, I do recommend you create a new thread in the server-windows topic tag, named something like āTranscoding HDR yields blank imageā
Perhaps someone there will know what you need to do
ChuckPa is it possible to ping a dev to have them look into this? Iām not sure how your team works
Bumping for visibility.
There is no need to get a dev at this point. There is something very strange/odd with your setup. We just need to find it.
I have a known-good test file for you. I use it here when testing transcoding with the various devices.
I would like to know if you have any devices, other than Plex/web, you can test with?
My issue is the same as everyone elses. Plex web refuses to transcode. Transcoding works fine on Roku apps.
I would wager to say my setup isnāt odd except for having Plex HW transcoding enabled. Everything else about my install is stock, sans my Ramdisk for transcoding storage.
And yes, the same is happening with that test file, and all other files, not just one. I assume the same is true for cr4zyb4st4rd.
Itās been over a year that this issue has been reported all over the internet, how long do paying customers have to wait to get a fix?
