Direct Play is disabled (Nvidia Shield TV)

Plex Media Server
,
1

Server Version#: v1.32.8.7639-fb6452ebf
Player Version#: 10.5.0.4996 (5d68759) Nvidie Shield TV (Android)
PMS Host: Linux plexvm 6.3.6-gentoo #4 SMP PREEMPT_DYNAMIC Sat Jun 24 04:26:44 -00 2023 x86_64 Intel(R) N100 GenuineIntel GNU/Linux
Network: Server and Clients all have gigabit internet

I have a couple of reomte users (both with Shield TV clients) who are experiencing severe pausing (every few seconds) on all media accessed from my Plex Media Server. They are not getting Direct Play to work. My PMS server hardware is not powerful enough to transcode, but it has Google Fiber and can send high bitrate streams with no problems. One user is new and has not been able to watch anything yet. The order user was able to Direct Play with no problems a month ago, but not since then.

The same this happens for remote users on all media they have tried.

I am attaching the log entries for the failed Direct Play attempt from a privious try. Please let me know what (if any) additional information you might need of find useful. Thanks for any help!

P.S. Here are the Plex Client Settings from the remote user’s Shield’s:

  • VIDEO QUALITY
    • Adjust Automatically: OFF
    • Home Streaming: Maximum
    • Quality Suggestions: OFF
    • Remote Streaming Quality: Maximum
    • Play Smaller Videos: ON
  • AUDIO
    • Remote Streaming Quality: Original
  • VIDEO
    • Burn Subtitles: Automatic
    • Audio Boost: None
  • ADVANCED
    • Passthrough: HDMI
    • Refresh Rate Switching: ON
    • Resolution Switching: OFF
    • Refresh Switching: None
    • Advertise as Player: ON
    • H264 maximum level: 5.2

On the PMS server I have: Under Settings → Transcode.
“Disable video stream transcoding” checked
“Transcode quality” Automatic

2

I can’t find where to upload an attachment with the logs. Please advise…

Adding them here…

