Server Version#: 1.13.5.5332
Player Version#: 3.13.23
Hi, I am unable to play high quality movies and TV shows from my Plex server to any client, including the web app. All slow resolution files play well but as soon as the quality turns on 1080 or better, I receive the message: the video did not start playing in time.
The Plex server logs show:
Aug 30, 2018 22:38:05.218 [4836] INFO - Plex Media Server v1.13.5.5332-21ab172de - Microsoft PC x64 - build: windows-i386 english - GMT 02:00
Aug 30, 2018 22:38:05.219 [4836] INFO - Windows version: 10.0 (Build 17134), language en-US
Aug 30, 2018 22:38:05.219 [4836] INFO - 8 2666 MHz processor(s): Architecture=0, Level=6, Revision=6661 Processor Identifier=Intel64 Family 6 Model 26 Stepping 5, GenuineIntel
Aug 30, 2018 22:38:05.219 [4836] DEBUG - “C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe”
Aug 30, 2018 22:38:05.206 [3948] DEBUG - Request: [172.16.0.116:46253 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F9211&mediaIndex=0&partIndex=0&protocol=hls&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&session=we2a93or3900yxpchnpofuho&subtitles=burn&Accept-Language=en (9 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:05.207 [3948] DEBUG - Found session GUID of we2a93or3900yxpchnpofuho in session start.
Aug 30, 2018 22:38:05.209 [3948] DEBUG - TranscodeUniversalRequest: using augmented profile HTML TV App
Aug 30, 2018 22:38:05.209 [3948] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/9211?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=bamdvr28595kizug07t9tkl9
Aug 30, 2018 22:38:05.210 [3948] DEBUG - Auth: authenticated user 1 as test@plexaccount.com
Aug 30, 2018 22:38:05.220 [1160] DEBUG - Streaming Resource: Usage: 0kbps of WAN bandwidth, 0 streaming transcode slots, and 0 static transcode slots across 0 sessions
Aug 30, 2018 22:38:05.221 [3948] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Aug 30, 2018 22:38:05.221 [3948] DEBUG - Selecting best audio stream for part ID 8527 (autoselect: 0 language: en)
Aug 30, 2018 22:38:05.221 [3948] DEBUG - Audio Stream: 2659, Subtitle Stream: 2643
Aug 30, 2018 22:38:05.224 [3948] DEBUG - Codecs: testing h264_nvenc (encoder)
Aug 30, 2018 22:38:05.225 [3948] ERROR - [FFMPEG] - Cannot load nvcuda.dll
Aug 30, 2018 22:38:05.225 [3948] WARN - avcodec_open2 returned -1313558101 for encoder ‘h264_nvenc’
Aug 30, 2018 22:38:05.225 [3948] DEBUG - Codecs: testing h264_mf (encoder)
Aug 30, 2018 22:38:05.226 [9908] DEBUG - HTTP 200 response from GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb083h8w9%2Foperation-ouch-hospital-takeover-3-bendy-cool-cartilage
Aug 30, 2018 22:38:05.226 [9908] DEBUG - [com.plexapp.system] HTTP reply status 200, with 3091 bytes of content.
Aug 30, 2018 22:38:05.227 [4968] DEBUG - Completed: [127.0.0.1:63563] 200 GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb083h8w9%2Foperation-ouch-hospital-takeover-3-bendy-cool-cartilage (9 live) GZIP 934ms 1914 bytes
Aug 30, 2018 22:38:05.241 [2316] DEBUG - Request: [127.0.0.1:63573 (Loopback)] GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08kftjd%2Fart-ninja-series-3-9-day-of-the-ancestor (9 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:05.241 [2316] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08kftjd%2Fart-ninja-series-3-9-day-of-the-ancestor
Aug 30, 2018 22:38:05.242 [2316] DEBUG - HTTP requesting GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08kftjd%2Fart-ninja-series-3-9-day-of-the-ancestor
Aug 30, 2018 22:38:05.249 [3948] ERROR - [FFMPEG] - could not find any MFT for the given media type
Aug 30, 2018 22:38:05.250 [3948] ERROR - [FFMPEG] - could not create MFT
Aug 30, 2018 22:38:05.250 [3948] WARN - avcodec_open2 returned -40 for encoder ‘h264_mf’
Aug 30, 2018 22:38:05.251 [3948] DEBUG - Codecs: hardware transcoding: testing API dxva2
Aug 30, 2018 22:38:05.255 [3948] ERROR - [FFMPEG] - Failed to create Direct3D device
Aug 30, 2018 22:38:05.256 [3948] DEBUG - Codecs: hardware transcoding: opening hw device failed - probably not supported by this system, error: Unknown error occurred
Aug 30, 2018 22:38:05.256 [3948] DEBUG - Scaled up video bitrate to 19986Kbps based on 1.500000x fudge factor.
Aug 30, 2018 22:38:05.257 [3948] DEBUG - MDE: Selected protocol hls; container: mpegts
Aug 30, 2018 22:38:05.257 [3948] DEBUG - MDE: analyzing media item 8527
Aug 30, 2018 22:38:05.257 [3948] DEBUG - MDE: Star Wars Episode Vii the Force Awakens (2015): Direct Play is disabled
Aug 30, 2018 22:38:05.257 [3948] DEBUG - MDE: Star Wars Episode Vii the Force Awakens (2015): media must be transcoded in order to use the hls protocol
Aug 30, 2018 22:38:05.258 [3948] DEBUG - MDE: Star Wars Episode Vii the Force Awakens (2015): selected subtitle cannot be converted to a compatible format, burning into video stream
Aug 30, 2018 22:38:05.258 [3948] DEBUG - MDE: Star Wars Episode Vii the Force Awakens (2015): avoiding video remux due to burned subtitle stream
Aug 30, 2018 22:38:05.258 [3948] DEBUG - MDE: Star Wars Episode Vii the Force Awakens (2015): no remuxable profile found, so video stream will be transcoded
Aug 30, 2018 22:38:05.258 [3948] DEBUG - Codecs: testing h264_nvenc (encoder)
Aug 30, 2018 22:38:05.259 [3948] ERROR - [FFMPEG] - Cannot load nvcuda.dll
Aug 30, 2018 22:38:05.259 [3948] WARN - avcodec_open2 returned -1313558101 for encoder ‘h264_nvenc’
Aug 30, 2018 22:38:05.259 [3948] DEBUG - Codecs: testing h264_mf (encoder)
Aug 30, 2018 22:38:05.260 [3948] ERROR - [FFMPEG] - could not find any MFT for the given media type
Aug 30, 2018 22:38:05.260 [3948] ERROR - [FFMPEG] - could not create MFT
Aug 30, 2018 22:38:05.260 [3948] WARN - avcodec_open2 returned -40 for encoder ‘h264_mf’
Aug 30, 2018 22:38:05.261 [3948] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Aug 30, 2018 22:38:05.261 [3948] DEBUG - Codecs: hardware transcoding: testing API dxva2
Aug 30, 2018 22:38:05.265 [3948] ERROR - [FFMPEG] - Failed to create Direct3D device
Aug 30, 2018 22:38:05.266 [3948] DEBUG - Codecs: hardware transcoding: opening hw device failed - probably not supported by this system, error: Unknown error occurred
Aug 30, 2018 22:38:05.266 [3948] DEBUG - Scaled up video bitrate to 19986Kbps based on 1.500000x fudge factor.
Aug 30, 2018 22:38:05.267 [3948] DEBUG - MDE: Star Wars Episode Vii the Force Awakens (2015): selected media 0 / 8527
Aug 30, 2018 22:38:05.268 [3948] DEBUG - Streaming Resource: Adding session 0x6352c38:bamdvr28595kizug07t9tkl9 which is using transcoder slot. Used slots is now 1
Aug 30, 2018 22:38:05.268 [3948] DEBUG - Streaming Resource: Added session 0x6352c38:bamdvr28595kizug07t9tkl9
Aug 30, 2018 22:38:05.268 [3948] DEBUG - Streaming Resource: Reached Decision id=9211 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=8527 part=(id=8527 decision=transcode container=mpegts protocol=hls streams=(Video=(id=2658 decision=transcode bitrate=19986 encoder=libx264 width=3840 height=2160) Audio=(id=2659 decision=copy channels=6 rate=48000) Subtitle=(id=2643 decision=burn languageCode=eng location=embedded))))
Aug 30, 2018 22:38:05.272 [4972] DEBUG - Completed: [172.16.0.116:46253] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F9211&mediaIndex=0&partIndex=0&protocol=hls&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&session=we2a93or3900yxpchnpofuho&subtitles=burn&Accept-Language=en (9 live) GZIP 66ms 1435 bytes (pipelined: 4)
Aug 30, 2018 22:38:05.828 [4968] DEBUG - Auth: authenticated user 1 as test@plexaccount.com
Aug 30, 2018 22:38:05.829 [7628] DEBUG - Request: [172.16.0.116:46255 (Subnet)] GET /video/:/transcode/universal/start.m3u8?hasMDE=1&path=%2Flibrary%2Fmetadata%2F9211&mediaIndex=0&partIndex=0&protocol=hls&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&session=we2a93or3900yxpchnpofuho&subtitles=burn&Accept-Language=en (10 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:05.829 [7628] DEBUG - Found session GUID of we2a93or3900yxpchnpofuho in session start.
Aug 30, 2018 22:38:05.831 [7628] DEBUG - TranscodeUniversalRequest: using augmented profile HTML TV App
Aug 30, 2018 22:38:05.831 [7628] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/9211?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=bamdvr28595kizug07t9tkl9
Aug 30, 2018 22:38:05.832 [7628] DEBUG - Auth: authenticated user 1 as test@plexaccount.com
Aug 30, 2018 22:38:05.868 [7628] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Aug 30, 2018 22:38:05.868 [7628] DEBUG - Selecting best audio stream for part ID 8527 (autoselect: 0 language: en)
Aug 30, 2018 22:38:05.868 [7628] DEBUG - Audio Stream: 2659, Subtitle Stream: 2643
Aug 30, 2018 22:38:05.871 [7628] DEBUG - Found session GUID of we2a93or3900yxpchnpofuho in session start.
Aug 30, 2018 22:38:05.871 [7628] DEBUG - Using session GUID we2a93or3900yxpchnpofuho for new transcode session.
Aug 30, 2018 22:38:05.871 [7628] DEBUG - Cleaning directory for session we2a93or3900yxpchnpofuho ()
Aug 30, 2018 22:38:05.876 [4972] DEBUG - Completed: [172.16.0.116:46255] 200 GET /video/:/transcode/universal/start.m3u8?hasMDE=1&path=%2Flibrary%2Fmetadata%2F9211&mediaIndex=0&partIndex=0&protocol=hls&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&session=we2a93or3900yxpchnpofuho&subtitles=burn&Accept-Language=en (10 live) GZIP 47ms 475 bytes (pipelined: 1)
Aug 30, 2018 22:38:05.897 [2276] DEBUG - Request: [172.16.0.116:46256 (Subnet)] GET /video/:/transcode/universal/session/we2a93or3900yxpchnpofuho/base/index.m3u8 (11 live) GZIP Signed-in
Aug 30, 2018 22:38:05.897 [2276] DEBUG - Found session GUID of we2a93or3900yxpchnpofuho in session start.
Aug 30, 2018 22:38:05.898 [2276] DEBUG - Starting a transcode session we2a93or3900yxpchnpofuho at offset -1.0 (state=3)
Aug 30, 2018 22:38:05.903 [2276] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Aug 30, 2018 22:38:05.904 [2276] DEBUG - [Universal] Using local file path instead of URL: \172.16.0.150\TVShows\Movies\Star Wars\Star Wars Episode VII The Force Awakens (2015) UHD 4K Upscaled x264 AC3 Soup.mkv
Aug 30, 2018 22:38:05.904 [2276] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/streams/2643?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Aug 30, 2018 22:38:05.916 [4968] DEBUG - Auth: authenticated user 1 as test@plexaccount.com
Aug 30, 2018 22:38:05.917 [4204] DEBUG - Request: [127.0.0.1:63577 (Loopback)] GET /library/streams/2643 (11 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:05.925 [4204] DEBUG - Content-Length of \172.16.0.150\TVShows\Movies\Star Wars\Star Wars Episode VII The Force Awakens (2015) UHD 4K Upscaled x264 AC3 Soup.idx is 459916.
Aug 30, 2018 22:38:05.962 [2276] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/streams/2643?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Aug 30, 2018 22:38:05.962 [4968] DEBUG - Completed: [127.0.0.1:63577] 200 GET /library/streams/2643 (11 live) GZIP 45ms 459916 bytes
Aug 30, 2018 22:38:05.980 [2276] DEBUG - Downloaded stream from [http://127.0.0.1:32400/library/streams/2643?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (codec: vobsub) to temporary file [C:\Users\Marc\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-we2a93or3900yxpchnpofuho-d88d9993-4f18-4714-b2cf-1da6ae291ceb\temp-0.idx]
Aug 30, 2018 22:38:05.981 [2276] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/streams/2643.sub?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Aug 30, 2018 22:38:05.992 [4968] DEBUG - Auth: authenticated user 1 as test@plexaccount.com
Aug 30, 2018 22:38:05.993 [9908] DEBUG - Request: [127.0.0.1:63580 (Loopback)] GET /library/streams/2643.sub (12 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:06.001 [9908] DEBUG - Content-Length of \172.16.0.150\TVShows\Movies\Star Wars\Star Wars Episode VII The Force Awakens (2015) UHD 4K Upscaled x264 AC3 Soup.sub is 128911285.
Aug 30, 2018 22:38:06.254 [2276] ERROR - Error issuing curl_easy_perform(handle): 23
Aug 30, 2018 22:38:06.254 [4968] DEBUG - Failed to stream media, client probably disconnected after 21692416 bytes: 10054 - An existing connection was forcibly closed by the remote host
Aug 30, 2018 22:38:06.255 [2276] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/streams/2643.sub?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Failed writing body (0 != 16384))
Aug 30, 2018 22:38:06.255 [4968] DEBUG - Completed: [127.0.0.1:63580] 200 GET /library/streams/2643.sub (12 live) GZIP 262ms 21692416 bytes
Aug 30, 2018 22:38:06.389 [2316] DEBUG - HTTP 200 response from GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08kftjd%2Fart-ninja-series-3-9-day-of-the-ancestor
Aug 30, 2018 22:38:06.389 [2316] DEBUG - [com.plexapp.system] HTTP reply status 200, with 2931 bytes of content.
Aug 30, 2018 22:38:06.391 [4968] DEBUG - Completed: [127.0.0.1:63573] 200 GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08kftjd%2Fart-ninja-series-3-9-day-of-the-ancestor (12 live) GZIP 1150ms 1878 bytes
Aug 30, 2018 22:38:06.406 [3948] DEBUG - Request: [127.0.0.1:63581 (Loopback)] GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb09pz4bn%2Fhh-gory-games-series-5-episode-6 (12 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:06.406 [3948] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb09pz4bn%2Fhh-gory-games-series-5-episode-6
Aug 30, 2018 22:38:06.407 [3948] DEBUG - HTTP requesting GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb09pz4bn%2Fhh-gory-games-series-5-episode-6
Aug 30, 2018 22:38:06.439 [2276] DEBUG - Also downloaded SUB file to temporary file [“C:\Users\Marc\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-we2a93or3900yxpchnpofuho-d88d9993-4f18-4714-b2cf-1da6ae291ceb\temp-0.sub”]
Aug 30, 2018 22:38:06.442 [2276] DEBUG - TPU: hardware transcoding: zero-copy support not present
Aug 30, 2018 22:38:06.443 [2276] DEBUG - TPU: hardware transcoding: final decoder: , final encoder:
Aug 30, 2018 22:38:06.445 [2276] DEBUG - Job running: EAE_ROOT=’\?\C:\Users\Marc\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=‘C:\Users\Marc\AppData\Local\Plex\ Media\ Server\Codecs\e944d3a-1322-windows-i386\’ X_PLEX_TOKEN=‘xxxxxxxxxxxxxxxxxxxx’ C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe -codec:0 h264 -i “\172.16.0.150\TVShows\Movies\Star Wars\Star Wars Episode VII The Force Awakens (2015) UHD 4K Upscaled x264 AC3 Soup.mkv” -i “C:\Users\Marc\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-we2a93or3900yxpchnpofuho-d88d9993-4f18-4714-b2cf-1da6ae291ceb\temp-0.idx” -filter_complex [1:0]scale=3840:2160[0];[0:0][0]overlay[1];[1]scale=w=3840:h=2160[2];[2]format=pix_fmts=yuv420p|nv12[3] -map [3] -codec:0 libx264 -crf:0 16 -maxrate:0 19986k -bufsize:0 39972k -r:0 23.975999999999999 -preset:0 veryfast -level:0 5.1 -x264opts:0 subme=0:me_range=4:rc_lookahead=10:me=hex:8x8dct=0:partitions=none -force_key_frames:0 expr:gte(t,0+n_forced*1) -map 0:1 -metadata:s:1 language=eng -codec:1 copy -copypriorss:1 0 -segment_format mpegts -f ssegment -individual_header_trailer 0 -segment_time 1 -segment_start_number 0 -segment_copyts 1 -segment_time_delta 0.0625 -segment_list http://127.0.0.1:32400/video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/seglist -segment_list_type csv -segment_list_size 2147483647 -segment_list_separate_stream_times 1 -max_delay 5000000 -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 media-%05d.ts -start_at_zero -copyts -vsync cfr -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress
Aug 30, 2018 22:38:06.450 [2276] DEBUG - Jobs: Starting child process with pid 9788
Aug 30, 2018 22:38:06.763 [7628] DEBUG - Request: [127.0.0.1:63584 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/stream?index=0&id=0&codec=h264&type=video (13 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:06.764 [4972] DEBUG - Completed: [127.0.0.1:63584] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/stream?index=0&id=0&codec=h264&type=video (13 live) 1ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:06.776 [4204] DEBUG - Request: [127.0.0.1:63585 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/stream?index=1&id=0&codec=ac3&type=audio (13 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:06.777 [4968] DEBUG - Completed: [127.0.0.1:63585] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/stream?index=1&id=0&codec=ac3&type=audio (13 live) 0ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:06.817 [4852] DEBUG - TrayIcon::WindowProc received DisplayIcon timer message
Aug 30, 2018 22:38:06.817 [4852] WARN - Shell_NotifyIcon(NIM_ADD) failed: 0x80004005
Aug 30, 2018 22:38:06.928 [7628] DEBUG - Request: [127.0.0.1:63587 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=Main&width=3840&height=2160&interlaced=0&level=51&frameRate=23.976&disp_default=1 (13 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:06.929 [4968] DEBUG - Completed: [127.0.0.1:63587] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=Main&width=3840&height=2160&interlaced=0&level=51&frameRate=23.976&disp_default=1 (13 live) 1ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:06.941 [2316] DEBUG - Request: [127.0.0.1:63588 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=1&id=0&codec=ac3&type=audio&bitrate=640000&language=eng&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (13 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:06.942 [4968] DEBUG - Completed: [127.0.0.1:63588] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=1&id=0&codec=ac3&type=audio&bitrate=640000&language=eng&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (13 live) 0ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:06.992 [4204] DEBUG - Request: [127.0.0.1:63591 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=0&id=0&codec=dvd_subtitle&type=subtitle&language=en&disp_default=1 (13 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:06.993 [4968] DEBUG - Completed: [127.0.0.1:63591] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=0&id=0&codec=dvd_subtitle&type=subtitle&language=en&disp_default=1 (13 live) 0ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.004 [7628] DEBUG - Request: [127.0.0.1:63592 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=1&id=1&codec=dvd_subtitle&type=subtitle&language=fr (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.005 [4972] DEBUG - Completed: [127.0.0.1:63592] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=1&id=1&codec=dvd_subtitle&type=subtitle&language=fr (14 live) 1ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.016 [2316] DEBUG - Request: [127.0.0.1:63593 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=2&id=2&codec=dvd_subtitle&type=subtitle&language=nl (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.016 [4972] DEBUG - Completed: [127.0.0.1:63593] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=2&id=2&codec=dvd_subtitle&type=subtitle&language=nl (14 live) 0ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.027 [4204] DEBUG - Request: [127.0.0.1:63594 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=3&id=3&codec=dvd_subtitle&type=subtitle&language=ar (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.028 [4968] DEBUG - Completed: [127.0.0.1:63594] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=3&id=3&codec=dvd_subtitle&type=subtitle&language=ar (14 live) 0ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.040 [9908] DEBUG - Request: [127.0.0.1:63595 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=4&id=4&codec=dvd_subtitle&type=subtitle&language=da (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.041 [4972] DEBUG - Completed: [127.0.0.1:63595] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=4&id=4&codec=dvd_subtitle&type=subtitle&language=da (14 live) 1ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.053 [2316] DEBUG - Request: [127.0.0.1:63596 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=5&id=5&codec=dvd_subtitle&type=subtitle&language=fi (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.053 [4968] DEBUG - Completed: [127.0.0.1:63596] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=5&id=5&codec=dvd_subtitle&type=subtitle&language=fi (14 live) 0ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.065 [4204] DEBUG - Request: [127.0.0.1:63597 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=6&id=6&codec=dvd_subtitle&type=subtitle&language=no (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.066 [4968] DEBUG - Completed: [127.0.0.1:63597] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=6&id=6&codec=dvd_subtitle&type=subtitle&language=no (14 live) 1ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.077 [7628] DEBUG - Request: [127.0.0.1:63598 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=7&id=7&codec=dvd_subtitle&type=subtitle&language=sv (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.078 [4972] DEBUG - Completed: [127.0.0.1:63598] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=7&id=7&codec=dvd_subtitle&type=subtitle&language=sv (14 live) 1ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.089 [9908] DEBUG - Request: [127.0.0.1:63599 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=8&id=8&codec=dvd_subtitle&type=subtitle&language=hi (14 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.090 [4968] DEBUG - Completed: [127.0.0.1:63599] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress/streamDetail?index=8&id=8&codec=dvd_subtitle&type=subtitle&language=hi (14 live) 1ms 227 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.107 [2316] DEBUG - Request: [127.0.0.1:63600 (Loopback)] PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress?duration=8286.720000 (15 live) Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.108 [2276] DEBUG - Started session successfully: we2a93or3900yxpchnpofuho
Aug 30, 2018 22:38:07.109 [2276] DEBUG - HLS: Building an M3U8 for 8287 total seconds with 1 seconds/segment, target duration of 1.
Aug 30, 2018 22:38:07.110 [4968] DEBUG - Completed: [127.0.0.1:63600] 206 PUT /video/:/transcode/session/we2a93or3900yxpchnpofuho/d88d9993-4f18-4714-b2cf-1da6ae291ceb/progress?duration=8286.720000 (15 live) 2ms 326 bytes (range: bytes=0-)
Aug 30, 2018 22:38:07.128 [4972] DEBUG - Completed: [172.16.0.116:46256] 200 GET /video/:/transcode/universal/session/we2a93or3900yxpchnpofuho/base/index.m3u8 (15 live) GZIP 1231ms 22038 bytes (pipelined: 1)
Aug 30, 2018 22:38:07.139 [4972] DEBUG - Auth: authenticated user 1 as test@plexaccount.com
Aug 30, 2018 22:38:07.140 [4204] DEBUG - Request: [172.16.0.114:50591 (Subnet)] GET /status/sessions (15 live) TLS GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.141 [4204] DEBUG - [Now] Adding 0 sessions.
Aug 30, 2018 22:38:07.146 [4968] DEBUG - Completed: [172.16.0.114:50591] 200 GET /status/sessions (15 live) TLS GZIP 5ms 470 bytes (pipelined: 1)
Aug 30, 2018 22:38:07.460 [3948] DEBUG - HTTP 200 response from GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb09pz4bn%2Fhh-gory-games-series-5-episode-6
Aug 30, 2018 22:38:07.461 [3948] DEBUG - [com.plexapp.system] HTTP reply status 200, with 2762 bytes of content.
Aug 30, 2018 22:38:07.461 [4968] DEBUG - Completed: [127.0.0.1:63581] 200 GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb09pz4bn%2Fhh-gory-games-series-5-episode-6 (15 live) GZIP 1056ms 1794 bytes
Aug 30, 2018 22:38:07.476 [9908] DEBUG - Request: [127.0.0.1:63601 (Loopback)] GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08gd0lp%2Fremotely-funny-series-1-8-poland (16 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:07.477 [9908] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08gd0lp%2Fremotely-funny-series-1-8-poland
Aug 30, 2018 22:38:07.477 [9908] DEBUG - HTTP requesting GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08gd0lp%2Fremotely-funny-series-1-8-poland
Aug 30, 2018 22:38:07.657 [4204] DEBUG - Transcoder: session we2a93or3900yxpchnpofuho indicated fallback to software decoding
Aug 30, 2018 22:38:08.381 [9908] DEBUG - HTTP 200 response from GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08gd0lp%2Fremotely-funny-series-1-8-poland
Aug 30, 2018 22:38:08.382 [9908] DEBUG - [com.plexapp.system] HTTP reply status 200, with 3108 bytes of content.
Aug 30, 2018 22:38:08.383 [4968] DEBUG - Completed: [127.0.0.1:63601] 200 GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08gd0lp%2Fremotely-funny-series-1-8-poland (16 live) GZIP 906ms 1974 bytes
Aug 30, 2018 22:38:08.386 [2276] DEBUG - Request: [127.0.0.1:63611 (Loopback)] GET /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08d6wk6%2Fall-over-the-place-asia-15-the-songs (17 live) GZIP Signed-in Token (test@plexaccount.com)
Aug 30, 2018 22:38:08.387 [2276] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08d6wk6%2Fall-over-the-place-asia-15-the-songs
Aug 30, 2018 22:38:08.387 [2276] DEBUG - HTTP requesting GET http://127.0.0.1:49682/system/services/url/lookup?url=http%3A%2F%2Fwww.bbc.co.uk%2Fiplayer%2Fepisode%2Fb08d6wk6%2Fall-over-the-place-asia-15-the-songs
Aug 30, 2018 22:38:08.878 [9908] DEBUG - Transcoder segment range: 0 - 0
Aug 30, 2018 22:38:09.462 [7628] DEBUG - Transcoder segment range: 0 - 1
Any clue on what is wrong in my settings?
My network is running at 1 Gbps with Cat 7 cables and I have a dedicated HP Procurve switch in the middle between the Plex computer and the TV.
Thanks
