May I have the full logs ZIP please? You’re not giving me enough.
Plex Media Server Logs_2021-07-14_20-58-59.zip (472.6 KB)
Plex media player
Plex.log (342.5 KB)
@ChuckPa is that enough, or do you want any other sort of log?
In the future, Please keep VERBOSE turned off unless requested. (only keep DEBUG on). VERBOSE doesn’t help us in 99/100 cases. It’s very rarely used anymore.
I’m trying to read now and it’s making it VERY difficult.
I do not know / use that player.
I see PMS itself is setting up fine for use with the Nvidia
t_format_options' 'live=1' '-segment_time' '1' '-segment_header_filename' 'header' '-segment_start_number' '0' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/manifest?X-Plex-Http-Pipeline=infinite' '-segment_list_type' 'csv' '-segment_list_unfinished' '1' '-segment_list_size' '5' '-segment_list_separate_stream_times' '1' '-avoid_negative_ts' 'disabled' '-map_metadata:g' '-1' '-map_metadata:c' '-1' '-map_chapters' '-1' 'chunk-%05d' '-start_at_zero' '-copyts' '-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/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/progress'
Jul 14, 2021 20:57:41.012 [0x7faf87994b38] DEBUG - [Transcode/JobRunner] Jobs: Starting child process with pid 2589
Jul 14, 2021 20:57:41.027 [0x7faf87ffcb38] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 14, 2021 20:57:41.027 [0x7faf87ffcb38] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 14, 2021 20:57:41.027 [0x7faf7f461b38] DEBUG - Request: [127.0.0.1:36630 (Loopback)] PUT /video/:/transcode/session/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/progress?status=startup (7 live) Signed-in Token (el_pedriyo) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/58.27.104 / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 14, 2021 20:57:41.028 [0x7faf87ffcb38] DEBUG - Completed: [127.0.0.1:36630] 204 PUT /video/:/transcode/session/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/progress?status=startup (7 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Jul 14, 2021 20:57:41.267 [0x7faf8801fb38] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 14, 2021 20:57:41.267 [0x7faf8801fb38] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 14, 2021 20:57:41.267 [0x7faf7f461b38] DEBUG - Request: [127.0.0.1:36630 (Loopback)] PUT /video/:/transcode/session/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/progress?status=startup (7 live) Signed-in Token (el_pedriyo) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/58.27.104 / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 14, 2021 20:57:41.267 [0x7faf8801fb38] DEBUG - Completed: [127.0.0.1:36630] 204 PUT /video/:/transcode/session/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/progress?status=startup (7 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Jul 14, 2021 20:57:41.268 [0x7faf87ffcb38] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 14, 2021 20:57:41.268 [0x7faf87ffcb38] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 14, 2021 20:57:41.268 [0x7faf7f461b38] DEBUG - Request: [127.0.0.1:36630 (Loopback)] PUT /video/:/transcode/session/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/progress?status=opening (7 live) Signed-in Token (el_pedriyo) (range: bytes=0-) / Accept => */* / Connection => keep-alive / Host => 127.0.0.1:32400 / Icy-MetaData => 1 / Range => bytes=0- / User-Agent => Lavf/58.27.104 / X-Plex-Http-Pipeline => infinite / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 14, 2021 20:57:41.268 [0x7faf87ffcb38] DEBUG - Completed: [127.0.0.1:36630] 204 PUT /video/:/transcode/session/2vg3r6mk2gb4eq2qgq194oi2/6ea31a7e-7422-4430-acdc-e474f4411c1c/progress?status=opening (7 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Jul 14, 2021 20:57:41.274 [0x7faf87749b38] DEBUG - [Transcode] [FFMPEG] - Loaded lib: libcuda.so.1
Jul 14, 2021 20:57:41.274 [0x7faf87749b38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuInit
For reasons I don’t know, it is as if the player stalls / does not read anything
It might be but that’s where VERBOSE is making it so difficult to read
Hello,
What do you mean about not using that player? I am currently using the official windows plex media player to make the testing.
Let me take out verbose and share again the logs with you 
Here the logs without Verbose @ChuckPa
Plex Media Server Logs_2021-07-14_22-46-42.zip (48.0 KB)
Also what I can see is multiple times:
Codecs: hardware transcoding: testing API vaapi
As I do not know why it tried many times, although it shows hwdevice nvdec to be working
But well, this is just my own internal thinking about a different thing 
Ignore Vaapi – you’re using nvdec / nvenc
The transcoder and MDE always check both VAAPI & Nvidia
This is a player problem.
There is nothing wrong on the server side.
Jul 14, 2021 22:46:14.339 [0x7ff05cdacb38] DEBUG - [Transcode] Codecs: 10-bit HEVC test succeeded
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] Scaled up video bitrate to 339394Kbps based on 4.500000x fudge factor.
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] Scaled maximum bitrate for resolution reduction to 84848Kbps.
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] MDE: 13 horas: los soldados secretos de Bengasi (2016): Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] MDE: Cannot direct stream audio stream due to profile or setting limitations
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] MDE: 13 horas: los soldados secretos de Bengasi (2016): selected media 0 / 101
Jul 14, 2021 22:46:14.374 [0x7ff05d349b38] DEBUG - [Transcode] Killing job.
Jul 14, 2021 22:46:14.374 [0x7ff05d349b38] DEBUG - [Transcode] Signalling job ID 4073 with 9
Jul 14, 2021 22:46:14.375 [0x7ff05d349b38] DEBUG - [Transcode] Job was already killed, not killing again.
Jul 14, 2021 22:46:14.375 [0x7ff05d349b38] DEBUG - [Transcode] Stopping transcode session 2k9zl7l0s8ic3tevf10r3qlg
Jul 14, 2021 22:46:14.375 [0x7ff05cab9b38] DEBUG - [Transcode] Cleaning directory for session 2k9zl7l0s8ic3tevf10r3qlg (/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-2k9zl7l0s8ic3tevf10r3qlg-47ecd3b6-0aa5-4b8e-ac2a-abf15dcc6324)
Jul 14, 2021 22:46:14.375 [0x7ff05cdacb38] DEBUG - [Transcode] Streaming Resource: Reached Decision id=48 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=101 part=(id=101 decision=transcode container=mkv protocol=http streams=(Video=(id=334 decision=transcode bitrate=7246 encoder=h264_nvenc width=1920 height=1080) Audio=(id=335 decision=transcode bitrate=321 encoder=libopus channels=6 rate=48000))))
Jul 14, 2021 22:46:14.377 [0x7ff05d924b38] DEBUG - Completed: [192.168.1.2:1078] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F48&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=8000&directStreamAudio=0&session=2k9zl7l0s8ic3tevf10r3qlg&offset=1&subtitles=auto©ts=1&Accept-Language=es (9 live) TLS GZIP 3574ms 3222 bytes (pipelined: 4)
Jul 14, 2021 22:46:14.380 [0x7ff05d901b38] DEBUG - Auth: authenticated user 1 as el_pedriyo
Jul 14, 2021 22:46:14.380 [0x7ff05cc89b38] DEBUG - Request: [192.168.1.2:1104 (Subnet)] GET /status/sessions (9 live) TLS GZIP Signed-in Token (el_pedriyo)
Jul 14, 2021 22:46:14.380 [0x7ff05cc89b38] DEBUG - [Now] Adding 1 sessions.
Jul 14, 2021 22:46:14.383 [0x7ff05d924b38] DEBUG - Completed: [192.168.1.2:1104] 200 GET /status/sessions (9 live) TLS GZIP 2ms 3350 bytes (pipelined: 2)
Jul 14, 2021 22:46:14.411 [0x7ff05d349b38] DEBUG - [Transcode] Transcoder: Cleaning old transcode directories.
Jul 14, 2021 22:46:14.411 [0x7ff05d349b38] DEBUG - [Transcode] Transcoder: Cleaning old transcode directory: "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-2k9zl7l0s8ic3tevf10r3qlg-47ecd3b6-0aa5-4b8e-ac2a-abf15dcc6324"
Jul 14, 2021 22:46:14.339 [0x7ff05cdacb38] DEBUG - [Transcode] Codecs: 10-bit HEVC test succeeded
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] Scaled up video bitrate to 339394Kbps based on 4.500000x fudge factor.
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] Scaled maximum bitrate for resolution reduction to 84848Kbps.
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] MDE: 13 horas: los soldados secretos de Bengasi (2016): Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] MDE: Cannot direct stream audio stream due to profile or setting limitations
Jul 14, 2021 22:46:14.374 [0x7ff05cdacb38] DEBUG - [Transcode] MDE: 13 horas: los soldados secretos de Bengasi (2016): selected media 0 / 101
Jul 14, 2021 22:46:14.374 [0x7ff05d349b38] DEBUG - [Transcode] Killing job.
Jul 14, 2021 22:46:14.374 [0x7ff05d349b38] DEBUG - [Transcode] Signalling job ID 4073 with 9
Jul 14, 2021 22:46:14.375 [0x7ff05d349b38] DEBUG - [Transcode] Job was already killed, not killing again.
Jul 14, 2021 22:46:14.375 [0x7ff05d349b38] DEBUG - [Transcode] Stopping transcode session 2k9zl7l0s8ic3tevf10r3qlg
Jul 14, 2021 22:46:14.375 [0x7ff05cab9b38] DEBUG - [Transcode] Cleaning directory for session 2k9zl7l0s8ic3tevf10r3qlg (/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-2k9zl7l0s8ic3tevf10r3qlg-47ecd3b6-0aa5-4b8e-ac2a-abf15dcc6324)
Jul 14, 2021 22:46:14.375 [0x7ff05cdacb38] DEBUG - [Transcode] Streaming Resource: Reached Decision id=48 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=101 part=(id=101 decision=transcode container=mkv protocol=http streams=(Video=(id=334 decision=transcode bitrate=7246 encoder=h264_nvenc width=1920 height=1080) Audio=(id=335 decision=transcode bitrate=321 encoder=libopus channels=6 rate=48000))))
Jul 14, 2021 22:46:14.377 [0x7ff05d924b38] DEBUG - Completed: [192.168.1.2:1078] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F48&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=8000&directStreamAudio=0&session=2k9zl7l0s8ic3tevf10r3qlg&offset=1&subtitles=auto©ts=1&Accept-Language=es (9 live) TLS GZIP 3574ms 3222 bytes (pipelined: 4)
Jul 14, 2021 22:46:14.380 [0x7ff05d901b38] DEBUG - Auth: authenticated user 1 as el_pedriyo
Jul 14, 2021 22:46:14.380 [0x7ff05cc89b38] DEBUG - Request: [192.168.1.2:1104 (Subnet)] GET /status/sessions (9 live) TLS GZIP Signed-in Token (el_pedriyo)
Jul 14, 2021 22:46:14.380 [0x7ff05cc89b38] DEBUG - [Now] Adding 1 sessions.
Jul 14, 2021 22:46:14.383 [0x7ff05d924b38] DEBUG - Completed: [192.168.1.2:1104] 200 GET /status/sessions (9 live) TLS GZIP 2ms 3350 bytes (pipelined: 2)
Jul 14, 2021 22:46:14.411 [0x7ff05d349b38] DEBUG - [Transcode] Transcoder: Cleaning old transcode directories.
Jul 14, 2021 22:46:14.411 [0x7ff05d349b38] DEBUG - [Transcode] Transcoder: Cleaning old transcode directory: "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-2k9zl7l0s8ic3tevf10r3qlg-47ecd3b6-0aa5-4b8e-ac2a-abf15dcc6324"
Is not the server wrong because of killing the transcode because of this:
MDE: Cannot direct stream audio stream due to profile or setting limitations
no Player is closing it.
Jul 14, 2021 22:46:22.721 [0x7ff05ceedb38] DEBUG - [MediaProviderManager] cloud provider (Metadata) is online and available
Jul 14, 2021 22:46:22.721 [0x7ff05ceedb38] DEBUG - [MediaProviderManager] cloud provider (Music) is online and available
Jul 14, 2021 22:46:22.721 [0x7ff05ceedb38] DEBUG - [MediaProviderManager] cloud provider (Movies & TV) is online and available
Jul 14, 2021 22:46:23.058 [0x7ff05d924b38] DEBUG - WebSocket: client initiated close
Jul 14, 2021 22:46:23.058 [0x7ff05d924b38] DEBUG - WebSocket: client initiated close
Jul 14, 2021 22:46:23.058 [0x7ff05d924b38] DEBUG - handleStreamRead code 1: stream truncated
Jul 14, 2021 22:46:23.058 [0x7ff05d924b38] DEBUG - NotificationStream: Removing because of error
Jul 14, 2021 22:46:23.059 [0x7ff05d924b38] DEBUG - Completed after connection close: [192.168.1.2:1106] -3 GET /:/websockets/notifications?Accept-Language=es (5 live) TLS GZIP 507ms 0 bytes
Jul 14, 2021 22:46:23.059 [0x7ff05d924b38] DEBUG - handleStreamRead code 1: stream truncated
Jul 14, 2021 22:46:23.059 [0x7ff05d924b38] DEBUG - NotificationStream: Removing because of error
Jul 14, 2021 22:46:23.059 [0x7ff05d924b38] DEBUG - Completed after connection close: [192.168.1.2:1040] -3 GET /:/websockets/notifications (5 live) TLS GZIP 25049ms 6463 bytes
Jul 14, 2021 22:46:23.078 [0x7ff05d924b38] DEBUG - Failed to stream media, client probably disconnected after 131481600 bytes: 104 - Connection reset by peer
Jul 14, 2021 22:46:23.078 [0x7ff05d924b38] DEBUG - Completed after connection close: [192.168.1.2:1083] 206 GET /library/parts/101/1624039092/file.mkv?Accept-Language=es (3 live) TLS 16473ms 131481600 bytes (range: bytes=8355-)
Jul 14, 2021 22:46:24.782 [0x7ff05cbfbb38] WARN - Connection Tester: Error testing media server ubuntu in 10000 ms.
Jul 14, 2021 22:46:24.783 [0x7ff05d954b38] DEBUG - Media Server: Tested all servers in 20.0 seconds.
Jul 14, 2021 22:46:29.212 [0x7ff05d901b38] DEBUG - Auth: authenticated user 1 as el_pedriyo
Jul 14, 2021 22:46:29.213 [0x7ff05cd4cb38] DEBUG - Request: [192.168.1.2:1145 (Subnet)] GET /media/providers (2 live) TLS GZIP Signed-in Token (el_pedriyo)
Jul 14, 2021 22:46:29.216 [0x7ff05d901b38] DEBUG - Completed: [192.168.1.2:1145] 200 GET /media/providers (2 live) TLS GZIP 3ms 4587 bytes (pipelined: 1)
Jul 14, 2021 22:46:29.269 [0x7ff05d924b38] DEBUG - Auth: authenticated user 1 as el_pedriyo
Jul 14, 2021 22:46:29.269 [0x7ff05ccacb38] DEBUG - Request: [192.168.1.2:1145 (Subnet)] GET /:/prefs (2 live) TLS GZIP Signed-in Token (el_pedriyo)
Jul 14, 2021 22:46:29.274 [0x7ff05d901b38] DEBUG - Auth: authenticated user 1 as el_pedriyo
Jul 14, 2021 22:46:29.275 [0x7ff05d924b38] DEBUG - Auth: authenticated user 1 as el_pedriyo
Jul 14, 2021 22:46:29.275 [0x7ff05ccdfb38] DEBUG - Request: [192.168.1.2:1166 (Subnet)] GET /updater/status (4 live) TLS GZIP Signed-in Token (el_pedriyo)
Jul 14, 2021 22:46:29.275 [0x7ff05cd4cb38] DEBUG - Request: [192.168.1.2:1167 (Subnet)] GET /:/websockets/notifications (4 live) TLS GZIP Signed-in Token (el_pedriyo)
Jul 14, 2021 22:46:29.275 [0x7ff05cd4cb38] DEBUG - WebSocket: Performing handshake from origin file://
Jul 14, 2021 22:46:29.275 [0x7ff05cd4cb38] DEBUG - Beginning read from WebSocket
Jul 14, 2021 22:46:29.276 [0x7ff05d924b38] DEBUG - Completed: [192.168.1.2:1166] 200 GET /updater/status (4 live) TLS GZIP 1ms 433 bytes (pipelined: 1)
Client initiated close
aka… network / player… one or combination of both (maybe firewall / virus software?)
hmmm, is there anyway you can report this issue to the plex media players devs?
Also I have issues when changing resolution in chrome browser, so I guess there will be another issue in there aswell 
Will try with firefox, to see if it is just chrome browser issue
Since it is network or plex player, let me try from another PC at home. Though this one I am using to test should be more than capable and not having network issues
No firewall I have at the moment, but let me check from different PC with the same plex player ![]()
Hello again,
I have tried from different PC and the same issue is happening, I only have a router in the middle, don’t think it is an issue from that stupid box, but I can try direct connection to server to see if that helps.
Soooo, for chrome and firefox, it is not working either. Here the log of the server:
Plex Media Server Logs_2021-07-14_23-15-45.zip (88.7 KB)
So, when I start playing the movie, it works under resolution: Convert maximum:
As soon as I change to different resolution settings like convert (20mbps, 1080p HD) black screen:
No buffering, no GPU process open as per Nvidia gpu monitor
Hey @ChuckPa let me drop a direct 10gbps fiber connection between my server and PC, will see if it is something in the middle or if it is the player and browser making this
If playing to a browser, the limits of the browser rendering and CPU are known to be problematic.
I have 80 Mbps videos which will not direct play or transcode to the web player.
The instant I drop to 20 Mbps, the browser is fine. I’m using an i7-8809G CPU for the browser (NUC8-i7-HVK)
Yep, that could be the problem. However the way it playsback is on 80mbps, if I remember correctly as maximum. When it does not play is when I set it as 20mbps. I currently have a i7-7820X 8c-16T
Hey @ChuckPa I have finished testing with direct 10gbps connection between my server and PC, and still the same issue, no manner to make this work.
Is there anyway you can report this to the plex media player team for them to fix it?