Log Snippet 
Dec 14, 2023 16:27:43.511 [139996721896248] DEBUG - [Req#519/Transcode/Req#51a] Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:27:43.523 [139996721896248] DEBUG - [Req#519/Transcode/Req#51a] It took 10.000000 ms to retrieve 216 items.
Dec 14, 2023 16:27:43.525 [139996721896248] DEBUG - [Req#519/Transcode] We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:27:43.525 [139996721896248] DEBUG - [Req#519/Transcode] Selecting best audio stream for part ID 98938 (language: en)
Dec 14, 2023 16:27:43.525 [139996721896248] DEBUG - [Req#519/Transcode] We're going to try to auto-select a subtitle.
Dec 14, 2023 16:27:43.525 [139996721896248] DEBUG - [Req#519/Transcode] Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:27:43.525 [139996721896248] DEBUG - [Req#519/Transcode] Audio Stream: 294567, Subtitle Stream: 294568
Dec 14, 2023 16:27:43.538 [139996721896248] DEBUG - [Req#519/Transcode] We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:27:43.538 [139996721896248] DEBUG - [Req#519/Transcode] Selecting best audio stream for part ID 146130 (language: en)
Dec 14, 2023 16:27:43.538 [139996721896248] DEBUG - [Req#519/Transcode] We're going to try to auto-select a subtitle.
Dec 14, 2023 16:27:43.538 [139996721896248] DEBUG - [Req#519/Transcode] Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:27:43.538 [139996721896248] DEBUG - [Req#519/Transcode] Audio Stream: 405808, Subtitle Stream: 405809
Dec 14, 2023 16:27:43.554 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Selected protocol hls; container: mkv
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: analyzing media item 145564
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): Direct Play is disabled
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): media must be transcoded in order to use the hls protocol
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): no direct play video profile exists for http/mkv/hevc
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): no direct play video profile exists for http/mkv/hevc/eac3
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): Direct Streaming is disabled, so video stream will be transcoded
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): no remuxable profile found, so video stream will be transcoded
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Cannot direct stream video stream due to profile or setting limitations
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Cannot direct stream audio stream due to profile or setting limitations
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): selected media 1 / 145564
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Required bandwidth unknown (media requires deep analysis) Changing decision parameters provided by client to fit bandwidth limit of 200000kbps
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Determining preferred transcode encoders through transcode only decision.
Dec 14, 2023 16:27:43.556 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Reducing playback quality for 190476kbps stream bitrate: audio channels to 6, quality to 99
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] Clipped max bitrate to 82944Kbps based on client-requested limit
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Selected protocol hls; container: mkv
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: analyzing media item 145564
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): Direct Play is disabled
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): media must be transcoded in order to use the hls protocol
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): no direct play video profile exists for http/mkv/hevc
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): no direct play video profile exists for http/mkv/hevc/eac3
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): Direct Streaming is disabled, so video stream will be transcoded
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): no remuxable profile found, so video stream will be transcoded
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Cannot direct stream video stream due to profile or setting limitations
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] Clipped max bitrate to 82944Kbps based on client-requested limit
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Cannot direct stream audio stream due to profile or setting limitations
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] MDE: Glass Onion (2022): selected media 1 / 145564
Dec 14, 2023 16:27:43.558 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Reached Decision id=62718 codes=(General=2000,Neither direct play nor conversion is available. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=4002,Not enough CPU for conversion of this item.)
Dec 14, 2023 16:27:43.559 [139996780542776] DEBUG - Completed: [192.168.1.1:44734] 200 GET /video/:/transcode/universal/decision?advancedSubtitles=text&audioBoost=100&autoAdjustQuality=0&directPlay=0&directStream=0&directStreamAudio=0&fastSeek=1&hasMDE=1&location=wan&maxVideoBitrate=200000&mediaBufferSize=209664&mediaIndex=1&partIndex=0&path=%2Flibrary%2Fmetadata%2F62718&protocol=*&session=21261ac151d579d-com-plexapp-android&skipSubtitles=1&subtitleSize=100&videoBitrate=200000&videoQuality=100&videoResolution=3840x2160 (8 live) #519 TLS GZIP 52ms 678 bytes (pipelined: 2)
Dec 14, 2023 16:27:46.962 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:27:46.962 [139996721896248] DEBUG - Request: [192.168.1.1:44734 (Subnet)] GET /library/parts/146130/1672497057/file.mkv?autoAdjustQuality=0&hasMDE=1&location=wan&mediaBufferSize=209664 (8 live) #51c TLS Signed-in Token (Adam)
Dec 14, 2023 16:27:46.966 [139996721896248] DEBUG - [Req#51c] Streaming Resource: Attempting to create AdHoc dp session 21261ac151d579d-com-plexapp-android for part 146130
Dec 14, 2023 16:27:46.973 [139996721896248] DEBUG - [Req#51c] TranscodeUniversalRequest: using profile Generic
Dec 14, 2023 16:27:46.973 [139996721896248] DEBUG - [Req#51c] Downloading document http://127.0.0.1:32400/library/metadata/62718?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=21261ac151d579d-com-plexapp-android
Dec 14, 2023 16:27:46.973 [139996721896248] DEBUG - [Req#51c/HCl#8f] HTTP requesting GET http://127.0.0.1:32400/library/metadata/62718?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=21261ac151d579d-com-plexapp-android
Dec 14, 2023 16:27:46.975 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:27:46.976 [139996719786808] DEBUG - Request: [127.0.0.1:41304 (Loopback)] GET /library/metadata/62718?includeBandwidths=1&offset=-1 (9 live) #508 GZIP Signed-in Token (Adam)
Dec 14, 2023 16:27:46.986 [139996719786808] DEBUG - [Req#508] It took 0.000000 ms to retrieve 216 items.
Dec 14, 2023 16:27:46.987 [139996719786808] DEBUG - We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:27:46.987 [139996719786808] DEBUG - Selecting best audio stream for part ID 98938 (language: en)
Dec 14, 2023 16:27:46.987 [139996719786808] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:27:46.987 [139996719786808] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:27:46.988 [139996719786808] DEBUG - Audio Stream: 294567, Subtitle Stream: 294568
Dec 14, 2023 16:27:46.993 [139996719786808] DEBUG - We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:27:46.993 [139996719786808] DEBUG - Selecting best audio stream for part ID 146130 (language: en)
Dec 14, 2023 16:27:46.993 [139996719786808] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:27:46.993 [139996719786808] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:27:46.993 [139996719786808] DEBUG - Audio Stream: 405808, Subtitle Stream: 405809
Dec 14, 2023 16:27:46.999 [139996778433336] DEBUG - Completed: [127.0.0.1:41304] 200 GET /library/metadata/62718?includeBandwidths=1&offset=-1 (9 live) #508 GZIP 23ms 6278 bytes (pipelined: 1)
Dec 14, 2023 16:27:47.000 [139996741847864] DEBUG - [HttpClient/HCl#8f] HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/library/metadata/62718?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=21261ac151d579d-com-plexapp-android
Dec 14, 2023 16:27:47.003 [139996721896248] DEBUG - [Req#51c] MDE: received PLEX_PROTOCOL_ANY from client, but could not determine best protocol. Defaulting to HTTP
Dec 14, 2023 16:27:47.003 [139996721896248] DEBUG - [Req#51c] MDE: analyzing media item 145564
Dec 14, 2023 16:27:47.003 [139996721896248] DEBUG - [Req#51c] MDE: Glass Onion (2022): Direct Playing due to no transcode profile
Dec 14, 2023 16:27:47.003 [139996721896248] DEBUG - [Req#51c] MDE: Glass Onion (2022): no direct play video profile exists for http/mkv/hevc
Dec 14, 2023 16:27:47.003 [139996721896248] DEBUG - [Req#51c] MDE: Glass Onion (2022): no direct play video profile exists for http/mkv/hevc/eac3
Dec 14, 2023 16:27:47.004 [139996721896248] DEBUG - [Req#51c] MDE: Glass Onion (2022): selected media 1 / 145564
Dec 14, 2023 16:27:47.004 [139996721896248] DEBUG - [Req#51c] Streaming Resource: Added session 0x7f538a7aa688:21261ac151d579d-com-plexapp-android
Dec 14, 2023 16:27:47.004 [139996721896248] DEBUG - [Req#51c] Streaming Resource: Reached Decision id=62718 codes=(MDE=1000,Direct play OK.) media=(id=145564 part=(id=146130 decision=direct play protocol=* streams=(Video=(id=405807 decision= width=3840 height=2160) Audio=(id=405808 decision= channels=0 rate=0) Subtitle=(id=405809 decision=unavailable languageCode=eng location=sidecar))))
Dec 14, 2023 16:27:47.004 [139996721896248] DEBUG - Content-Length of /mnt/bluray/Glass Onion a Knives Out Mystery (2022)/Glass.Onion.A.Knives.Out.Mystery.2022.2160p.NF.WEB-DL.DDP5.1.Atmos.HDR.DV-MZABI.mkv is 20703562797 (of total: 20703562797).
Dec 14, 2023 16:27:47.771 [139996778433336] DEBUG - Failed to stream media, client probably disconnected after 475136 bytes: 32 - Broken pipe
Dec 14, 2023 16:27:47.771 [139996778433336] DEBUG - Completed after connection close: [192.168.1.1:44734] 200 GET /library/parts/146130/1672497057/file.mkv?autoAdjustQuality=0&hasMDE=1&location=wan&mediaBufferSize=209664 (9 live) #51c TLS 808ms 475136 bytes (pipelined: 3)
Dec 14, 2023 16:27:48.077 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:27:48.077 [139996719786808] DEBUG - Request: [192.168.1.1:44750 (Subnet)] GET /library/parts/146130/1672497057/file.mkv?autoAdjustQuality=0&hasMDE=1&location=wan&mediaBufferSize=209664 (9 live) #51e TLS Signed-in Token (Adam) (range: bytes=20703550151-) 
Dec 14, 2023 16:27:48.081 [139996719786808] DEBUG - Content-Length of /mnt/bluray/Glass Onion a Knives Out Mystery (2022)/Glass.Onion.A.Knives.Out.Mystery.2022.2160p.NF.WEB-DL.DDP5.1.Atmos.HDR.DV-MZABI.mkv is 12646 (of total: 20703562797).
Dec 14, 2023 16:27:48.092 [139996778433336] DEBUG - Completed: [192.168.1.1:44750] 206 GET /library/parts/146130/1672497057/file.mkv?autoAdjustQuality=0&hasMDE=1&location=wan&mediaBufferSize=209664 (9 live) #51e TLS 14ms 12646 bytes (pipelined: 1) (range: bytes=20703550151-) 
Dec 14, 2023 16:27:48.208 [139996780542776] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:27:48.208 [139996721896248] DEBUG - Request: [192.168.1.1:44750 (Subnet)] GET /library/parts/146130/1672497057/file.mkv?autoAdjustQuality=0&hasMDE=1&location=wan&mediaBufferSize=209664 (9 live) #523 TLS Signed-in Token (Adam) (range: bytes=9563-) 
Dec 14, 2023 16:27:48.211 [139996721896248] DEBUG - Content-Length of /mnt/bluray/Glass Onion a Knives Out Mystery (2022)/Glass.Onion.A.Knives.Out.Mystery.2022.2160p.NF.WEB-DL.DDP5.1.Atmos.HDR.DV-MZABI.mkv is 20703553234 (of total: 20703562797).
Dec 14, 2023 16:27:48.405 [139996778433336] DEBUG - Completed: [192.168.1.1:44732] 200 GET /library/metadata/62718?includeRelated=1&includeLoudnessRamps=1&includeChapters=1&includeMarkers=1 (9 live) #507 TLS GZIP 5431ms 14932 bytes (pipelined: 2)
Dec 14, 2023 16:27:52.781 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:27:52.782 [139996719786808] DEBUG - Request: [192.168.1.1:44752 (Subnet)] POST /playQueues?includeChapters=1&continuous=0&includeLoudnessRamps=1&repeat=0&extrasPrefixCount=0&type=video&shuffle=0&uri=server%3A%2F%2Fd526c5b46bac560f9e8d1da79b7d95efdf14b9f2%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F62718&key=%2Flibrary%2Fmetadata%2F62718 (10 live) #522 TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:27:52.783 [139996719786808] DEBUG - [Req#522] PlayQueue: Converted 'server://d526c5b46bac560f9e8d1da79b7d95efdf14b9f2/com.plexapp.plugins.library/library/metadata/62718' to 'library://x/item/%2Flibrary%2Fmetadata%2F62718'
Dec 14, 2023 16:27:52.787 [139996719786808] DEBUG - [Req#522] PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Dec 14, 2023 16:27:52.788 [139996719786808] DEBUG - [Req#522] PlayQueue: Start index: 0 End index: 0 Count: 1
Dec 14, 2023 16:27:52.789 [139996719786808] DEBUG - [Req#522] PlayQueue: 1 generated IDs compressed down to a 24 byte blob.
Dec 14, 2023 16:27:52.789 [139996719786808] DEBUG - [Req#522] PlayQueue: Added 1 items in 0.0 sec.
Dec 14, 2023 16:27:52.803 [139996719786808] DEBUG - [Req#522] It took 10.000000 ms to retrieve 216 items.
Dec 14, 2023 16:27:58.130 [139996719786808] DEBUG - [Req#522] It took 0.000000 ms to retrieve 216 items.
Dec 14, 2023 16:27:58.132 [139996719786808] DEBUG - We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:27:58.132 [139996719786808] DEBUG - Selecting best audio stream for part ID 98938 (language: en)
Dec 14, 2023 16:27:58.132 [139996719786808] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:27:58.132 [139996719786808] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:27:58.132 [139996719786808] DEBUG - Audio Stream: 294567, Subtitle Stream: 294568
Dec 14, 2023 16:27:58.137 [139996719786808] DEBUG - We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:27:58.137 [139996719786808] DEBUG - Selecting best audio stream for part ID 146130 (language: en)
Dec 14, 2023 16:27:58.137 [139996719786808] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:27:58.138 [139996719786808] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:27:58.138 [139996719786808] DEBUG - Audio Stream: 405808, Subtitle Stream: 405809
Dec 14, 2023 16:27:58.144 [139996778433336] DEBUG - Completed: [192.168.1.1:44752] 200 POST /playQueues?includeChapters=1&continuous=0&includeLoudnessRamps=1&repeat=0&extrasPrefixCount=0&type=video&shuffle=0&uri=server%3A%2F%2Fd526c5b46bac560f9e8d1da79b7d95efdf14b9f2%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F62718&key=%2Flibrary%2Fmetadata%2F62718 (10 live) #522 TLS GZIP 5362ms 6092 bytes (pipelined: 1)
Dec 14, 2023 16:27:58.386 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:27:58.387 [139996724005688] DEBUG - Request: [192.168.1.1:44752 (Subnet)] GET /:/timeline?audioStreamID=294567&bufferedTime=2877&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=7855&playQueueItemID=15568&ratingKey=62718&state=playing&time=1290&timeToFirstFrame=2&token=xxxxxxxxxxxxxxxxxxxx (10 live) #538 TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:27:58.389 [139996724005688] DEBUG - [Req#538] Client [21261ac151d579d-com-plexapp-android] reporting timeline state playing, progress of 1290/8462752ms for guid=plex://movie/5e165afd20b9e6003e35a05b, playbackTime=7855ms ratingKey=62718 url=, key=/library/metadata/62718, containerKey=, metadataId=62718, source=
Dec 14, 2023 16:27:58.404 [139996724005688] DEBUG - [Req#538] It took 0.000000 ms to retrieve 216 items.
Dec 14, 2023 16:28:03.945 [139996724005688] DEBUG - [Req#538] It took 10.000000 ms to retrieve 216 items.
Dec 14, 2023 16:28:09.291 [139996724005688] DEBUG - [Req#538] We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:28:09.291 [139996724005688] DEBUG - [Req#538] Selecting best audio stream for part ID 98938 (language: en)
Dec 14, 2023 16:28:09.291 [139996724005688] DEBUG - [Req#538] We're going to try to auto-select a subtitle.
Dec 14, 2023 16:28:09.291 [139996724005688] DEBUG - [Req#538] Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:28:09.291 [139996724005688] DEBUG - [Req#538] Audio Stream: 294567, Subtitle Stream: 294568
Dec 14, 2023 16:28:09.297 [139996724005688] DEBUG - [Req#538] We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:28:09.297 [139996724005688] DEBUG - [Req#538] Selecting best audio stream for part ID 146130 (language: en)
Dec 14, 2023 16:28:09.297 [139996724005688] DEBUG - [Req#538] We're going to try to auto-select a subtitle.
Dec 14, 2023 16:28:09.297 [139996724005688] DEBUG - [Req#538] Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:28:09.297 [139996724005688] DEBUG - [Req#538] Audio Stream: 405808, Subtitle Stream: 405809
Dec 14, 2023 16:28:09.305 [139996724005688] DEBUG - [Req#538] [Now] User is Adam (ID: 201681677)
Dec 14, 2023 16:28:09.305 [139996724005688] DEBUG - [Req#538] [Now] Device is Android (SHIELD Android TV).
Dec 14, 2023 16:28:09.305 [139996724005688] DEBUG - [Req#538] [Now] Profile is Android
Dec 14, 2023 16:28:09.305 [139996724005688] DEBUG - [Req#538] [Now] Updated play state for /library/metadata/62718.
Dec 14, 2023 16:28:09.306 [139996724005688] DEBUG - [Req#538] Statistics: (21261ac151d579d-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 201681677
Dec 14, 2023 16:28:09.311 [139996780542776] DEBUG - Completed: [192.168.1.1:44752] 200 GET /:/timeline?audioStreamID=294567&bufferedTime=2877&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=7855&playQueueItemID=15568&ratingKey=62718&state=playing&time=1290&timeToFirstFrame=2&token=xxxxxxxxxxxxxxxxxxxx (9 live) #538 TLS GZIP 10923ms 489 bytes (pipelined: 2)
Dec 14, 2023 16:28:09.322 [139996724005688] DEBUG - Request: [192.168.1.108:58178 (Subnet)] GET /status/sessions (10 live) #535 GZIP Signed-in Token (Tunerooster) (plexvm (Tautulli))
Dec 14, 2023 16:28:09.323 [139996724005688] DEBUG - [Req#535] [Now] Adding 1 sessions.
Dec 14, 2023 16:28:09.331 [139996780542776] DEBUG - Completed: [192.168.1.108:58178] 200 GET /status/sessions (10 live) #535 GZIP 8ms 4681 bytes (pipelined: 1)
Dec 14, 2023 16:28:09.338 [139996724005688] DEBUG - Request: [192.168.1.108:58178 (Subnet)] GET /library/metadata/62718?includeMarkers=1 (10 live) #548 GZIP Signed-in Token (Tunerooster) (plexvm (Tautulli))
Dec 14, 2023 16:28:09.348 [139996724005688] DEBUG - [Req#548] It took 10.000000 ms to retrieve 216 items.
Dec 14, 2023 16:28:09.350 [139996724005688] DEBUG - We're going to try to auto-select an audio stream for account 1.
Dec 14, 2023 16:28:09.350 [139996724005688] DEBUG - Selecting best audio stream for part ID 98938 (language: en)
Dec 14, 2023 16:28:09.350 [139996724005688] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:28:09.350 [139996724005688] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:28:09.350 [139996724005688] DEBUG - Audio Stream: 294567, Subtitle Stream: 294568
Dec 14, 2023 16:28:09.355 [139996724005688] DEBUG - We're going to try to auto-select an audio stream for account 1.
Dec 14, 2023 16:28:09.355 [139996724005688] DEBUG - Selecting best audio stream for part ID 146130 (language: en)
Dec 14, 2023 16:28:09.355 [139996724005688] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:28:09.355 [139996724005688] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:28:09.355 [139996724005688] DEBUG - Audio Stream: 405808, Subtitle Stream: 405809
Dec 14, 2023 16:28:09.362 [139996778433336] DEBUG - Completed: [192.168.1.108:58178] 200 GET /library/metadata/62718?includeMarkers=1 (10 live) #548 GZIP 23ms 6079 bytes (pipelined: 2)
Dec 14, 2023 16:28:09.583 [139996780542776] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:28:09.583 [139996724005688] DEBUG - Request: [192.168.1.1:44752 (Subnet)] GET /playQueues/2409?center=15568&includeChapters=1&repeat=0 (9 live) #546 TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:28:09.585 [139996724005688] DEBUG - [Req#546] PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Dec 14, 2023 16:28:09.586 [139996724005688] DEBUG - [Req#546] PlayQueue: No need to refresh, active window is operational.
Dec 14, 2023 16:28:09.601 [139996724005688] DEBUG - [Req#546] It took 0.000000 ms to retrieve 216 items.
Dec 14, 2023 16:28:09.602 [139996724005688] DEBUG - We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:28:09.602 [139996724005688] DEBUG - Selecting best audio stream for part ID 98938 (language: en)
Dec 14, 2023 16:28:09.602 [139996724005688] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:28:09.602 [139996724005688] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:28:09.602 [139996724005688] DEBUG - Audio Stream: 294567, Subtitle Stream: 294568
Dec 14, 2023 16:28:09.607 [139996724005688] DEBUG - We're going to try to auto-select an audio stream for account 201681677.
Dec 14, 2023 16:28:09.607 [139996724005688] DEBUG - Selecting best audio stream for part ID 146130 (language: en)
Dec 14, 2023 16:28:09.607 [139996724005688] DEBUG - We're going to try to auto-select a subtitle.
Dec 14, 2023 16:28:09.607 [139996724005688] DEBUG - Subtitles: Found a candidate subtitle language [en] for a foreign film
Dec 14, 2023 16:28:09.608 [139996724005688] DEBUG - Audio Stream: 405808, Subtitle Stream: 405809
Dec 14, 2023 16:28:09.614 [139996780542776] DEBUG - Completed: [192.168.1.1:44752] 200 GET /playQueues/2409?center=15568&includeChapters=1&repeat=0 (9 live) #546 TLS GZIP 30ms 6092 bytes (pipelined: 3)
Dec 14, 2023 16:28:10.654 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:28:10.656 [139996724005688] DEBUG - Request: [192.168.1.1:44754 (Subnet)] GET /:/timeline?audioStreamID=294567&bufferedTime=621&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=16501&playQueueItemID=15568&ratingKey=62718&state=buffering&time=5843&timeStalled=4&token=xxxxxxxxxxxxxxxxxxxx (10 live) #547 TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:28:10.657 [139996724005688] DEBUG - [Req#547] Client [21261ac151d579d-com-plexapp-android] reporting timeline state buffering, progress of 5843/8462752ms for guid=plex://movie/5e165afd20b9e6003e35a05b, playbackTime=16501ms ratingKey=62718 url=, key=/library/metadata/62718, containerKey=, metadataId=62718, source=
Dec 14, 2023 16:28:10.658 [139996724005688] DEBUG - [Req#547] [Now] User is Adam (ID: 201681677)
Dec 14, 2023 16:28:10.658 [139996724005688] DEBUG - [Req#547] [Now] Device is Android (SHIELD Android TV).
Dec 14, 2023 16:28:10.658 [139996724005688] DEBUG - [Req#547] [Now] Profile is Android
Dec 14, 2023 16:28:10.658 [139996724005688] DEBUG - [Req#547] [Now] Updated play state for /library/metadata/62718.
Dec 14, 2023 16:28:10.658 [139996724005688] DEBUG - [Req#547] Statistics: (21261ac151d579d-com-plexapp-android) Reporting active playback in state 2 of type 1 (scrobble: 0) for account 201681677
Dec 14, 2023 16:28:10.663 [139996780542776] DEBUG - Completed: [192.168.1.1:44754] 200 GET /:/timeline?audioStreamID=294567&bufferedTime=621&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=16501&playQueueItemID=15568&ratingKey=62718&state=buffering&time=5843&timeStalled=4&token=xxxxxxxxxxxxxxxxxxxx (10 live) #547 TLS GZIP 7ms 489 bytes (pipelined: 1)
Dec 14, 2023 16:28:10.670 [139996724005688] DEBUG - Request: [192.168.1.108:58194 (Subnet)] GET /status/sessions (11 live) #54c GZIP Signed-in Token (Tunerooster) (plexvm (Tautulli))
Dec 14, 2023 16:28:10.670 [139996724005688] DEBUG - [Req#54c] [Now] Adding 1 sessions.
Dec 14, 2023 16:28:10.672 [139996780542776] DEBUG - Completed: [192.168.1.108:58194] 200 GET /status/sessions (11 live) #54c GZIP 2ms 4683 bytes (pipelined: 1)
Dec 14, 2023 16:28:17.048 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:28:17.050 [139996724005688] DEBUG - Request: [192.168.1.1:44752 (Subnet)] GET /:/timeline?audioStreamID=294567&bufferedTime=2957&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=26541&playQueueItemID=15568&ratingKey=62718&state=buffering&time=5843&timeStalled=14&token=xxxxxxxxxxxxxxxxxxxx (9 live) #54b TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:28:17.051 [139996724005688] DEBUG - [Req#54b] Client [21261ac151d579d-com-plexapp-android] reporting timeline state buffering, progress of 5843/8462752ms for guid=plex://movie/5e165afd20b9e6003e35a05b, playbackTime=26541ms ratingKey=62718 url=, key=/library/metadata/62718, containerKey=, metadataId=62718, source=
Dec 14, 2023 16:28:17.052 [139996724005688] DEBUG - [Req#54b] [Now] User is Adam (ID: 201681677)
Dec 14, 2023 16:28:17.052 [139996724005688] DEBUG - [Req#54b] [Now] Device is Android (SHIELD Android TV).
Dec 14, 2023 16:28:17.052 [139996724005688] DEBUG - [Req#54b] [Now] Profile is Android
Dec 14, 2023 16:28:17.052 [139996724005688] DEBUG - [Req#54b] [Now] Updated play state for /library/metadata/62718.
Dec 14, 2023 16:28:17.053 [139996724005688] DEBUG - [Req#54b] Statistics: (21261ac151d579d-com-plexapp-android) Reporting active playback in state 2 of type 1 (scrobble: 0) for account 201681677
Dec 14, 2023 16:28:17.055 [139996778433336] DEBUG - Completed: [192.168.1.1:44752] 200 GET /:/timeline?audioStreamID=294567&bufferedTime=2957&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=26541&playQueueItemID=15568&ratingKey=62718&state=buffering&time=5843&timeStalled=14&token=xxxxxxxxxxxxxxxxxxxx (9 live) #54b TLS GZIP 5ms 489 bytes (pipelined: 4)
Dec 14, 2023 16:28:17.065 [139996724005688] DEBUG - Request: [192.168.1.108:42628 (Subnet)] GET /status/sessions (10 live) #558 GZIP Signed-in Token (Tunerooster) (plexvm (Tautulli))
Dec 14, 2023 16:28:17.065 [139996724005688] DEBUG - [Req#558] [Now] Adding 1 sessions.
Dec 14, 2023 16:28:17.067 [139996778433336] DEBUG - Completed: [192.168.1.108:42628] 200 GET /status/sessions (10 live) #558 GZIP 2ms 4683 bytes (pipelined: 1)
Dec 14, 2023 16:28:27.145 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:28:27.147 [139996724005688] DEBUG - Request: [192.168.1.1:44752 (Subnet)] GET /:/timeline?audioStreamID=294567&bufferedTime=4657&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=36581&playQueueItemID=15568&ratingKey=62718&state=buffering&time=5843&timeStalled=24&token=xxxxxxxxxxxxxxxxxxxx (9 live) #566 TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:28:27.148 [139996724005688] DEBUG - [Req#566] Client [21261ac151d579d-com-plexapp-android] reporting timeline state buffering, progress of 5843/8462752ms for guid=plex://movie/5e165afd20b9e6003e35a05b, playbackTime=36581ms ratingKey=62718 url=, key=/library/metadata/62718, containerKey=, metadataId=62718, source=
Dec 14, 2023 16:28:27.149 [139996724005688] DEBUG - [Req#566] [Now] User is Adam (ID: 201681677)
Dec 14, 2023 16:28:27.149 [139996724005688] DEBUG - [Req#566] [Now] Device is Android (SHIELD Android TV).
Dec 14, 2023 16:28:27.149 [139996724005688] DEBUG - [Req#566] [Now] Profile is Android
Dec 14, 2023 16:28:27.149 [139996724005688] DEBUG - [Req#566] [Now] Updated play state for /library/metadata/62718.
Dec 14, 2023 16:28:27.151 [139996724005688] DEBUG - [Req#566] Statistics: (21261ac151d579d-com-plexapp-android) Reporting active playback in state 2 of type 1 (scrobble: 0) for account 201681677
Dec 14, 2023 16:28:27.158 [139996778433336] DEBUG - Completed: [192.168.1.1:44752] 200 GET /:/timeline?audioStreamID=294567&bufferedTime=4657&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=36581&playQueueItemID=15568&ratingKey=62718&state=buffering&time=5843&timeStalled=24&token=xxxxxxxxxxxxxxxxxxxx (9 live) #566 TLS GZIP 10ms 489 bytes (pipelined: 5)
Dec 14, 2023 16:28:27.160 [139996724005688] DEBUG - Request: [192.168.1.108:44606 (Subnet)] GET /status/sessions (10 live) #567 GZIP Signed-in Token (Tunerooster) (plexvm (Tautulli))
Dec 14, 2023 16:28:27.161 [139996724005688] DEBUG - [Req#567] [Now] Adding 1 sessions.
Dec 14, 2023 16:28:27.165 [139996778433336] DEBUG - Completed: [192.168.1.108:44606] 200 GET /status/sessions (10 live) #567 GZIP 5ms 4683 bytes (pipelined: 1)
Dec 14, 2023 16:28:29.793 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:28:29.795 [139996724005688] DEBUG - Request: [192.168.1.1:44752 (Subnet)] GET /:/timeline?audioStreamID=294567&bufferedTime=5074&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=39285&playQueueItemID=15568&ratingKey=62718&state=playing&time=5843&token=xxxxxxxxxxxxxxxxxxxx (9 live) #574 TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:28:29.796 [139996724005688] DEBUG - [Req#574] Client [21261ac151d579d-com-plexapp-android] reporting timeline state playing, progress of 5843/8462752ms for guid=plex://movie/5e165afd20b9e6003e35a05b, playbackTime=39285ms ratingKey=62718 url=, key=/library/metadata/62718, containerKey=, metadataId=62718, source=
Dec 14, 2023 16:28:29.797 [139996724005688] DEBUG - [Req#574] [Now] User is Adam (ID: 201681677)
Dec 14, 2023 16:28:29.797 [139996724005688] DEBUG - [Req#574] [Now] Device is Android (SHIELD Android TV).
Dec 14, 2023 16:28:29.798 [139996724005688] DEBUG - [Req#574] [Now] Profile is Android
Dec 14, 2023 16:28:29.798 [139996724005688] DEBUG - [Req#574] [Now] Updated play state for /library/metadata/62718.
Dec 14, 2023 16:28:29.798 [139996724005688] DEBUG - [Req#574] Statistics: (21261ac151d579d-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 201681677
Dec 14, 2023 16:28:29.801 [139996778433336] DEBUG - Completed: [192.168.1.1:44752] 200 GET /:/timeline?audioStreamID=294567&bufferedTime=5074&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=39285&playQueueItemID=15568&ratingKey=62718&state=playing&time=5843&token=xxxxxxxxxxxxxxxxxxxx (9 live) #574 TLS GZIP 6ms 489 bytes (pipelined: 6)
Dec 14, 2023 16:28:47.138 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:28:47.140 [139996724005688] DEBUG - Request: [192.168.1.1:44752 (Subnet)] GET /:/timeline?audioStreamID=294567&bufferedTime=3424&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=56629&playQueueItemID=15568&ratingKey=62718&state=buffering&time=13326&timeStalled=9&token=xxxxxxxxxxxxxxxxxxxx (8 live) #582 TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:28:47.141 [139996724005688] DEBUG - [Req#582] Client [21261ac151d579d-com-plexapp-android] reporting timeline state buffering, progress of 13326/8462752ms for guid=plex://movie/5e165afd20b9e6003e35a05b, playbackTime=56629ms ratingKey=62718 url=, key=/library/metadata/62718, containerKey=, metadataId=62718, source=
Dec 14, 2023 16:28:47.142 [139996724005688] DEBUG - [Req#582] [Now] User is Adam (ID: 201681677)
Dec 14, 2023 16:28:47.142 [139996724005688] DEBUG - [Req#582] [Now] Device is Android (SHIELD Android TV).
Dec 14, 2023 16:28:47.142 [139996724005688] DEBUG - [Req#582] [Now] Profile is Android
Dec 14, 2023 16:28:47.142 [139996724005688] DEBUG - [Req#582] [Now] Updated play state for /library/metadata/62718.
Dec 14, 2023 16:28:47.143 [139996724005688] DEBUG - [Req#582] Statistics: (21261ac151d579d-com-plexapp-android) Reporting active playback in state 2 of type 1 (scrobble: 0) for account 201681677
Dec 14, 2023 16:28:47.148 [139996780542776] DEBUG - Completed: [192.168.1.1:44752] 200 GET /:/timeline?audioStreamID=294567&bufferedTime=3424&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=56629&playQueueItemID=15568&ratingKey=62718&state=buffering&time=13326&timeStalled=9&token=xxxxxxxxxxxxxxxxxxxx (8 live) #582 TLS GZIP 8ms 489 bytes (pipelined: 7)
Dec 14, 2023 16:28:47.154 [139996724005688] DEBUG - Request: [192.168.1.108:48808 (Subnet)] GET /status/sessions (9 live) #575 GZIP Signed-in Token (Tunerooster) (plexvm (Tautulli))
Dec 14, 2023 16:28:47.154 [139996724005688] DEBUG - [Req#575] [Now] Adding 1 sessions.
Dec 14, 2023 16:28:47.157 [139996780542776] DEBUG - Completed: [192.168.1.108:48808] 200 GET /status/sessions (9 live) #575 GZIP 2ms 4683 bytes (pipelined: 1)
Dec 14, 2023 16:28:49.331 [139996778433336] DEBUG - Auth: authenticated user 201681677 as Adam
Dec 14, 2023 16:28:49.333 [139996724005688] DEBUG - Request: [192.168.1.1:44752 (Subnet)] GET /:/timeline?audioStreamID=294567&bufferedTime=5091&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=58819&playQueueItemID=15568&ratingKey=62718&state=playing&time=13326&token=xxxxxxxxxxxxxxxxxxxx (8 live) #58e TLS GZIP Signed-in Token (Adam)
Dec 14, 2023 16:28:49.334 [139996724005688] DEBUG - [Req#58e] Client [21261ac151d579d-com-plexapp-android] reporting timeline state playing, progress of 13326/8462752ms for guid=plex://movie/5e165afd20b9e6003e35a05b, playbackTime=58819ms ratingKey=62718 url=, key=/library/metadata/62718, containerKey=, metadataId=62718, source=
Dec 14, 2023 16:28:49.335 [139996724005688] DEBUG - [Req#58e] [Now] User is Adam (ID: 201681677)
Dec 14, 2023 16:28:49.335 [139996724005688] DEBUG - [Req#58e] [Now] Device is Android (SHIELD Android TV).
Dec 14, 2023 16:28:49.335 [139996724005688] DEBUG - [Req#58e] [Now] Profile is Android
Dec 14, 2023 16:28:49.335 [139996724005688] DEBUG - [Req#58e] [Now] Updated play state for /library/metadata/62718.
Dec 14, 2023 16:28:49.335 [139996724005688] DEBUG - [Req#58e] Statistics: (21261ac151d579d-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 201681677
Dec 14, 2023 16:28:49.341 [139996778433336] DEBUG - Completed: [192.168.1.1:44752] 200 GET /:/timeline?audioStreamID=294567&bufferedTime=5091&context=source%3Acontent.collections&duration=8462752&guid=plex%3A%2F%2Fmovie%2F5e165afd20b9e6003e35a05b&key=%2Flibrary%2Fmetadata%2F62718&playbackTime=58819&playQueueItemID=15568&ratingKey=62718&state=playing&time=13326&token=xxxxxxxxxxxxxxxxxxxx (8 live) #58e TLS GZIP 8ms 489 bytes (pipelined: 8)
Dec 14, 2023 16:28:52.786 [139996721896248] DEBUG - [Req#522] BPQ: onConsiderProcessing: Idle (true)
Dec 14, 2023 16:28:52.786 [139996721896248] DEBUG - [Req#522] BPQ: [Idle] -> [Processing]
Dec 14, 2023 16:28:52.790 [139996721896248] DEBUG - [Req#522] BPQ: generating queue items from 0 generator(s)
Dec 14, 2023 16:28:52.791 [139996721896248] DEBUG - [Req#522] BPQ: generated 0 item(s) for queue
Dec 14, 2023 16:28:52.791 [139996721896248] DEBUG - [Req#522] PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Dec 14, 2023 16:28:52.791 [139996721896248] DEBUG - [Req#522] PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Dec 14, 2023 16:28:52.792 [139996721896248] DEBUG - [Req#522] BPQ: [Processing] -> [Idle]

Moderator: Edited for easier reading.

3

Is this a Shield 2017 (or earlier) or Shield Pro 2019 ?

4

Shield Pro 2019

5

I also have shield pro 2019.

It will direct play ANYTHING I throw at it

Screenshot from 2023-12-15 23-51-46
Screenshot from 2023-12-15 23-51-46744×672 46.8 KB

Would you like confirmation of my Plex app settings ?

6

Plex Media Server has not performed Deep Analysis on the movie Glass Onion. This can cause problems when streaming high bit rate media such as 4K HDR rips/remuxes.

Suggested Steps:

  1. In Settings → Scheduled Tasks, make sure both Upgrade media analysis during maintenance and Perform extensive media analysis during maintenance are enabled.

  2. Leave Plex Media Server running through the time period for scheduled tasks.

  3. To verify the tasks have run, look at the XML Info for the file. Look for deepAnalysisVersion and requiredBandwidths entries.
    Example:

deepAnalysisVersion="6" <-- only present if Deep Analysis has run.
requiredBandwidths="106883,93570,91296,87556,85356,84328,82693,81399" <-- results of DeepAnalysis
  1. Try and stream the file.

Here’s what I think is happening, at least with this specific movie.

  1. Plex Media Server has not performed Deep Analysis on the file. This is mentioned in the log file.

  2. Plex Media Server assumes it needs 2X the average bitrate of the file to direct play it
    Ref: Server Settings – Bandwidth and Transcoding Limits

  3. This is over 80% of the 200 Mbps limit of the Shield.
    Shield limit is in log file (200000 kbps); 80% limit mentioned in Bandwidth & Transcoding Limits doc.

  4. Therefore, PMS wants to transcode the video
    Reducing playback quality... in log file.

  5. Video transcoding is disabled, so playback fails
    Not enough CPU... in log file.

Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Required bandwidth unknown (media requires deep analysis) Changing decision parameters provided by client to fit bandwidth limit of 200000kbps
Dec 14, 2023 16:27:43.555 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Determining preferred transcode encoders through transcode only decision.
Dec 14, 2023 16:27:43.556 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Reducing playback quality for 190476kbps stream bitrate: audio channels to 6, quality to 99
Dec 14, 2023 16:27:43.557 [139996721896248] DEBUG - [Req#519/Transcode] Clipped max bitrate to 82944Kbps based on client-requested limit


Dec 14, 2023 16:27:43.558 [139996721896248] DEBUG - [Req#519/Transcode] Streaming Resource: Reached Decision id=62718 codes=(General=2000,Neither direct play nor conversion is available. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=4002,Not enough CPU for conversion of this item.)
7

My local Shield (which is an older model) also Direct Plays anything I throw at it. It is only my two remote users, which my server (on a Linux box - not the one which is available in the Shield), tries to transcode whenever they play anything. But sure, I would like to see your settings if they differ from the ones I posted. Thanks!

8

Thanks for your reply!

  1. I have checked the maintenance options you suggest. Can I also, just to confirm your theory, manually optimize the Glass Onion and then have my remote uses retry.

  2. The remote users both have Gigabit bandwidth. User “Mark” played Barbie a month ago with no problem. Now , a month later, it (the same file) won’t play with Direct Play. He was also able to play many other high bitrate titles at that time, so there wasn’t a bandwidth issue then. Now, nothing Direct Plays, not even lower bitrate titles.

    1. Playback does not fail. It starts to play, and pauses every few seconds until stopped by the user.

Please advice further… Thanks!

9

Option 1: Set the scheduled tasks to start at 00:00 and run until 23:00. Then restart Plex Media Server. That should kick off the tasks.

Option 2: Use WebTools-NG Butler Scheduled Tasks (wiki) and manually start them (the tasks show up as Butler tasks in the log files).

.
Let the tasks run until finished, then have one of the remote users stream some movies.

Try streaming a 1080p SDR movie (i.e. Blu-ray rip), then try a 4K HDR movie.

The pull the log files. Post the entire ZIP file to the thread. Just drag the zip file into the message window. It will insert at the cursor location.

10

I just realized what “Optimize” means in this context. I do not have the disk space to optimize my media for TV, and it would take forever. Since it was working, I need to find out what changed before doing anything else. Can you posit any possible explanations for why it worked a month ago, and nothing I am aware of has changed?

Can you tell me what “Upgrade media analysis during maintenance” and “Perform extensive media analysis during maintenance” means? I am assuming it means a remux…

I will look for a 1080p SDR title to try and let you know.

Thanks for you help!

11

I wanted to ask…

Isn’t there some way to tell PMS to Direct Play unconditionally? I know it works, because Mark was watching a number of 4K HDR titles before it “broke”. I thought that was what the “Transcoder → Disable video stream transcoding” was supposed to do (except for audio, I know). Why isn’t that working? Is it supposed to “Direct Play” regardless if what the heuristics decide?

What is “Disable video stream transcoding” for then?

Thanks!

12

When you add a file to the server, Plex performs an initial analysis (codec type, etc). Those options tell Plex to analyze the files in greater detail during the maintenance window. See my earlier reply that mentions DeepAnalysis.

Plex does not modify your media (no remuxing, etc).

No.

You can disable video transcoding. That does not force direct play.

If Plex cannot direct play/stream the video and video transcoding is disabled, then playback fails. Plex returns the “Not enough CPU” error message.

Think of it as “Direct Play or Don’t Play.”

It is for people with servers not powerful enough to transcode video.

It lets the server owner disable transcoding instead of having the server needlessly try to do something it cannot.

An extreme example would be PMS on a Raspberry Pi. The Pi has basically zero ability to transcode video. It just is not powerful enough. It is better to disable video transcoding then have the Pi try to do something it will never accomplish.

13

Can I run “DeepAnalysis” on a single title without scheduling it to be run on everything? I want to understand the consequences first…

What do you make of the fact that it used to work as recently as a month ago?

14

Deep Analysis is a good thing. You want Plex to analyze every file. The consequences are that Plex will work better. You cannot run it on just one file.

No idea. You provided a snippet of a log file for playing one movie. The steps I suggested may or may not resolve the problem. However, Plex uses the results of deep analysis when streaming remotely, so it needs to analyze the files.

15

Once Deep Analysis is completed on a title, it is re-scanned in furure runs, or is it “one and done”? I.e., once all are completed, are new additions the only thing rescanned? I keep my NAS off except when it is being used. The scan is running now and it looks like it will take days to complete.

So if “deep analysis” is secheduled 0:00-23:00, will it automatically deep scan as a new title is added, and only the new title?

Thanks as always for your insight!

16

Interestingly, it ran deep analysis on “Glass Onion” as the first one it did. I asked Adam, (remote user) to again try it on his shield (with the settings specified above) and “No Change”. It still tries to transcode…

I just don’t understand… The Shield is one of the most popular (and likely the most powerful) players available. We (me and my remote users) all have Fiber based 1000Mbits internet. Am I the only one experiencing this? If anyone else has a Shield TV, and if they have access to a gigabit remote server, could they try it?

It seems more like a bug to me that everything is configured correctly, but Plex erroneously tries to transcode when there is no need to.

I know it is not my media because it was working fine at the beginning. Mark was able to play my highest bitrate 4k HDR title with no problems, but now, only low bit-rate titles will play (of which I have few). Plex is virtually unusable by my remote users at this point.

Is there any way to escalate this issue? I am at a dead end!

Thanks for your understanding.

17

Please pull & upload the server log files. Let’s see what they say now that the file has been analzyed.

Also, please provide the XML Info for Glass Onion. Zip the file or save it as .txt. The forum does not support .xml attachments.

Re: timing of analysis, etc.

Plex Documentation → Scheduled Tasks has info about each task.

The tasks start at the beginning of the maintenance window. If they do not complete, they take up from where they left off during the next window.

The tasks do not run on demand. So, if you have them scheduled for 0000 to 2300, and drop in new media after they have already completed, the media will be analyzed during the following window. If you drop in new media while the tasks are running, it may not be analyzed until the following window (I’m not sure if the tasks pick up new media after they have already started).

Deep Analysis analyzes the file once. If already analyzed, it is skipped. If the file is modified or replaced, it will be analyzed again. If Plex updates Deep Analysis to a new version (which has not happened in years), then all files would be re-analyzed.

The items in Settings → Library, such as Intro Detection, Credit Detection, etc., are different. They can be configured to run (a) never, (b) as a scheduled task, or (c) as a scheduled task and when media is added.

Plex Documentation → Library has additional information for those items.

18

So, I will archive the log file and restart Plex to get a new log file including the Glass Onion entries. Then upload a log zip and the xml zip by drag-n-drop into my reply. Let me know if this is not correct.

The problem is, I have to rely on my users to start the Glass Onion on their remote Shield to get useful results, and they are hard to corrral. I will get it done as soon as they will cooperate.

Thanks for your continues support!

19

Here are the requested logs + xml files. “Adam” (the remote user), tried several times so there will be several attempts reflected in the logs. Looking forward to your analysis! Thanks!

Oops… When I drag-n-drop the .zip file into this reply, it says:
“Sorry, you can only upload 5 files at a time.”

I am dropping one file: log-xml.zip.

Please advise…

20

I tried it from a different browser (Opera) and the upload worked!
log-xml.zip (92.2 KB)

I originally tried it using Chrome.