Plex transcoding no longer works

Plex Media Server
1

Server Version#: v1.25.2.5319
Player Version#: any (android, web, local, remote, whatever)

Running on an Intel nuc with Ubuntu 20.04, can’t watch anything that requires transcoding. No error messages, just an infinite loading spinner. Tried enabling or disabling hardware acceleration, doesn’t make a difference.

Any suggestions where I might look for the cause? Can’t find anything related in the logs. Used to work fine with a previous version, not sure which exactly…

Already tried complete re-install and using docker instead of deb package on the host, to no avail.

2

can you attach a DEBUG log ZIP file which captures the start of playback?

3

Here’s a bit of the debug log that should capture the start of playback

Summary

Jan 09, 2022 20:30:32.663 [0x7f8c975d3b38] Warning — [Transcode/mxzz0a3qvkde69yr7xvkqf7n] Timed out waiting for segment.
Jan 09, 2022 20:30:32.663 [0x7f8c98f00b38] Debug — Completed: [192.168.1.7:54799] 404 GET /video/:/transcode/universal/session/mxzz0a3qvkde69yr7xvkqf7n/0/0.m4s (58 live) TLS GZIP 120460ms 496 bytes (pipelined: 1)
Jan 09, 2022 20:30:32.686 [0x7f8c98f00b38] Debug — Completed: [192.168.1.7:54882] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (57 live) TLS GZIP 20004ms 5 bytes (pipelined: 3)
Jan 09, 2022 20:30:32.697 [0x7f8c95ab6b38] Debug — Request: [192.168.1.7:54882 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (57 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:32.698 [0x7f8c95ab6b38] Debug — Content-Length is -1 (of total: -1).
Jan 09, 2022 20:30:37.184 [0x7f8c96c6fb38] Warning — [Transcode/mxzz0a3qvkde69yr7xvkqf7n] Timed out waiting for segment.
Jan 09, 2022 20:30:37.185 [0x7f8c98f23b38] Debug — Completed: [192.168.1.7:54803] 404 GET /video/:/transcode/universal/session/mxzz0a3qvkde69yr7xvkqf7n/1/0.m4s (57 live) TLS GZIP 120536ms 496 bytes (pipelined: 1)
Jan 09, 2022 20:30:37.336 [0x7f8c96458b38] Debug — [Transcode/mxzz0a3qvkde69yr7xvkqf7n] Exists("/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-mxzz0a3qvkde69yr7xvkqf7n-b67b20b0-af20-43e1-9cb7-bc6c61571288/init-stream0.m4s") => 1
Jan 09, 2022 20:30:37.336 [0x7f8c96458b38] Debug — Content-Length of /config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-mxzz0a3qvkde69yr7xvkqf7n-b67b20b0-af20-43e1-9cb7-bc6c61571288/init-stream0.m4s is 830 (of total: 830).
Jan 09, 2022 20:30:37.337 [0x7f8c98f00b38] Debug — Completed: [192.168.1.7:54802] 200 GET /video/:/transcode/universal/session/mxzz0a3qvkde69yr7xvkqf7n/0/header (56 live) TLS GZIP 120688ms 830 bytes (pipelined: 1)
Jan 09, 2022 20:30:38.076 [0x7f8c96702b38] Debug — Request: [192.168.1.7:54949 (Subnet)] POST /playQueues?type=video&continuous=1&uri=server%3A%2F%2Fad6bbea2786a579ad70731249525900674d3ef74%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F1573&repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (55 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:38.076 [0x7f8c96702b38] Debug — PlayQueue: Converted ‘server://ad6bbea2786a579ad70731249525900674d3ef74/com.plexapp.plugins.library/library/metadata/1573’ to ‘library://x/item/%2Flibrary%2Fmetadata%2F1573’
Jan 09, 2022 20:30:38.077 [0x7f8c96702b38] Debug — PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Jan 09, 2022 20:30:38.267 [0x7f8c96702b38] Debug — PlayQueue: Start index: 0 End index: 3 Count: 4
Jan 09, 2022 20:30:38.267 [0x7f8c96702b38] Debug — PlayQueue: 4 generated IDs compressed down to a 31 byte blob.
Jan 09, 2022 20:30:38.294 [0x7f8c96702b38] Debug — PlayQueue: Added 4 items in 0.0 sec.
Jan 09, 2022 20:30:38.313 [0x7f8c96702b38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.314 [0x7f8c96702b38] Debug — Selecting best audio stream for part ID 2786 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.314 [0x7f8c96702b38] Debug — Audio Stream: 8957, Subtitle Stream: 8958
Jan 09, 2022 20:30:38.315 [0x7f8c96702b38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.316 [0x7f8c96702b38] Debug — Selecting best audio stream for part ID 2784 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.316 [0x7f8c96702b38] Debug — Audio Stream: 8932, Subtitle Stream: 8933
Jan 09, 2022 20:30:38.318 [0x7f8c96702b38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.318 [0x7f8c96702b38] Debug — Selecting best audio stream for part ID 2787 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.318 [0x7f8c96702b38] Debug — Audio Stream: 8951, Subtitle Stream: -1
Jan 09, 2022 20:30:38.319 [0x7f8c96702b38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.320 [0x7f8c96702b38] Debug — Selecting best audio stream for part ID 2789 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.320 [0x7f8c96702b38] Debug — Audio Stream: 8966, Subtitle Stream: -1
Jan 09, 2022 20:30:38.320 [0x7f8c96702b38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.321 [0x7f8c96702b38] Debug — Selecting best audio stream for part ID 2780 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.321 [0x7f8c96702b38] Debug — Audio Stream: 8917, Subtitle Stream: -1
Jan 09, 2022 20:30:38.336 [0x7f8c96702b38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.339 [0x7f8c96702b38] Debug — Selecting best audio stream for part ID 2781 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.340 [0x7f8c96702b38] Debug — Audio Stream: 8923, Subtitle Stream: -1
Jan 09, 2022 20:30:38.346 [0x7f8c98f23b38] Debug — Completed: [192.168.1.7:54949] 200 POST /playQueues?type=video&continuous=1&uri=server%3A%2F%2Fad6bbea2786a579ad70731249525900674d3ef74%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F1573&repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (55 live) TLS GZIP 269ms 4653 bytes (pipelined: 8)
Jan 09, 2022 20:30:38.407 [0x7f8c96d1db38] Debug — Request: [192.168.1.7:54949 (Subnet)] GET /library/metadata/1573?Accept-Language=en&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&includeExternalMedia=1&asyncAugmentMetadata=1&checkFiles=1 (55 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:38.431 [0x7f8c96d1db38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.431 [0x7f8c96d1db38] Debug — Selecting best audio stream for part ID 2786 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.431 [0x7f8c96d1db38] Debug — Audio Stream: 8957, Subtitle Stream: 8958
Jan 09, 2022 20:30:38.432 [0x7f8c96d1db38] Debug — We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.432 [0x7f8c96d1db38] Debug — Selecting best audio stream for part ID 2784 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.432 [0x7f8c96d1db38] Debug — Audio Stream: 8932, Subtitle Stream: 8933
Jan 09, 2022 20:30:38.435 [0x7f8c98f23b38] Debug — Completed: [192.168.1.7:54949] 200 GET /library/metadata/1573?Accept-Language=en&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&includeExternalMedia=1&asyncAugmentMetadata=1&checkFiles=1 (55 live) TLS GZIP 31ms 2857 bytes (pipelined: 9)
Jan 09, 2022 20:30:38.452 [0x7f8c96578b38] Debug — Request: [192.168.1.7:54949 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1573&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ucp43ihz9tt961jx1f958p8t&subtitles=burn&Accept-Language=en (55 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:38.452 [0x7f8c96578b38] Debug — [Transcode] Found session GUID of ucp43ihz9tt961jx1f958p8t in session start.
Jan 09, 2022 20:30:38.453 [0x7f8c96578b38] Debug — [Transcode] TranscodeUniversalRequest: using augmented profile Web
Jan 09, 2022 20:30:38.453 [0x7f8c96578b38] Debug — [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/1573?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=trhonhn6d3g520hnstvxejro
Jan 09, 2022 20:30:38.464 [0x7f8c96578b38] Debug — [Transcode] We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.464 [0x7f8c96578b38] Debug — [Transcode] Selecting best audio stream for part ID 2786 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.464 [0x7f8c96578b38] Debug — [Transcode] Audio Stream: 8957, Subtitle Stream: 8958
Jan 09, 2022 20:30:38.465 [0x7f8c96578b38] Debug — [Transcode] We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.465 [0x7f8c96578b38] Debug — [Transcode] Selecting best audio stream for part ID 2784 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.465 [0x7f8c96578b38] Debug — [Transcode] Audio Stream: 8932, Subtitle Stream: 8933
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] Scaled up video bitrate to 12148Kbps based on 1.500000x fudge factor.
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] MDE: Selected protocol dash; container: mp4
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] MDE: analyzing media item 2777
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] MDE: E4 - Insult: Direct Play is disabled
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] MDE: E4 - Insult: media must be transcoded in order to use the dash protocol
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] MDE: E4 - Insult: selected subtitle cannot be converted to a compatible format, burning into video stream
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] MDE: E4 - Insult: avoiding video remux due to burned subtitle stream
Jan 09, 2022 20:30:38.467 [0x7f8c96578b38] Debug — [Transcode] MDE: E4 - Insult: no remuxable profile found, so video stream will be transcoded
Jan 09, 2022 20:30:38.468 [0x7f8c96578b38] Debug — [Transcode] MDE: Cannot direct stream video stream due to profile or setting limitations
Jan 09, 2022 20:30:38.468 [0x7f8c96578b38] Debug — [Transcode] Scaled up video bitrate to 12148Kbps based on 1.500000x fudge factor.
Jan 09, 2022 20:30:38.468 [0x7f8c96578b38] Debug — [Transcode] MDE: Demon Slayer: Kimetsu no Yaiba - S2 E4 - Insult: selected media 0 / 2777
Jan 09, 2022 20:30:38.468 [0x7f8c96578b38] Debug — [Transcode] Streaming Resource: Adding session 0x7f8c98a70c58:trhonhn6d3g520hnstvxejro which is using transcoder slot. Used slots is now 4
Jan 09, 2022 20:30:38.468 [0x7f8c96578b38] Debug — [Transcode] Streaming Resource: Added session 0x7f8c98a70c58:trhonhn6d3g520hnstvxejro
Jan 09, 2022 20:30:38.468 [0x7f8c96578b38] Debug — [Transcode] Streaming Resource: Reached Decision id=1573 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=2777 part=(id=2786 decision=transcode container=mp4 protocol=dash streams=(Video=(id=8956 decision=transcode bitrate=12148 encoder=libx264 width=1920 height=1080) Audio=(id=8957 decision=copy channels=2 rate=44100) Subtitle=(id=8958 decision=burn languageCode=eng location=embedded))))
Jan 09, 2022 20:30:38.471 [0x7f8c98f23b38] Debug — Completed: [192.168.1.7:54949] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1573&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ucp43ihz9tt961jx1f958p8t&subtitles=burn&Accept-Language=en (55 live) TLS GZIP 18ms 2453 bytes (pipelined: 10)
Jan 09, 2022 20:30:38.512 [0x7f8c975d3b38] Debug — Request: [192.168.1.4:33272 (WAN)] GET /web/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js (55 live) GZIP Signed-in
Jan 09, 2022 20:30:38.512 [0x7f8c96768b38] Debug — Request: [192.168.1.4:33266 (WAN)] GET /web/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js (55 live) GZIP Signed-in
Jan 09, 2022 20:30:38.512 [0x7f8c975d3b38] Debug — Final path: “/usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js”
Jan 09, 2022 20:30:38.512 [0x7f8c96768b38] Debug — Final path: “/usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js”
Jan 09, 2022 20:30:38.513 [0x7f8c96768b38] Debug — Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js is 379365 (of total: 379365).
Jan 09, 2022 20:30:38.514 [0x7f8c975d3b38] Debug — Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js is 7332 (of total: 7332).
Jan 09, 2022 20:30:38.515 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33272] 200 GET /web/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js (55 live) GZIP 3ms 7332 bytes (pipelined: 4)
Jan 09, 2022 20:30:38.518 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33266] 200 GET /web/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js (55 live) GZIP 6ms 379365 bytes (pipelined: 3)
Jan 09, 2022 20:30:38.573 [0x7f8c96799b38] Debug — Request: [192.168.1.7:54949 (Subnet)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1573&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ucp43ihz9tt961jx1f958p8t&subtitles=burn&Accept-Language=en (55 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:38.573 [0x7f8c96799b38] Debug — [Transcode] Found session GUID of ucp43ihz9tt961jx1f958p8t in session start.
Jan 09, 2022 20:30:38.574 [0x7f8c96799b38] Debug — [Transcode] TranscodeUniversalRequest: using augmented profile Web
Jan 09, 2022 20:30:38.574 [0x7f8c96799b38] Debug — [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/1573?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=trhonhn6d3g520hnstvxejro
Jan 09, 2022 20:30:38.585 [0x7f8c96799b38] Debug — [Transcode] We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.585 [0x7f8c96799b38] Debug — [Transcode] Selecting best audio stream for part ID 2786 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.586 [0x7f8c96799b38] Debug — [Transcode] Audio Stream: 8957, Subtitle Stream: 8958
Jan 09, 2022 20:30:38.586 [0x7f8c96799b38] Debug — [Transcode] We’re going to try to auto-select an audio stream for account 1.
Jan 09, 2022 20:30:38.587 [0x7f8c96799b38] Debug — [Transcode] Selecting best audio stream for part ID 2784 (autoselect: 1 language: en)
Jan 09, 2022 20:30:38.587 [0x7f8c96799b38] Debug — [Transcode] Audio Stream: 8932, Subtitle Stream: 8933
Jan 09, 2022 20:30:38.589 [0x7f8c96799b38] Debug — [Transcode] Found session GUID of ucp43ihz9tt961jx1f958p8t in session start.
Jan 09, 2022 20:30:38.589 [0x7f8c96799b38] Debug — [Transcode] Cleaning directory for session ucp43ihz9tt961jx1f958p8t ()
Jan 09, 2022 20:30:38.590 [0x7f8c96799b38] Debug — [Transcode] Starting a transcode session ucp43ihz9tt961jx1f958p8t at offset -1.0 (state=3)
Jan 09, 2022 20:30:38.590 [0x7f8c96799b38] Debug — [Transcode] [Universal] Using local file path instead of URL: /path/to/file
Jan 09, 2022 20:30:38.591 [0x7f8c96799b38] Debug — [Transcode/JobRunner] Job running: FFMPEG_EXTERNAL_LIBS=’/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/fb9eae3-4185-linux-x86_64/’ XDG_CACHE_HOME=’/config/Library/Application Support/Plex Media Server/Cache’ XDG_DATA_HOME=’/usr/lib/plexmediaserver/Resources’ X_PLEX_TOKEN=‘xxxxxxxxxxxxxxxxxxxx’ ‘/usr/lib/plexmediaserver/Plex Transcoder’ ‘-codec:0’ ‘h264’ ‘-analyzeduration’ ‘20000000’ ‘-probesize’ ‘20000000’ ‘-i’ ‘/path/to/file’ ‘-map_inlineass’ ‘0:2’ ‘-filter_complex’ ‘[0:0]scale=w=1920:h=1080:force_divisible_by=4[0];[0]format=pix_fmts=yuv420p|nv12[1];[1]inlineass=font_scale=1.000000:font_path=/usr/lib/plexmediaserver/Resources/Fonts/NotoSans-Medium.otf:fontconfig_file=/usr/lib/plexmediaserver/Resources/fonts.conf:language=en[2]’ ‘-map’ ‘[2]’ ‘-codec:0’ ‘libx264’ ‘-crf:0’ ‘16’ ‘-maxrate:0’ ‘12148k’ ‘-bufsize:0’ ‘24296k’ ‘-r:0’ ‘23.975999999999999’ ‘-preset:0’ ‘veryfast’ ‘-x264opts:0’ ‘subme=0:me_range=4:rc_lookahead=10:me=hex:8x8dct=0:partitions=none’ ‘-force_key_frames:0’ ‘expr:gte(t,n_forced*1)’ ‘-map’ ‘0:1’ ‘-metadata:s:1’ ‘language=jpn’ ‘-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/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/manifest?X-Plex-Http-Pipeline=infinite’ ‘-avoid_negative_ts’ ‘disabled’ ‘-map_metadata’ ‘-1’ ‘-map_chapters’ ‘-1’ ‘dash’ ‘-map’ ‘0:2’ ‘-f’ ‘null’ ‘-codec’ ‘ass’ ‘nullfile’ ‘-start_at_zero’ ‘-copyts’ ‘-vsync’ ‘cfr’ ‘-y’ ‘-nostats’ ‘-loglevel’ ‘quiet’ ‘-loglevel_plex’ ‘error’ ‘-progressurl’ ‘http://127.0.0.1:32400/video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress’
Jan 09, 2022 20:30:38.593 [0x7f8c96799b38] Debug — [Transcode/JobRunner] Jobs: Starting child process with pid 8831
Jan 09, 2022 20:30:38.611 [0x7f8c96802b38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=startup (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.611 [0x7f8c98f00b38] Debug — Completed: [192.168.1.4:33660] 204 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=startup (56 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Jan 09, 2022 20:30:38.619 [0x7f8c96825b38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=startup (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.620 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33660] 204 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=startup (56 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Jan 09, 2022 20:30:38.621 [0x7f8c968ebb38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=opening (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.621 [0x7f8c98f00b38] Debug — Completed: [192.168.1.4:33660] 204 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=opening (56 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Jan 09, 2022 20:30:38.622 [0x7f8c95b11b38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=opened (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.623 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33660] 204 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?status=opened (56 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-)
Jan 09, 2022 20:30:38.626 [0x7f8c95f06b38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/stream?index=0&id=0&codec=h264&type=video (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.626 [0x7f8c98f00b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/stream?index=0&id=0&codec=h264&type=video (56 live) 3ms 195 bytes (pipelined: 5) (range: bytes=0-)
Jan 09, 2022 20:30:38.783 [0x7f8c96a9db38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/stream?index=1&id=0&codec=aac&type=audio&profile=LC (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.784 [0x7f8c98f00b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/stream?index=1&id=0&codec=aac&type=audio&profile=LC (56 live) 0ms 195 bytes (pipelined: 6) (range: bytes=0-)
Jan 09, 2022 20:30:38.786 [0x7f8c96c6fb38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&width=1920&height=1080&interlaced=0&sar=1:1&level=40&frameRate=23.976&disp_default=1 (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.786 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&width=1920&height=1080&interlaced=0&sar=1:1&level=40&frameRate=23.976&disp_default=1 (56 live) 0ms 195 bytes (pipelined: 7) (range: bytes=0-)
Jan 09, 2022 20:30:38.788 [0x7f8c9649eb38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=1&id=0&codec=aac&type=audio&profile=LC&language=jpn&channels=2&layout=stereo&sampleRate=44100&disp_default=1 (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.788 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=1&id=0&codec=aac&type=audio&profile=LC&language=jpn&channels=2&layout=stereo&sampleRate=44100&disp_default=1 (56 live) 0ms 195 bytes (pipelined: 8) (range: bytes=0-)
Jan 09, 2022 20:30:38.789 [0x7f8c967dfb38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=2&id=0&codec=ass&type=subtitle&language=eng&disp_default=1&disp_forced=1 (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.789 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=2&id=0&codec=ass&type=subtitle&language=eng&disp_default=1&disp_forced=1 (56 live) 1ms 195 bytes (pipelined: 9) (range: bytes=0-)
Jan 09, 2022 20:30:38.791 [0x7f8c9714cb38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=3&id=0&codec=ass&type=subtitle&language=spa (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.791 [0x7f8c98f00b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=3&id=0&codec=ass&type=subtitle&language=spa (56 live) 1ms 195 bytes (pipelined: 10) (range: bytes=0-)
Jan 09, 2022 20:30:38.793 [0x7f8c96d91b38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=4&id=0&codec=ass&type=subtitle&language=por (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.794 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=4&id=0&codec=ass&type=subtitle&language=por (56 live) 0ms 195 bytes (pipelined: 11) (range: bytes=0-)
Jan 09, 2022 20:30:38.798 [0x7f8c96458b38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=5&id=0&codec=ass&type=subtitle&language=spa (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.799 [0x7f8c98f23b38] Debug — Completed: [192.168.1.4:33660] 200 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress/streamDetail?index=5&id=0&codec=ass&type=subtitle&language=spa (56 live) 4ms 195 bytes (pipelined: 12) (range: bytes=0-)
Jan 09, 2022 20:30:38.803 [0x7f8c96702b38] Debug — Request: [192.168.1.4:33660 (WAN)] PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?duration=1421.740000 (56 live) Signed-in Token () (range: bytes=0-)
Jan 09, 2022 20:30:38.803 [0x7f8c98f00b38] Debug — Completed: [192.168.1.4:33660] 204 PUT /video/:/transcode/session/ucp43ihz9tt961jx1f958p8t/3fdc642a-f339-44d0-a0b2-b76ce2c5ec93/progress?duration=1421.740000 (56 live) 0ms 203 bytes (pipelined: 13) (range: bytes=0-)
Jan 09, 2022 20:30:38.803 [0x7f8c96799b38] Debug — [Transcode] Started session successfully: ucp43ihz9tt961jx1f958p8t
Jan 09, 2022 20:30:38.805 [0x7f8c98f00b38] Debug — Completed: [192.168.1.7:54949] 200 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1573&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ucp43ihz9tt961jx1f958p8t&subtitles=burn&Accept-Language=en (56 live) TLS GZIP 232ms 1081 bytes (pipelined: 11)
Jan 09, 2022 20:30:38.808 [0x7f8c968c8b38] Debug — Request: [192.168.1.7:54949 (Subnet)] GET /status/sessions (56 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:38.808 [0x7f8c968c8b38] Debug — [Now] Adding 1 sessions.
Jan 09, 2022 20:30:38.808 [0x7f8c95c41b38] Debug — Request: [192.168.1.7:54907 (Subnet)] GET /status/sessions (56 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:38.808 [0x7f8c95c41b38] Debug — [Now] Adding 1 sessions.
Jan 09, 2022 20:30:38.812 [0x7f8c98f00b38] Debug — Completed: [192.168.1.7:54949] 200 GET /status/sessions (56 live) TLS GZIP 4ms 2882 bytes (pipelined: 12)
Jan 09, 2022 20:30:38.812 [0x7f8c98f00b38] Debug — Completed: [192.168.1.7:54907] 200 GET /status/sessions (56 live) TLS GZIP 4ms 2882 bytes (pipelined: 12)
Jan 09, 2022 20:30:38.812 [0x7f8c95b34b38] Debug — Request: [192.168.1.7:54907 (Subnet)] GET /status/sessions (56 live) TLS GZIP Signed-in Token ()
Jan 09, 2022 20:30:38.812 [0x7f8c95b34b38] Debug — [Now] Adding 1 sessions.
Jan 09, 2022 20:30:38.819 [0x7f8c98f23b38] Debug — Completed: [192.168.1.7:54907] 200 GET /status/sessions (56 live) TLS GZIP 7ms 2882 bytes (pipelined: 13)
Jan 09, 2022 20:30:38.824 [0x7f8c96a1cb38] Debug — Request: [192.168.1.7:54907 (Subnet)] GET /video/:/transcode/universal/session/ucp43ihz9tt961jx1f958p8t/1/header (56 live) TLS GZIP Signed-in
Jan 09, 2022 20:30:38.824 [0x7f8c96a3fb38] Debug — Request: [192.168.1.7:54949 (Subnet)] GET /video/:/transcode/universal/session/ucp43ihz9tt961jx1f958p8t/1/0.m4s (56 live) TLS GZIP Signed-in
Jan 09, 2022 20:30:38.824 [0x7f8c96a3fb38] Debug — [Transcode/ucp43ihz9tt961jx1f958p8t] Asked for segment 0 from session.
Jan 09, 2022 20:30:38.826 [0x7f8c95ab6b38] Debug — Request: [192.168.1.7:54952 (Subnet)] GET /video/:/transcode/universal/session/ucp43ihz9tt961jx1f958p8t/0/header (56 live) TLS GZIP Signed-in
Jan 09, 2022 20:30:38.827 [0x7f8c96768b38] Debug — Request: [192.168.1.7:54951 (Subnet)] GET /video/:/transcode/universal/session/ucp43ihz9tt961jx1f958p8t/0/0.m4s (56 live) TLS GZIP Signed-in
Jan 09, 2022 20:30:38.827 [0x7f8c96768b38] Debug — [Transcode/ucp43ihz9tt961jx1f958p8t] Asked for segment 0 from session.
Jan 09, 2022 20:30:39.032 [0x7f8c98f00b38] Debug — Request: didn’t get any data from [::ffff:192.168.1.4]:33248: End of file
Jan 09, 2022 20:30:39.121 [0x7f8c96825b38] Debug — [Transcode] Transcoder: session ucp43ihz9tt961jx1f958p8t indicated fallback to software decoding
Jan 09, 2022 20:30:39.182 [0x7f8c97275b38] Warning — [Transcode/fx3rsewxdk85owi7sh6p5vb0] Timed out waiting for segment.
Jan 09, 2022 20:30:39.183 [0x7f8c98f23b38] Debug — Completed: [192.168.1.7:54809] 404 GET /video/:/transcode/universal/session/fx3rsewxdk85owi7sh6p5vb0/1/0.m4s (37 live) TLS GZIP 120535ms 496 bytes (pipelined: 1)
Jan 09, 2022 20:30:39.285 [0x7f8c96bcfb38] Debug — [Transcode/fx3rsewxdk85owi7sh6p5vb0] Exists("/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-fx3rsewxdk85owi7sh6p5vb0-edbcadf5-2da8-4bf7-a590-51cbdc16a596/init-stream0.m4s") => 1
Jan 09, 2022 20:30:39.286 [0x7f8c96bcfb38] Debug — Content-Length of /config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-fx3rsewxdk85owi7sh6p5vb0-edbcadf5-2da8-4bf7-a590-51cbdc16a596/init-stream0.m4s is 830 (of total: 830).
Jan 09, 2022 20:30:39.288 [0x7f8c98f23b38] Debug — Completed: [192.168.1.7:54808] 200 GET /video/:/transcode/universal/session/fx3rsewxdk85owi7sh6p5vb0/0/header (35 live) TLS GZIP 120641ms 830 bytes (pipelined: 1)

Is there some way to view more detailed logs of the transcode process?

4

I have a Plex Livetime account. Is there any official support I can contact besides these forums?

5

Unfortunately, not. No real way to report a bug either.

6

When Chuck asked for logs he was asking for this:

  1. Go to Settings > Manage > Troubleshooting in Plex Web App
  2. Click the Download Logs button
  3. Save the resulting zip file and drag and drop into this thread.

Posting summaries isn’t that helpful and delays assistance.

7

May I please have the ZIP file?

It’s not possible to diagnose from a snippet posted in the forum.

Recreate the failure,
Wait 20 seconds
Download the logs
Attach the ZIP file.

8

Thanks for your reply. I started a playback that reproduces the failure, waited 20 seconds, downloaded the logs and attached the zipfile. Hope you can help me out
Plex Media Server Logs_2022-01-12_16-48-00.zip (2.9 MB)

9

Is anyone able to help? Is there any way to involve someone from Plex?

10

@ddienlin

Workng part time here & sick.

The transcoder is not liking the media it’s playing (HTTP 204 == No content being sent)

Jan 12, 2022 16:47:22.243 [0x7f7e9e0e0b38] DEBUG - [Transcode] Selecting best audio stream for part ID 836 (autoselect: 1 language: en)
Jan 12, 2022 16:47:22.243 [0x7f7e9e0e0b38] DEBUG - [Transcode] Audio Stream: 3784, Subtitle Stream: -1
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] MDE: Selected protocol dash; container: mp4
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] MDE: analyzing media item 836
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] MDE: E4 - The Perfect Opportunity: Direct Play is disabled
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] MDE: E4 - The Perfect Opportunity: media must be transcoded in order to use the dash protocol
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] MDE: E4 - The Perfect Opportunity: no direct play video profile exists for http/mkv/h264
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] MDE: E4 - The Perfect Opportunity: no direct play video profile exists for http/mkv/h264/aac
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] MDE: The Chosen - S2 E4 - The Perfect Opportunity: selected media 0 / 836
Jan 12, 2022 16:47:22.244 [0x7f7e9e0e0b38] DEBUG - [Transcode] Streaming Resource: Added session 0x7f7e9de412f8:nebbyahfeniddn991wqphgcc
Jan 12, 2022 16:47:22.245 [0x7f7e9e0e0b38] DEBUG - [Transcode] Streaming Resource: Reached Decision id=797 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=836 part=(id=836 decision=transcode container=mp4 protocol=dash streams=(Video=(id=3783 decision=copy width=1920 height=1080) Audio=(id=3784 decision=copy channels=2 rate=48000))))
Jan 12, 2022 16:47:22.246 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.7:53863] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F797&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=h97nji5k3ifxwwc4kv3m1da8&subtitles=burn&Accept-Language=en (12 live) TLS GZIP 13ms 1763 bytes (pipelined: 3)
Jan 12, 2022 16:47:22.271 [0x7f7e9db33b38] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 12, 2022 16:47:22.291 [0x7f7e9e09ab38] DEBUG - Request: [192.168.1.7:53815 (Subnet)] GET /web/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js (12 live) GZIP Signed-in
Jan 12, 2022 16:47:22.291 [0x7f7e9e0bdb38] DEBUG - Request: [192.168.1.7:53814 (Subnet)] GET /web/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js (12 live) GZIP Signed-in
Jan 12, 2022 16:47:22.291 [0x7f7e9e09ab38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js"
Jan 12, 2022 16:47:22.291 [0x7f7e9e0bdb38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js"
Jan 12, 2022 16:47:22.291 [0x7f7e9e09ab38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js is 379365 (of total: 379365).
Jan 12, 2022 16:47:22.291 [0x7f7e9e0bdb38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-c43dc0277/WebClient.bundle/Contents/Resources/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js is 7332 (of total: 7332).
Jan 12, 2022 16:47:22.291 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.7:53814] 200 GET /web/js/chunk-686-e6ace0349b6ea8a7666f-plex-4.66.1.19836-799dc6f.js (12 live) GZIP 1ms 7332 bytes (pipelined: 9)
Jan 12, 2022 16:47:22.296 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.7:53815] 200 GET /web/js/chunk-123-ff63466dc0e6aab4ae61-plex-4.66.1.19836-799dc6f.js (12 live) GZIP 5ms 379365 bytes (pipelined: 13)
Jan 12, 2022 16:47:22.338 [0x7f7e9e054b38] DEBUG - Request: [192.168.1.7:53863 (Subnet)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F797&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=h97nji5k3ifxwwc4kv3m1da8&subtitles=burn&Accept-Language=en (12 live) TLS GZIP Signed-in Token (ddienlin)
Jan 12, 2022 16:47:22.338 [0x7f7e9e054b38] DEBUG - [Transcode] Found session GUID of h97nji5k3ifxwwc4kv3m1da8 in session start.
Jan 12, 2022 16:47:22.339 [0x7f7e9e054b38] DEBUG - [Transcode] TranscodeUniversalRequest: using augmented profile Web
Jan 12, 2022 16:47:22.339 [0x7f7e9e054b38] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/797?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=nebbyahfeniddn991wqphgcc
Jan 12, 2022 16:47:22.347 [0x7f7e9e054b38] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jan 12, 2022 16:47:22.347 [0x7f7e9e054b38] DEBUG - [Transcode] Selecting best audio stream for part ID 836 (autoselect: 1 language: en)
Jan 12, 2022 16:47:22.347 [0x7f7e9e054b38] DEBUG - [Transcode] Audio Stream: 3784, Subtitle Stream: -1
Jan 12, 2022 16:47:22.348 [0x7f7e9e054b38] DEBUG - [Transcode] Found session GUID of h97nji5k3ifxwwc4kv3m1da8 in session start.
Jan 12, 2022 16:47:22.348 [0x7f7e9e054b38] DEBUG - [Transcode] Cleaning directory for session h97nji5k3ifxwwc4kv3m1da8 ()
Jan 12, 2022 16:47:22.348 [0x7f7e9e054b38] DEBUG - [Transcode] Starting a transcode session h97nji5k3ifxwwc4kv3m1da8 at offset -1.0 (state=3)
Jan 12, 2022 16:47:22.349 [0x7f7e9e054b38] DEBUG - [Transcode] [Universal] Using local file path instead of URL: /data/tv/The Chosen/Season 2/The.Chosen.S02E04.1080p.WEB-DL.AAC2.0.H.264-AlfaHD-AsRequested.mkv
Jan 12, 2022 16:47:22.349 [0x7f7e9e054b38] DEBUG - [Transcode/JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/fb9eae3-4185-linux-x86_64/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-codec:0' 'h264' '-ss' '0' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/data/tv/The Chosen/Season 2/The.Chosen.S02E04.1080p.WEB-DL.AAC2.0.H.264-AlfaHD-AsRequested.mkv' '-map' '0:0' '-codec:0' 'copy' '-map' '0:1' '-metadata:s:1' 'language=eng' '-codec:1' 'copy' '-f' 'dash' '-seg_duration' '5' '-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/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/manifest?X-Plex-Http-Pipeline=infinite' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress'
Jan 12, 2022 16:47:22.350 [0x7f7e9e054b38] DEBUG - [Transcode/JobRunner] Jobs: Starting child process with pid 514
Jan 12, 2022 16:47:22.355 [0x7f7e9e077b38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=startup (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.356 [0x7f7e9eceeb38] DEBUG - Completed: [192.168.1.4:51150] 204 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=startup (13 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Jan 12, 2022 16:47:22.359 [0x7f7e9e0e0b38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=startup (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.360 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.4:51150] 204 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=startup (13 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Jan 12, 2022 16:47:22.360 [0x7f7e9e0bdb38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=opening (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.361 [0x7f7e9eceeb38] DEBUG - Completed: [192.168.1.4:51150] 204 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=opening (13 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Jan 12, 2022 16:47:22.371 [0x7f7e9db33b38] DEBUG - HTTP/2.0 (0.1s) 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Jan 12, 2022 16:47:22.372 [0x7f7e9db33b38] DEBUG - Media Server: Tested all servers in 0.0 seconds.
Jan 12, 2022 16:47:22.379 [0x7f7e9e09ab38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=opened (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.379 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.4:51150] 204 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?status=opened (13 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-) 
Jan 12, 2022 16:47:22.379 [0x7f7e9e077b38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/stream?index=0&id=0&codec=h264&type=video (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.380 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.4:51150] 200 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/stream?index=0&id=0&codec=h264&type=video (13 live) 0ms 195 bytes (pipelined: 5) (range: bytes=0-) 
Jan 12, 2022 16:47:22.414 [0x7f7e9e0e0b38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/stream?index=1&id=0&codec=aac&type=audio&profile=LC (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.414 [0x7f7e9eceeb38] DEBUG - Completed: [192.168.1.4:51150] 200 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/stream?index=1&id=0&codec=aac&type=audio&profile=LC (13 live) 0ms 195 bytes (pipelined: 6) (range: bytes=0-) 
Jan 12, 2022 16:47:22.414 [0x7f7e9e0bdb38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&width=1920&height=1080&interlaced=0&sar=1:1&level=50&frameRate=23.976&disp_default=1 (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.415 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.4:51150] 200 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&width=1920&height=1080&interlaced=0&sar=1:1&level=50&frameRate=23.976&disp_default=1 (13 live) 0ms 195 bytes (pipelined: 7) (range: bytes=0-) 
Jan 12, 2022 16:47:22.415 [0x7f7e9e09ab38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/streamDetail?index=1&id=0&codec=aac&type=audio&profile=LC&language=eng&channels=2&layout=stereo&sampleRate=48000&disp_default=1 (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.415 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.4:51150] 200 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/streamDetail?index=1&id=0&codec=aac&type=audio&profile=LC&language=eng&channels=2&layout=stereo&sampleRate=48000&disp_default=1 (13 live) 0ms 195 bytes (pipelined: 8) (range: bytes=0-) 
Jan 12, 2022 16:47:22.415 [0x7f7e9e077b38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/streamDetail?index=2&id=0&codec=subrip&type=subtitle&language=eng&disp_default=1 (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.415 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.4:51150] 200 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress/streamDetail?index=2&id=0&codec=subrip&type=subtitle&language=eng&disp_default=1 (13 live) 0ms 195 bytes (pipelined: 9) (range: bytes=0-) 
Jan 12, 2022 16:47:22.417 [0x7f7e9e0e0b38] DEBUG - Request: [192.168.1.4:51150 (WAN)] PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?duration=3521.280000 (13 live) Signed-in Token (ddienlin) (range: bytes=0-) 
Jan 12, 2022 16:47:22.417 [0x7f7e9e054b38] DEBUG - [Transcode] Started session successfully: h97nji5k3ifxwwc4kv3m1da8
Jan 12, 2022 16:47:22.417 [0x7f7e9eceeb38] DEBUG - Completed: [192.168.1.4:51150] 204 PUT /video/:/transcode/session/h97nji5k3ifxwwc4kv3m1da8/a9de623f-1f89-4dbe-a7ec-c61678892b43/progress?duration=3521.280000 (13 live) 0ms 203 bytes (pipelined: 10) (range: bytes=0-) 
Jan 12, 2022 16:47:22.417 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.7:53863] 200 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F797&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=h97nji5k3ifxwwc4kv3m1da8&subtitles=burn&Accept-Language=en (13 live) TLS GZIP 78ms 1081 bytes (pipelined: 4)
Jan 12, 2022 16:47:22.421 [0x7f7e9e09ab38] DEBUG - Request: [192.168.1.7:53863 (Subnet)] GET /status/sessions (13 live) TLS GZIP Signed-in Token (ddienlin)
Jan 12, 2022 16:47:22.421 [0x7f7e9e09ab38] DEBUG - [Now] Adding 0 sessions.
Jan 12, 2022 16:47:22.421 [0x7f7e9eccbb38] DEBUG - Completed: [192.168.1.7:53863] 200 GET /status/sessions (13 live) TLS GZIP 0ms 480 bytes (pipelined: 5)
Jan 12, 2022 16:47:22.435 [0x7f7e9e077b38] DEBUG - Request: [192.168.1.7:53863 (Subnet)] GET /video/:/transcode/universal/session/h97nji5k3ifxwwc4kv3m1da8/1/header (13 live) TLS GZIP Signed-in
Jan 12, 2022 16:47:22.437 [0x7f7e9e0e0b38] DEBUG - Request: [192.168.1.7:53868 (Subnet)] GET /video/:/transcode/universal/session/h97nji5k3ifxwwc4kv3m1da8/0/header (16 live) TLS GZIP Signed-in
Jan 12, 2022 16:47:22.437 [0x7f7e9e054b38] DEBUG - Request: [192.168.1.7:53866 (Subnet)] GET /video/:/transcode/universal/session/h97nji5k3ifxwwc4kv3m1da8/1/0.m4s (16 live) TLS GZIP Signed-in
Jan 12, 2022 16:47:22.437 [0x7f7e9e054b38] DEBUG - [Transcode/h97nji5k3ifxwwc4kv3m1da8] Asked for segment 0 from session.
Jan 12, 2022 16:47:22.439 [0x7f7e9e0bdb38] DEBUG - Request: [192.168.1.7:53869 (Subnet)] GET /video/:/transcode/universal/session/h97nji5k3ifxwwc4kv3m1da8/0/0.m4s (16 live) TLS GZIP Signed-in
Jan 12, 2022 16:47:22.439 [0x7f7e9e0bdb38] DEBUG - [Transcode/h97nji5k3ifxwwc4kv3m1da8] Asked for segment 0 from session.
Jan 12, 2022 16:47:22.668 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 4326376 is out of range for mov/mp4 format
Jan 12, 2022 16:47:22.675 [0x7f7e9dc05b38] DEBUG - Grabber: Cleaning up orphaned grabs.
Jan 12, 2022 16:47:22.675 [0x7f7e9dc05b38] DEBUG - Grabber: Cleaned up 0 decrepit directories

further, it’s not liking the time stamps in the file

172.22.0.1:32400&Connection[][uri]=http://172.20.0.1:32400&Connection[][uri]=http://172.26.0.1:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=1&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (reused)
Jan 12, 2022 16:47:25.871 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 33848296 is out of range for mov/mp4 format
Jan 12, 2022 16:47:26.340 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 37688296 is out of range for mov/mp4 format
Jan 12, 2022 16:47:26.780 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 40808440 is out of range for mov/mp4 format
Jan 12, 2022 16:47:26.798 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 41288680 is out of range for mov/mp4 format
Jan 12, 2022 16:47:26.867 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 41528296 is out of range for mov/mp4 format
Jan 12, 2022 16:47:26.886 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 42008536 is out of range for mov/mp4 format
Jan 12, 2022 16:47:26.961 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 42488824 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.226 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 45128680 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.241 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 45368296 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.387 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 46809064 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.585 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 48488440 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.653 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 48968680 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.668 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 49448920 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.730 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 49929208 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.933 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 52808680 is out of range for mov/mp4 format
Jan 12, 2022 16:47:27.956 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 53527528 is out of range for mov/mp4 format
Jan 12, 2022 16:47:28.054 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 57128920 is out of range for mov/mp4 format
Jan 12, 2022 16:47:28.067 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 57609208 is out of range for mov/mp4 format
Jan 12, 2022 16:47:28.370 [0x7f7e9e09ab38] ERROR - [Transcoder] [mp4 @ 0x7f04b673c340] Application provided duration: -32 / timestamp: 60249064 is out of range for mov/mp4 format
Jan 12, 2022 16:47:28.809 [0x7f7e9e09ab38] DEBUG - Request: [192.168.1.7:53882 (Subnet)] GET /:/websockets/notifications (18 live) TLS GZIP Signed-in Token (ddienlin)
Jan 12, 2022 16:47:28.809 [0x7f7e9e09ab38] DEBUG - WebSocket: Performing handshake from origin https://plex.ddienlin.de
Jan 12, 2022 16:47:28.809 [0x7f7e9e09ab38] DEBUG - Beginning read from WebSocket

File where this is occurring is:

'/data/tv/The Chosen/Season 2/The.Chosen.S02E04.1080p.WEB-DL.AAC2.0.H.264-AlfaHD-AsRequested.mkv'

Is this true of all files or a selected few?

11

Thanks for your help, hope you’ll feel better soon. It seems to be the case for all files. Only if transcoding though, otherwise it works.

12

@ddienlin

Please do the following:

  1. Restart Plex
  2. Let it sit completely idle for 3 minutes
  3. Download the logs ZIP file
  4. Attach it.

I will look at the startup sequence (possible notify table overflow)

13

Plex Media Server Logs_2022-01-20_15-59-43.zip (2.4 MB)
Here you go

14

I have a lot of issues with transcoding if the file is .AVI video or the audio is TrueHD 7.1 - it just will not work.

The TV will try to playback, and the server will not show a request to playback the media at all, or it will be a large delay and eventually say it’s transcoding, but fail to transcode and the TV returns a non-descript playback error.

As for the audio, if there are multiple audio tracks, sometimes just switching from 7.1 to 5.1 will allow playback, but if I try to play 7.1, it just fails. lol.

I don’t know if it’s related to your issue, but I’d like to monitor this thread for a possible solution.

15

There are a number of us that are having what may be a related issue
Videos in the phone directory stopped playing a few weeks ago, you can hear audio, but no video

Happens on Android TV devices

Here is the link to this thread

16

this might seems related but not sure it actually is. For me transcoding doesn’t work at all

17

@ddienlin
sorry for delay. covid here. will be afk-medical tomorrow.

startup is not overflowing any tables. this means all dolby codecs will be ok.

This device, on the other hand, 192.168.1.4, is spewing all kinds of SSDP traffic on the lan. It should not be responding unless it’s a player device or a gateway.

Jan 19, 2022 07:59:54.144 [0x7f7e9e189b38] ERROR - SSDP: Error parsing device schema for http://192.168.1.4/upnp/basic_dev.cgi
Jan 19, 2022 08:00:20.798 [0x7f7e9db48b38] DEBUG - Statistics: Flushing 1 expired bandwidth entries, 0 expired media entries.
Jan 19, 2022 08:00:24.114 [0x7f7e9e189b38] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.1.4/upnp/basic_dev.cgi
Jan 19, 2022 08:00:24.114 [0x7f7e9e189b38] DEBUG - HTTP requesting GET http://192.168.1.4/upnp/basic_dev.cgi
Jan 19, 2022 08:00:24.132 [0x7f7e9e189b38] ERROR - Error issuing curl_easy_perform(handle): 35
Jan 19, 2022 08:00:24.132 [0x7f7e9e189b38] WARN - HTTP error requesting GET http://192.168.1.4/upnp/basic_dev.cgi (35, SSL connect error) (error:14094438:SSL routines:ssl3_read_bytes:tlsv1 alert internal error)

if that’s a TV, then will ask if one of the TV’s which lost its certificate on Sept 30,2021?
if so, you need make a manual exception for that in settings - server - network.

Whatever it is… it’s filling your logs. Do you have connections set to REQUIRED?

18

No worries hope you get back to health soon! 192.168.1.4 is actually the host of the Plex docker container not sure why that’s showing up in the logs.

I had set connections to allow insecure while trying to get this to work. But streaming without transcoding works and I also have a reverse proxy providing with valid ssl certificate (but for debugging this issue I used the local direct connection without https).

Why is it trying to connect to http://192.168.1.4/upnp/… ?

Don’t feel obliged do respond if you’re sick, I can wait until you’ve recovered.

19

proxies are a PAIN when dealing with mixed-mode.

definitely something on your host is advertizing as SSDP and the Plex in the NAT (?) / Bridge (Same LAN/ Different IP) container is picking that up.

(I don’t understand why running a proxy. what’s to gain when you can run the container in HOST network mode without all the fuss and the external FQDN still resolves to the WAN address/port)

20

ok, I’ll try to investigate where this is coming from. but why should that break the transcoding? everything works fine if I just play the original version and transcoding isn’t necessary

when I set up the reverse proxy with a subdomain handler for plex a while ago, I was hoping that the plex clients would just connect to that domain. instead it seems to try to figure out the actual IP address and port for a direct connection (so running behind a proxy doesn’t really work). note that I wasn’t using the proxy at all when debugging this problem but directly connected via http://<my ip>:32400/web or via https://plex.tv/web