Server Version#: 1.25.4.5426
Player Version#: Latest for Shield TV Pro
Can provide logs if helpful. Just updated my Plex Server. Using plexinc/pms-docker:plexpass via docker so ran a docker-compose down && docker-compose up -d to run the update. Server was previously running perfectly fine but now it starts buffering every 10 to 15 seconds. Looking at the logs, it’s like the client is losing its authentication and stalling out the session and then reauthenticating on its own. Stream is running as Direct Play for both video and audio. This exact file has played fine previously. No resource contention on host or VM hosting the container. No major changes to the environment since the last time the file was played, other than the update to the PMS.
Jan 21, 2022 21:16:39.678 [0x7f3938539b38] DEBUG - Request: [127.0.0.1:37658 (Loopback)] GET /identity (9 live) Signed-in
Jan 21, 2022 21:16:39.679 [0x7f393942eb38] DEBUG - Completed: [127.0.0.1:37658] 200 GET /identity (9 live) 0ms 398 bytes (pipelined: 1)
Jan 21, 2022 21:16:41.329 [0x7f393940bb38] DEBUG - Auth: authenticated user 8593606 as Tp
Jan 21, 2022 21:16:41.330 [0x7f3938539b38] DEBUG - Request: [10.0.30.101:43542 (WAN)] GET /:/timeline?audioStreamID=14809&bufferedTime=10654&duration=6963000&guid=plex%3A%2F%2Fmovie%2F5d7768244de0ee001fcc7fed&key=%2Flibrary%2Fmetadata%2F2800&playbackTime=2291098&playQueueItemID=656&ratingKey=2800&state=playing&time=3865638&token=xxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP Signed-in Token (Tp)
Jan 21, 2022 21:16:41.331 [0x7f3938539b38] DEBUG - Client [2fd524ce70d5c08c-com-plexapp-android] reporting timeline state playing, progress of 3865638/6963000ms for guid=plex://movie/5d7768244de0ee001fcc7fed, playbackTime=2291098ms ratingKey=2800 url=, key=/library/metadata/2800, containerKey=, metadataId=2800, source=
Jan 21, 2022 21:16:41.332 [0x7f3938539b38] DEBUG - Play progress on 2800 ‘Back to the Future’ - got played 3865638 ms by account 8593606!
Jan 21, 2022 21:16:41.332 [0x7f3938539b38] DEBUG - [Now] User is Tp (ID: 8593606)
Jan 21, 2022 21:16:41.332 [0x7f3938539b38] DEBUG - [Now] Device is Android (SHIELD Android TV).
Jan 21, 2022 21:16:41.332 [0x7f3938539b38] DEBUG - [Now] Profile is Android
Jan 21, 2022 21:16:41.332 [0x7f3938539b38] DEBUG - [Now] Updated play state for /library/metadata/2800.
Jan 21, 2022 21:16:41.338 [0x7f3938539b38] DEBUG - Statistics: (2fd524ce70d5c08c-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 8593606
Jan 21, 2022 21:16:41.340 [0x7f393940bb38] DEBUG - Completed: [10.0.30.101:43542] 200 GET /:/timeline?audioStreamID=14809&bufferedTime=10654&duration=6963000&guid=plex%3A%2F%2Fmovie%2F5d7768244de0ee001fcc7fed&key=%2Flibrary%2Fmetadata%2F2800&playbackTime=2291098&playQueueItemID=656&ratingKey=2800&state=playing&time=3865638&token=xxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP 10ms 782 bytes (pipelined: 43)
Jan 21, 2022 21:16:41.352 [0x7f3938381b38] DEBUG - Request: [172.18.0.2:36254 (WAN)] GET /status/sessions (9 live) TLS GZIP Signed-in Token (LF)
Jan 21, 2022 21:16:41.352 [0x7f3938381b38] DEBUG - [Now] Adding 1 sessions.
Jan 21, 2022 21:16:41.354 [0x7f393940bb38] DEBUG - Completed: [172.18.0.2:36254] 200 GET /status/sessions (9 live) TLS GZIP 1ms 4192 bytes (pipelined: 1)
Jan 21, 2022 21:16:44.270 [0x7f393940bb38] DEBUG - Completed: [10.0.30.101:43486] 200 GET /video/:/transcode/universal/session/2fd524ce70d5c08c-com-plexapp-android/base/00387.ts (8 live) TLS 19877ms 99171949 bytes (pipelined: 110)
Jan 21, 2022 21:16:44.271 [0x7f393940bb38] DEBUG - Removed transcode data consumer, active count 2 => 1
Jan 21, 2022 21:16:44.626 [0x7f3938539b38] DEBUG - Request: [10.0.30.101:43486 (WAN)] GET /video/:/transcode/universal/session/2fd524ce70d5c08c-com-plexapp-android/base/00389.ts (8 live) TLS Signed-in
Jan 21, 2022 21:16:44.627 [0x7f3938539b38] DEBUG - [Transcode/2fd524ce70d5c08c-com-plexapp-android] Asked for segment 389 from session.
Jan 21, 2022 21:16:44.627 [0x7f3938539b38] DEBUG - [Transcode/2fd524ce70d5c08c-com-plexapp-android] Returning segment 389 from session
Jan 21, 2022 21:16:44.627 [0x7f3938539b38] DEBUG - Content-Length of /transcode/Transcode/Sessions/plex-transcode-2fd524ce70d5c08c-com-plexapp-android-f32d9194-2df4-42c8-8a2f-9480e882922a/media-00389.ts is 94510319 (of total: 94510319).
Jan 21, 2022 21:16:44.894 [0x7f3938539b38] DEBUG - Request: [127.0.0.1:37664 (Loopback)] GET /identity (9 live) Signed-in
Jan 21, 2022 21:16:44.895 [0x7f393940bb38] DEBUG - Completed: [127.0.0.1:37664] 200 GET /identity (9 live) 0ms 398 bytes (pipelined: 1)
Jan 21, 2022 21:16:49.995 [0x7f393849bb38] DEBUG - Request: [127.0.0.1:37668 (Loopback)] GET /identity (9 live) Signed-in
Jan 21, 2022 21:16:49.995 [0x7f393940bb38] DEBUG - Completed: [127.0.0.1:37668] 200 GET /identity (9 live) 0ms 398 bytes (pipelined: 1)
Jan 21, 2022 21:16:51.370 [0x7f393942eb38] DEBUG - Auth: authenticated user 8593606 as Tp
Jan 21, 2022 21:16:51.373 [0x7f393849bb38] DEBUG - Request: [10.0.30.101:43542 (WAN)] GET /:/timeline?audioStreamID=14809&bufferedTime=11445&duration=6963000&guid=plex%3A%2F%2Fmovie%2F5d7768244de0ee001fcc7fed&key=%2Flibrary%2Fmetadata%2F2800&playbackTime=2301138&playQueueItemID=656&ratingKey=2800&state=playing&time=3875647&token=xxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP Signed-in Token (Tp)
Jan 21, 2022 21:16:51.374 [0x7f393849bb38] DEBUG - Client [2fd524ce70d5c08c-com-plexapp-android] reporting timeline state playing, progress of 3875647/6963000ms for guid=plex://movie/5d7768244de0ee001fcc7fed, playbackTime=2301138ms ratingKey=2800 url=, key=/library/metadata/2800, containerKey=, metadataId=2800, source=
Jan 21, 2022 21:16:51.378 [0x7f393849bb38] DEBUG - Play progress on 2800 ‘Back to the Future’ - got played 3875647 ms by account 8593606!
Jan 21, 2022 21:16:51.378 [0x7f393849bb38] DEBUG - [Now] User is Tp (ID: 8593606)
Jan 21, 2022 21:16:51.378 [0x7f393849bb38] DEBUG - [Now] Device is Android (SHIELD Android TV).
Jan 21, 2022 21:16:51.378 [0x7f393849bb38] DEBUG - [Now] Profile is Android
Jan 21, 2022 21:16:51.378 [0x7f393849bb38] DEBUG - [Now] Updated play state for /library/metadata/2800.
Jan 21, 2022 21:16:51.386 [0x7f393849bb38] DEBUG - Statistics: (2fd524ce70d5c08c-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 8593606
Jan 21, 2022 21:16:51.388 [0x7f393940bb38] DEBUG - Completed: [10.0.30.101:43542] 200 GET /:/timeline?audioStreamID=14809&bufferedTime=11445&duration=6963000&guid=plex%3A%2F%2Fmovie%2F5d7768244de0ee001fcc7fed&key=%2Flibrary%2Fmetadata%2F2800&playbackTime=2301138&playQueueItemID=656&ratingKey=2800&state=playing&time=3875647&token=xxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP 15ms 782 bytes (pipelined: 44)
Jan 21, 2022 21:16:53.783 [0x7f393942eb38] DEBUG - Completed: [10.0.30.101:43484] 200 GET /video/:/transcode/universal/session/2fd524ce70d5c08c-com-plexapp-android/base/00388.ts (8 live) TLS 19301ms 98202672 bytes (pipelined: 111)
Jan 21, 2022 21:16:53.783 [0x7f393942eb38] DEBUG - Removed transcode data consumer, active count 2 => 1
Jan 21, 2022 21:16:54.547 [0x7f393849bb38] DEBUG - Request: [10.0.30.101:43484 (WAN)] GET /video/:/transcode/universal/session/2fd524ce70d5c08c-com-plexapp-android/base/00390.ts (8 live) TLS Signed-in
Jan 21, 2022 21:16:54.547 [0x7f393849bb38] DEBUG - [Transcode/2fd524ce70d5c08c-com-plexapp-android] Asked for segment 390 from session.
Jan 21, 2022 21:16:54.547 [0x7f393849bb38] DEBUG - [Transcode/2fd524ce70d5c08c-com-plexapp-android] Returning segment 390 from session
Jan 21, 2022 21:16:54.547 [0x7f393849bb38] DEBUG - Content-Length of /transcode/Transcode/Sessions/plex-transcode-2fd524ce70d5c08c-com-plexapp-android-f32d9194-2df4-42c8-8a2f-9480e882922a/media-00390.ts is 110812044 (of total: 110812044).
Jan 21, 2022 21:16:54.697 [0x7f3938539b38] DEBUG - [Transcode] Session 2fd524ce70d5c08c-com-plexapp-android (3) is unthrottling
Jan 21, 2022 21:16:54.698 [0x7f3938381b38] DEBUG - [Transcoder] Throttle - Getting back to work.
Jan 21, 2022 21:16:55.163 [0x7f3938539b38] DEBUG - Request: [127.0.0.1:37672 (Loopback)] GET /identity (9 live) Signed-in
Jan 21, 2022 21:16:55.163 [0x7f393942eb38] DEBUG - Completed: [127.0.0.1:37672] 200 GET /identity (9 live) 0ms 398 bytes (pipelined: 1)
Jan 21, 2022 21:16:55.960 [0x7f3938381b38] DEBUG - [Transcode] Transcoder segment range: 316 - 397 (396)
Jan 21, 2022 21:16:55.968 [0x7f393849bb38] DEBUG - [Transcode] Transcoder segment range: 316 - 397 (396)
Jan 21, 2022 21:16:57.170 [0x7f3938539b38] DEBUG - [Transcode] Transcoder segment range: 316 - 397 (397)
Jan 21, 2022 21:16:57.171 [0x7f3938381b38] DEBUG - [Transcode] Transcoder segment range: 316 - 398 (397)
Jan 21, 2022 21:16:57.223 [0x7f393849bb38] DEBUG - [Transcode] Session 2fd524ce70d5c08c-com-plexapp-android (3) is throttling
Jan 21, 2022 21:16:57.223 [0x7f3938539b38] DEBUG - [Transcoder] Throttle - Going into sloth mode.
Jan 21, 2022 21:17:00.246 [0x7f3938381b38] DEBUG - Request: [127.0.0.1:37684 (Loopback)] GET /identity (9 live) Signed-in
Jan 21, 2022 21:17:00.246 [0x7f393942eb38] DEBUG - Completed: [127.0.0.1:37684] 200 GET /identity (9 live) 0ms 398 bytes (pipelined: 1)
Jan 21, 2022 21:17:01.410 [0x7f393940bb38] DEBUG - Auth: authenticated user 8593606 as Tp
Jan 21, 2022 21:17:01.411 [0x7f393849bb38] DEBUG - Request: [10.0.30.101:43542 (WAN)] GET /:/timeline?audioStreamID=14809&bufferedTime=10955&duration=6963000&guid=plex%3A%2F%2Fmovie%2F5d7768244de0ee001fcc7fed&key=%2Flibrary%2Fmetadata%2F2800&playbackTime=2311178&playQueueItemID=656&ratingKey=2800&state=playing&time=3885657&token=xxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP Signed-in Token (Tp)
Jan 21, 2022 21:17:01.412 [0x7f393849bb38] DEBUG - Client [2fd524ce70d5c08c-com-plexapp-android] reporting timeline state playing, progress of 3885657/6963000ms for guid=plex://movie/5d7768244de0ee001fcc7fed, playbackTime=2311178ms ratingKey=2800 url=, key=/library/metadata/2800, containerKey=, metadataId=2800, source=
Jan 21, 2022 21:17:01.413 [0x7f393849bb38] DEBUG - Play progress on 2800 ‘Back to the Future’ - got played 3885657 ms by account 8593606!
Jan 21, 2022 21:17:01.414 [0x7f393849bb38] DEBUG - [Now] User is Tp (ID: 8593606)
Jan 21, 2022 21:17:01.414 [0x7f393849bb38] DEBUG - [Now] Device is Android (SHIELD Android TV).
Jan 21, 2022 21:17:01.414 [0x7f393849bb38] DEBUG - [Now] Profile is Android
Jan 21, 2022 21:17:01.414 [0x7f393849bb38] DEBUG - [Now] Updated play state for /library/metadata/2800.
Jan 21, 2022 21:17:01.422 [0x7f393849bb38] DEBUG - Statistics: (2fd524ce70d5c08c-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 8593606
Jan 21, 2022 21:17:01.424 [0x7f393942eb38] DEBUG - Completed: [10.0.30.101:43542] 200 GET /:/timeline?audioStreamID=14809&bufferedTime=10955&duration=6963000&guid=plex%3A%2F%2Fmovie%2F5d7768244de0ee001fcc7fed&key=%2Flibrary%2Fmetadata%2F2800&playbackTime=2311178&playQueueItemID=656&ratingKey=2800&state=playing&time=3885657&token=xxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP 12ms 776 bytes (pipelined: 45)
Getting absolutely hammered with the above. Not sure if it’s expected, I never went through the logs when it was functioning properly. Restarted the client and the buffering has decreased from every 10 seconds to a handful of times throughout the stream. Resource contention continues to not be an issue. When the video stalls, checking glances (top/htop/etc) shows that the transcode process has completely stalled out of the process list entirely.
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
