[BUG] (re open) play next episode (implicite playlist) randomly not working (black screen)

Plex Players
1

Server Version#: 1.41.3.9314
Player Version#: 10.24.1.2319

Hello,

I’m reopening this bug report. Sometimes on my Shield (and Firestick), the next episode of a series doesn’t start. I get a black screen, and while I can bring up the player with the remote, the play/pause function doesn’t work. I’m forced to return to the Plex menu to manually start the next episode.

Apparently, this issue has existed since 2020, and I can’t find any feedback from Plex about it. It’s becoming frustrating to report bugs that have been around for several years without receiving any response…

my original post :

other subject about this bug :

2

@Atomatth

3

When the issue occurs can you please grab client and PMS logs?

4

Hi,

for the moment the problem has not reoccurred since I made sure to clear the cache of the plex application on my nvidia shield each time it comes out of sleep mode. if it happens again I will try to provide you with the logs

5

However, my users also reported the problem to me on Android. In particular, a similar case where the next video does not start and pressing the next button replays the last video played.

6

i found a log PMS, but for the client l don’t know how to get it

Dec 25, 2024 17:31:27.667 [3800] ERROR - Caught exception trying to stream file: Y:\plex-series\normal\Santa Clarita Diet (2017) {tmdb-69470} {imdb-tt5580540} {tvdb-320450}\Saison 2\Santa Clarita Diet - S02E08 - [.multi.web.x264-cielos-1080p-HDL].mkv: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 17:31:47.389 [5344] INFO - [Req#133ff9] AutoUpdate: no updates available
Dec 25, 2024 17:31:54.064 [5344] INFO - [Req#13401e] AutoUpdate: no updates available
Dec 25, 2024 17:32:03.429 [7576] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\71\71f2e8caf1bcec2aae3c8e76b5835d75db1f0238.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 17:32:08.711 [14824] ERROR - Session 0000022D019516E0 terminated
Dec 25, 2024 17:32:08.953 [15864] WARN - [Req#1340bc] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 17:32:09.245 [15864] WARN - [Req#1340d0] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 17:36:41.629 [4500] WARN - [Req#1341fa] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 17:36:41.877 [13068] WARN - [Req#134207] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:00:56.407 [11160] WARN - [Req#13478a] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:00:56.918 [12412] WARN - [Req#134792] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:04:09.179 [12308] WARN - [Req#134933] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:04:09.796 [12308] WARN - [Req#13495b] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:29:14.667 [13444] WARN - [Req#135273] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:29:15.212 [7608] WARN - [Req#13529c] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:29:26.830 [14820] ERROR - Session 0000022D7DB79980 terminated
Dec 25, 2024 18:29:27.111 [12672] WARN - [Req#1352e5] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:29:27.652 [12672] WARN - [Req#1352f7] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:40:47.451 [6788] WARN - [Req#135790] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:40:48.043 [6788] WARN - [Req#135799] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:40:54.078 [6788] INFO - [Req#1357ac] AutoUpdate: no updates available
Dec 25, 2024 18:48:04.530 [6788] INFO - [Req#1358e6] AutoUpdate: no updates available
Dec 25, 2024 18:48:05.547 [6788] WARN - [Req#1358f4] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:48:05.548 [6788] WARN - [Req#1358f4] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:48:05.564 [6788] WARN - [Req#1358f4] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:48:09.700 [10632] WARN - [Req#13592d] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:48:09.946 [14156] WARN - [Req#135934] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:48:29.759 [10632] ERROR - Caught exception trying to stream file: Y:\plex-series\normal\Santa Clarita Diet (2017) {tmdb-69470} {imdb-tt5580540} {tvdb-320450}\Saison 2\Santa Clarita Diet - S02E10 - [.final.multi.web.x264-cielos-1080p-HDL].mkv: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 18:48:36.465 [14820] ERROR - Session 0000022D7F3D7210 terminated
Dec 25, 2024 18:48:36.581 [12172] WARN - [Req#1359a7] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:48:36.808 [12172] WARN - [Req#1359ba] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:56:30.081 [13380] WARN - [Req#135b25] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 18:56:30.592 [8264] WARN - [Req#135b2c] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 19:08:54.405 [12924] WARN - [Req#136262] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 19:08:54.963 [13820] WARN - [Req#136289] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 20:49:40.838 [14824] ERROR - Session 0000022D7E143250 terminated
Dec 25, 2024 21:13:20.385 [6508] WARN - [Req#139507] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:13:20.873 [16332] WARN - [Req#13950e] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:23:14.678 [1924] WARN - [Req#1398ea] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:23:15.217 [12684] WARN - [Req#13990c] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:31:40.909 [4372] WARN - [Req#139eaa] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:31:41.420 [12216] WARN - [Req#139ecb] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:38:18.927 [12524] WARN - [Req#13a208] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:38:19.441 [12524] WARN - [Req#13a21b] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:39:18.716 [1360] ERROR - Caught exception trying to stream file: Y:\plex-series\normal\Santa Clarita Diet (2017) {tmdb-69470} {imdb-tt5580540} {tvdb-320450}\Saison 3\Santa Clarita Diet - S03E08 - [.MULTi.WEB.x264-FRATERNiTY-1080p-HDL].mkv: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 21:39:31.154 [13348] ERROR - Session 0000022D0AE85820 terminated
Dec 25, 2024 21:39:31.559 [5404] WARN - [Req#13a2f6] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:39:32.090 [10152] WARN - [Req#13a319] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:40:24.894 [8756] WARN - [Req#13a3f5] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:40:25.453 [11696] WARN - [Req#13a40e] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:51:24.176 [11080] WARN - [Req#13a82b] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:51:24.658 [1252] WARN - [Req#13a854] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:53:24.469 [12476] WARN - [Req#13a96c] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:53:24.991 [7516] WARN - [Req#13a98c] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:58:55.497 [12244] WARN - [Req#13abc7] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:59:30.392 [14440] WARN - [Req#13ac16] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:59:34.016 [228] WARN - [Req#13ac6f] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 21:59:34.411 [14440] WARN - [Req#13ac76] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:19:41.730 [14824] ERROR - Session 0000022D0AE84C20 terminated
Dec 25, 2024 22:19:42.738 [14776] WARN - [Req#13b61f] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:19:42.739 [13752] WARN - [Req#13b61e] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:19:43.167 [14776] WARN - [Req#13b632] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:19:47.721 [8404] WARN - [Req#13b656] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:19:47.969 [13752] WARN - [Req#13b65c] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:20:04.327 [14776] WARN - [Req#13b676] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:21:41.963 [7228] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\6c\6ca6a37a3782feab98279e1cfcc2d09a57757c06.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 22:21:41.963 [12892] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\6c\6ca6a37a3782feab98279e1cfcc2d09a57757c06.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 22:21:41.964 [7228] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\6c\6ca6a37a3782feab98279e1cfcc2d09a57757c06.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 22:21:42.290 [7228] WARN - [Req#13b787] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:21:46.946 [7320] WARN - [Req#13b7b6] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:21:47.318 [7228] WARN - [Req#13b7bd] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:21:57.576 [7320] WARN - [Req#13b7da] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:21:57.612 [7320] WARN - [Req#13b7e0] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:21:57.803 [7320] WARN - [Req#13b7e4] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:22:18.928 [14456] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\62\62b7dc4995e1a173200854a0bc8efeaf609a858a.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 22:22:19.173 [7228] WARN - [Req#13b81e] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:22:25.292 [14456] WARN - [Req#13b83d] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 22:22:25.594 [7228] WARN - [Req#13b844] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:02:07.127 [6752] ERROR - Caught exception trying to stream file: Y:\plex-series\normal\Teen Wolf (2011) {tmdb-34524} {imdb-tt1567432} {tvdb-175001}\Saison 1\Teen Wolf - S01E01 - [.MULTi.1080p.BluRay.H264.AC3-MBHD-HDL].mkv: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 23:02:40.294 [14232] WARN - [Req#13caf7] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:02:40.299 [14408] WARN - [Req#13caf8] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:02:40.949 [14232] WARN - [Req#13cb11] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:02:47.435 [14820] ERROR - Session 0000022D0AE847A0 terminated
Dec 25, 2024 23:02:47.835 [14408] WARN - [Req#13cb48] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:02:47.841 [14408] WARN - [Req#13cb4a] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:02:48.232 [14232] WARN - [Req#13cb54] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:28:42.686 [14232] WARN - [Req#13cfa5] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:28:43.624 [8792] WARN - [Req#13cfac] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:30:17.771 [15696] WARN - [Req#13d267] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:30:17.982 [15696] WARN - [Req#13d26e] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:59:44.172 [13492] WARN - [Req#13d804] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 25, 2024 23:59:44.676 [1504] WARN - [Req#13d832] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Dec 26, 2024 00:00:11.325 [2444] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\89\89ec18a43175b577119159fa6700a33f670e4326.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 26, 2024 00:00:11.325 [6808] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\c1\c1c52b0e2b9fa3e16cedae53dcb9ad3a5b81f034.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 26, 2024 00:00:11.326 [6808] ERROR - Caught exception trying to stream file: C:\Users\plex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\89\89ec18a43175b577119159fa6700a33f670e4326.jpg: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 26, 2024 00:00:20.928 [6808] ERROR - Caught exception trying to stream file: Y:\plex-series\normal\Teen Wolf (2011) {tmdb-34524} {imdb-tt1567432} {tvdb-175001}\Saison 1\Teen Wolf - S01E03 - [.MULTi.1080p.BluRay.H264.AC3-MBHD-HDL].mkv: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]

i think that the cause is that :

Dec 25, 2024 21:39:18.716 [1360] ERROR - Caught exception trying to stream file: Y:\plex-series\normal\Santa Clarita Diet (2017) {tmdb-69470} {imdb-tt5580540} {tvdb-320450}\Saison 3\Santa Clarita Diet - S03E08 - [.MULTi.WEB.x264-FRATERNiTY-1080p-HDL].mkv: write: protocol is shutdown (SSL routines) [asio.ssl:167772367]
Dec 25, 2024 21:39:31.154 [13348] ERROR - Session 0000022D0AE85820 terminated

does this help?

7

Nope. We’ll need the entirety of your PMS logs and Android Logs. Please make sure to enable debug logging.

8

ok when the problem reoccurs I will try to provide you with the client logs along with those of the server

9

Hi @Atomatth, the problem has reappeared:

log android tv full log:
logging.txt (4.5 MB)

log android tv extract :

01-03 01:48:16.495  i: [MarkerBehaviour] Auto skipping credits marker to 1153898.
01-03 01:48:16.511  i: [MediaSessionBehaviour] Player seeked, updating state to PLAYING
01-03 01:48:16.511  i: [FFmpegExtractor] IO seek point for 1153897999us with 0 byte position.
01-03 01:48:16.513  i: [ExoPlayer][EventLogger] positionDiscontinuity [eventTime=6734.97, mediaPos=1153.90, window=0, period=0, reason=SEEK, PositionInfo:old [mediaItem=0, period=0, pos=992484], PositionInfo:new [mediaItem=0, period=0, pos=1153897]]
01-03 01:48:16.514  i: [Player][ExoPlayer] onPositionDiscontinuity, Reason: 1
01-03 01:48:16.514  i: [ExoPlayer][EventLogger] state [eventTime=6734.97, mediaPos=1153.90, window=0, period=0, BUFFERING]
01-03 01:48:16.515  i: [EngineEventManager] onBufferingStarted (isSeeking: true)
01-03 01:48:16.516  i: [TranscodeSession] Media choice updated
01-03 01:48:16.516  i: [TranscodeSession] Pausing...
01-03 01:48:16.517  i: [ExoPlayer][EventLogger] isPlaying [eventTime=6734.97, mediaPos=1153.90, window=0, period=0, false]
01-03 01:48:16.546  i: [FFmpegExtractor] IO seek point for 1153897999us with 0 byte position.
01-03 01:48:16.656  i: [FFmpegExtractor] User-seek to 1153897999us, data will start from 0 bytes, position is 0 bytes.
01-03 01:48:16.656  i: [FFmpegExtractor] Applying user-seek to 1153897999us.
01-03 01:48:16.657  i: [FFmpeg] [INFO] [FFmpegExtractor] User-seeking context to 1153897999 us, original target was 1153897999 us.
01-03 01:48:16.658  i: [FFmpeg] [INFO] [CacheSource] Seeking in the reserve to 704407807 bytes, read position was 704477192 bytes.
01-03 01:48:16.665  i: [FFmpegExtractor] Completed user-seek to 1153897999us successfully.
01-03 01:48:16.666  i: [FFmpeg] [INFO] [CacheSource] Seeking in the reserve to 704407807 bytes, read position was 704477192 bytes.
01-03 01:48:16.792  i: [ExoPlayer][EventLogger] state [eventTime=6735.25, mediaPos=1153.90, window=0, period=0, READY]
01-03 01:48:16.793  i: [EngineEventManager] onBufferingEnded
01-03 01:48:16.793  i: [EngineEventManager] onPlaybackResumed
01-03 01:48:16.794  w: [Player][Timeline] Ignoring timeline update, as less than 8 seconds has passed since last update without any state change.
01-03 01:48:16.795  i: [PlaybackTimeBehaviour] Resuming stopwatch
01-03 01:48:16.795  i: [VideoAwakeBehaviour] Keep screen awake has been requested enabled for VideoAwakeBehaviour.
01-03 01:48:16.795  i: [AudioFocusBehaviour] Playback resumed
01-03 01:48:16.795  i: [TranscodeSession] Media choice updated
01-03 01:48:16.796  i: [TranscodeSession] Resuming...
01-03 01:48:16.797  i: [PlayQueueProgressBehaviour] Updating PlayQueueManager state, isPlaying: true
01-03 01:48:16.797  i: [Player][ExoPlayer] Passing subtitle offset of 0.
01-03 01:48:16.802  i: [WiFiLockBehaviour] Aquiring WiFi lock since playback was resumed.
01-03 01:48:16.803  i: [PictureInPictureBehaviour] Picture-in-picture is available and can be used.
01-03 01:48:16.970  i: [MediaSessionBehaviour] Updating state to PLAYING
01-03 01:48:16.972  i: [ExoPlayer][EventLogger] isPlaying [eventTime=6735.42, mediaPos=1153.91, window=0, period=0, true]
01-03 01:48:16.973  i: [ExoPlayer][EventLogger] state [eventTime=6735.42, mediaPos=1153.91, window=0, period=0, ENDED]
01-03 01:48:16.973  i: [ExoPlayerEngine] Releasing media source due to item stopped.
01-03 01:48:16.973  i: [EngineEventManager] onPlaybackStopped: Completed
01-03 01:48:16.973  i: [Player][Timeline] Playback stopped (error: false)
01-03 01:48:16.977  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320049&bufferedTime=1&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf030&key=%2Flibrary%2Fmetadata%2F68343&playbackTime=957373&playQueueItemID=77875&ratingKey=68343&state=stopped&time=1153897&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:16.982  i: [BufferingMetrics] {auto=true, bufferingDurationUnit=ms, bufferDurationFromSeeks=7, bufferingDuration=12703, bufferingCount=29, numSeeks=22}
01-03 01:48:16.987  i: [PlaybackTimeBehaviour] Playback stopped, pausing stopwatch
01-03 01:48:16.991  i: [VideoAwakeBehaviour] Keep screen awake has been requested disabled for VideoAwakeBehaviour.
01-03 01:48:16.992  i: [TranscodeSession] Stopping...
01-03 01:48:16.992  i: [TranscodeSession] Session already stopped.
01-03 01:48:16.992  i: [PlayQueueProgressBehaviour] Updating PlayQueueManager state, isPlaying: false
01-03 01:48:16.992  i: [VideoAwakeBehaviour] Disabling screen on
01-03 01:48:17.087  i: [Player][ExoPlayer] Setting given surfaceView 
01-03 01:48:17.087  i: [VideoAwakeBehaviour] Keep screen awake has been requested disabled for VideoAwakeBehaviour.
01-03 01:48:17.087  i: [DisplayBehaviour] New ExoPlayerEngine detected, enabling: false.
01-03 01:48:17.088  i: [Player][ExoPlayer] Using PlayQueueMediaSource
01-03 01:48:17.092  i: [ExoPlayerEngine] Media source has been created.
01-03 01:48:17.093  i: [MediaCodecVideoSyncRenderer] Setting allow dummy surface: false
01-03 01:48:17.093  i: [ASS][Renderer] Renderer thread being stopped due to renderer disabled.
01-03 01:48:17.094  i: [FFmpeg] [INFO] [ASS][Renderer] Clearing surface.
01-03 01:48:17.094  i: [TrackSelectorHelper] Resetting track selection
01-03 01:48:17.104  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:48:17.106  i: [Player][DimensionsLayout] Resizing: 1920 x 1080
01-03 01:48:17.109  i: [PictureInPictureBehaviour] Picture-in-picture is available and can be used.
01-03 01:48:17.110  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320049&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:17.112  i: [CurrentItemMetadataBehaviour] Fetching current item
01-03 01:48:17.115  i: [MediaSessionBehaviour] Current item has changed
01-03 01:48:17.120  i: [PlayQueueProgressBehaviour] Item completed, moving to next PlayQueue item
01-03 01:48:17.122  i: [MediaSessionBehaviour] Updating metadata for video.
01-03 01:48:17.123  i: [WiFiLockBehaviour] Releasing WiFi lock since playback was stopped.
01-03 01:48:17.142  i: [BandwidthQualityBehaviour] Recording bandwidth for server as 4,2 Mbps.
01-03 01:48:17.146  i: [FFmpeg] [INFO] [ASS][Renderer] Renderer has been released.
01-03 01:48:17.149  i: [PictureInPictureBehaviour] Picture-in-picture is available and can be used.
01-03 01:48:17.154  i: [ASS][Renderer] Renderer thread has terminated.
01-03 01:48:17.162  i: [FFmpeg] [INFO] [ASS][Renderer] Connecting surface ...
01-03 01:48:17.177  i: [CurrentItemMetadataBehaviour] Fetched current item, success: true
01-03 01:48:17.177  i: [MenuSheetHud] Item changed (and fetched), invalidating settings...
01-03 01:48:17.201  i: [MediaSessionBehaviour] Updating state to STOPPED
01-03 01:48:17.202  i: [PostPlayHud] Not showing as countdown set to immediate.
01-03 01:48:17.202  i: [ExoPlayer][EventLogger] isPlaying [eventTime=6735.65, mediaPos=0.00, window=0, period=0, false]
01-03 01:48:17.202  i: [ExoPlayer][EventLogger] surfaceSize [eventTime=6735.66, mediaPos=0.00, window=0, period=0, 0, 0]
01-03 01:48:17.202  i: [ExoPlayer][EventLogger] state [eventTime=6735.66, mediaPos=0.00, window=0, period=0, IDLE]
01-03 01:48:17.202  i: [ExoPlayer][EventLogger] timeline [eventTime=6735.66, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
01-03 01:48:17.203  i: [ExoPlayer][EventLogger]   period [?]
01-03 01:48:17.203  i: [ExoPlayer][EventLogger]   window [?, seekable=false, dynamic=true]
01-03 01:48:17.203  i: [ExoPlayer][EventLogger] ]
01-03 01:48:17.203  i: [Player][ExoPlayer] onTimelineChanged (Position: 0 ms, Duration: 0 ms)
01-03 01:48:17.203  i: [ExoPlayer][EventLogger] positionDiscontinuity [eventTime=6735.66, mediaPos=0.00, window=0, reason=REMOVE, PositionInfo:old [mediaItem=0, period=0, pos=1153912], PositionInfo:new [mediaItem=0, period=-1, pos=0]]
01-03 01:48:17.204  i: [Player][ExoPlayer] onPositionDiscontinuity, Reason: 4
01-03 01:48:17.204  i: [ExoPlayer][EventLogger] mediaItem [eventTime=6735.66, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
01-03 01:48:17.204  i: [ExoPlayer][EventLogger] tracks [eventTime=6735.66, mediaPos=0.00, window=0
01-03 01:48:17.204  i: [ExoPlayer][EventLogger] ]
01-03 01:48:17.204  i: [Player][ExoPlayer] Track information has been changed.
01-03 01:48:17.205  i: [Player][ExoPlayer] Ignoring track selection.
01-03 01:48:17.205  i: [ExoPlayer][EventLogger] timeline [eventTime=6735.66, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
01-03 01:48:17.205  i: [ExoPlayer][EventLogger]   period [?]
01-03 01:48:17.205  i: [ExoPlayer][EventLogger]   window [?, seekable=false, dynamic=true]
01-03 01:48:17.205  i: [ExoPlayer][EventLogger] ]
01-03 01:48:17.206  i: [Player][ExoPlayer] onTimelineChanged (Position: 0 ms, Duration: 0 ms)
01-03 01:48:17.206  i: [ExoPlayer][EventLogger] mediaItem [eventTime=6735.66, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
01-03 01:48:17.206  i: [ExoPlayer][EventLogger] state [eventTime=6735.66, mediaPos=0.00, window=0, BUFFERING]
01-03 01:48:17.206  i: [EngineEventManager] onBufferingStarted (isSeeking: false)
01-03 01:48:17.206  i: [Player][Timeline] Playback started (or buffering), scheduling updates
01-03 01:48:17.207  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:48:17.207  i: [TranscodeSession] Pausing...
01-03 01:48:17.207  i: [ExoPlayer][EventLogger] videoDisabled [eventTime=6735.66, mediaPos=0.00, window=0, period=0]
01-03 01:48:17.207  i: [ExoPlayer][EventLogger] videoSize [eventTime=6735.66, mediaPos=0.00, window=0, period=0, 0, 0]
01-03 01:48:17.207  w: [Player][ExoPlayer] Video size changed to something undefined: 0/0
01-03 01:48:17.208  i: [ExoPlayer][EventLogger] audioDisabled [eventTime=6735.66, mediaPos=0.00, window=0, period=0]
01-03 01:48:17.208  i: [ExoPlayer][EventLogger] surfaceSize [eventTime=6735.66, mediaPos=0.00, window=0, period=0, 1920, 1080]
01-03 01:48:17.211  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:17.226  i: [FFmpeg] [INFO] [ASS][Renderer] EGL2 renderer created.
01-03 01:48:17.227  i: [AndroidHomeContentEngageBehaviour] app in foreground, scheduling a pending update ITEM_UPDATED_OR_REMOVED
01-03 01:48:17.227  i: [PreplayViewModel] Refreshing metadata for Saison 1
01-03 01:48:17.229  i: [FFmpeg] [INFO] [ASS][Decoder] Video size has been updated to 1920x1080 (1.000000).
01-03 01:48:17.229  i: [ASS][Renderer] Renderer starting.
01-03 01:48:17.229  i: Fetching [method:GET] https://192.168.1.19:32400/hubs/sections/2?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:17.232  i: [FFmpeg] [INFO] [ASS][Decoder] Frame size has been updated to 1920x1080.
01-03 01:48:17.374  i: [SectionHubManager] Notifying listeners with status: SUCCESS
01-03 01:48:18.283  i: [Preplay] Notifying listeners with status: SUCCESS
01-03 01:48:18.284  i: Fetching [method:GET] https://192.168.1.19:32400/library/metadata/68336/related?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:18.291  i: [Preplay] Notifying listeners with status: SUCCESS
01-03 01:48:18.688  i: Fetching [method:GET] https://192.168.1.19:32400/library/metadata/68337/related?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:18.695  i: [Preplay] Notifying listeners with status: EMPTY
01-03 01:48:27.243  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:48:27.247  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&timeStalled=10&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:30.929  i: [InteractionBehaviour] Woke up, reason: Interface visibility has been toggled..
01-03 01:48:30.932  i: [SystemUIBehaviour] Showing the system user-interface.
01-03 01:48:34.807  i: [InteractionBehaviour] Slept, reason: Timeout reached.
01-03 01:48:34.807  i: [SystemUIBehaviour] Hiding the system user-interface.
01-03 01:48:36.227  i: [InteractionBehaviour] Woke up, reason: Interface visibility has been toggled..
01-03 01:48:36.228  i: [SystemUIBehaviour] Showing the system user-interface.
01-03 01:48:37.285  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:48:37.289  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&timeStalled=20&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:39.808  i: [InteractionBehaviour] Slept, reason: Timeout reached.
01-03 01:48:39.809  i: [SystemUIBehaviour] Hiding the system user-interface.
01-03 01:48:47.299  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:48:47.303  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&timeStalled=30&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:48:57.339  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:48:57.344  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&timeStalled=40&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:07.379  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:49:07.385  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&timeStalled=50&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:07.954  i: [InteractionBehaviour] Woke up, reason: Interface visibility has been toggled..
01-03 01:49:07.955  i: [SystemUIBehaviour] Showing the system user-interface.
01-03 01:49:08.950  i: [Player][Hud][Video] Play clicked.
01-03 01:49:08.950  i: [MediaSessionBehaviour] Player seeked, updating state to PAUSED
01-03 01:49:08.951  i: [ExoPlayer][EventLogger] positionDiscontinuity [eventTime=6787.40, mediaPos=0.00, window=0, reason=SEEK, PositionInfo:old [mediaItem=0, period=0, pos=0], PositionInfo:new [mediaItem=0, period=0, pos=0]]
01-03 01:49:08.953  i: [Player][ExoPlayer] onPositionDiscontinuity, Reason: 1
01-03 01:49:12.817  i: [InteractionBehaviour] Slept, reason: Timeout reached.
01-03 01:49:12.818  i: [SystemUIBehaviour] Hiding the system user-interface.
01-03 01:49:17.423  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:49:17.428  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&timeStalled=60&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:27.463  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:49:27.467  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=buffering&time=0&timeStalled=70&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:35.051  i: [InteractionBehaviour] Woke up, reason: Interface visibility has been toggled..
01-03 01:49:35.052  i: [SystemUIBehaviour] Showing the system user-interface.
01-03 01:49:36.101  i: [Player][Hud][Video] Play clicked.
01-03 01:49:36.102  i: [MediaSessionBehaviour] Player seeked, updating state to PAUSED
01-03 01:49:36.103  i: [ExoPlayer][EventLogger] positionDiscontinuity [eventTime=6814.56, mediaPos=0.00, window=0, reason=SEEK, PositionInfo:old [mediaItem=0, period=0, pos=0], PositionInfo:new [mediaItem=0, period=0, pos=0]]
01-03 01:49:36.103  i: [Player][ExoPlayer] onPositionDiscontinuity, Reason: 1
01-03 01:49:37.480  i: [EngineEventManager] onPlaybackStopped: Skipped
01-03 01:49:37.480  i: [Player][Timeline] Playback stopped (error: false)
01-03 01:49:37.481  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:49:37.482  i: [VideoAwakeBehaviour] Keep screen awake has been requested disabled for VideoAwakeBehaviour.
01-03 01:49:37.483  i: [TranscodeSession] Stopping...
01-03 01:49:37.484  i: [TranscodeSession] Session already stopped.
01-03 01:49:37.484  i: [PlayQueueProgressBehaviour] Updating PlayQueueManager state, isPlaying: false
01-03 01:49:37.486  i: [WiFiLockBehaviour] Releasing WiFi lock since playback was stopped.
01-03 01:49:37.487  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02f&key=%2Flibrary%2Fmetadata%2F68344&playbackTime=957386&playQueueItemID=77876&ratingKey=68344&state=stopped&time=0&timeStalled=80&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:37.488  i: [BandwidthQualityBehaviour] Recording bandwidth for server as 4,2 Mbps.
01-03 01:49:37.566  i: [PreplayViewModel] Refreshing metadata for Saison 1
01-03 01:49:37.568  i: Fetching [method:GET] https://192.168.1.19:32400/hubs/sections/2?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:37.569  i: [AndroidHomeContentEngageBehaviour] app in foreground, scheduling a pending update PLAYED_ITEMS_CHANGED
01-03 01:49:37.570  i: [MediaSessionBehaviour] Updating state to STOPPED
01-03 01:49:37.599  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:49:37.602  i: [CurrentItemMetadataBehaviour] Fetching current item
01-03 01:49:37.604  i: [PictureInPictureBehaviour] Picture-in-picture is available and can be used.
01-03 01:49:37.606  i: [MediaSessionBehaviour] Current item has changed
01-03 01:49:37.606  i: [MediaSessionBehaviour] Updating metadata for video.
01-03 01:49:37.609  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02e&key=%2Flibrary%2Fmetadata%2F68345&playbackTime=957386&playQueueItemID=77877&ratingKey=68345&state=buffering&time=0&timeStalled=80&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:37.617  i: [PictureInPictureBehaviour] Picture-in-picture is available and can be used.
01-03 01:49:37.650  i: [CurrentItemMetadataBehaviour] Fetched current item, success: true
01-03 01:49:37.651  i: [MenuSheetHud] Item changed (and fetched), invalidating settings...
01-03 01:49:37.727  i: [SectionHubManager] Notifying listeners with status: SUCCESS
01-03 01:49:38.495  i: [Preplay] Notifying listeners with status: SUCCESS
01-03 01:49:38.497  i: Fetching [method:GET] https://192.168.1.19:32400/library/metadata/68336/related?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:38.501  i: [Preplay] Notifying listeners with status: SUCCESS
01-03 01:49:38.894  i: Fetching [method:GET] https://192.168.1.19:32400/library/metadata/68337/related?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:38.898  i: [Preplay] Notifying listeners with status: EMPTY
01-03 01:49:39.638  e: [ExoPlayer][EventLogger] playerFailed [eventTime=6818.09, mediaPos=0.00, window=0, errorCode=ERROR_CODE_TIMEOUT
  androidx.media3.exoplayer.ExoPlaybackException: Unexpected runtime error
      at androidx.media3.exoplayer.ExoPlayerImpl.setVideoOutputInternal(SourceFile:108)
      at androidx.media3.exoplayer.ExoPlayerImpl.setVideoSurfaceHolder(SourceFile:55)
      at ci.s0.u2(SourceFile:44)
      at ci.s0.m1(SourceFile:1)
      at ci.m.invoke(SourceFile:2)
      at ci.s0.O1(SourceFile:28)
      at ci.s0.E2(SourceFile:18)
      at ci.s0.l2(SourceFile:21)
      at ci.s0.y1(SourceFile:1)
      at ci.n0.invoke(SourceFile:2)
      at ci.s0.X1(SourceFile:3)
      at ci.s0.o1(SourceFile:1)
      at ci.p.run(SourceFile:1)
      at android.os.Handler.handleCallback(Handler.java:938)
      at android.os.Handler.dispatchMessage(Handler.java:99)
      at android.os.Looper.loop(Looper.java:223)
      at android.os.HandlerThread.run(HandlerThread.java:67)
  Caused by: androidx.media3.exoplayer.ExoTimeoutException: Detaching surface timed out.
      at androidx.media3.exoplayer.ExoPlayerImpl.setVideoOutputInternal(SourceFile:103)
      ... 16 more
]
01-03 01:49:39.639  e: [Player][ExoPlayer] Playback error detected
01-03 01:49:39.640  e: An exception occurred: androidx.media3.exoplayer.ExoPlaybackException: Unexpected runtime error
01-03 01:49:39.641  i: [Player][Timeline] Handling player error
01-03 01:49:39.641  i: [Player][Timeline] Playback stopped (error: true)
01-03 01:49:39.641  e: Stacktrace: androidx.media3.exoplayer.ExoPlaybackException: Unexpected runtime error
	at androidx.media3.exoplayer.ExoPlayerImpl.setVideoOutputInternal(SourceFile:108)
	at androidx.media3.exoplayer.ExoPlayerImpl.setVideoSurfaceHolder(SourceFile:55)
	at ci.s0.u2(SourceFile:44)
	at ci.s0.m1(SourceFile:1)
	at ci.m.invoke(SourceFile:2)
	at ci.s0.O1(SourceFile:28)
	at ci.s0.E2(SourceFile:18)
	at ci.s0.l2(SourceFile:21)
	at ci.s0.y1(SourceFile:1)
	at ci.n0.invoke(SourceFile:2)
	at ci.s0.X1(SourceFile:3)
	at ci.s0.o1(SourceFile:1)
	at ci.p.run(SourceFile:1)
	at android.os.Handler.handleCallback(Handler.java:938)
	at android.os.Handler.dispatchMessage(Handler.java:99)
	at android.os.Looper.loop(Looper.java:223)
	at android.os.HandlerThread.run(HandlerThread.java:67)
Caused by: androidx.media3.exoplayer.ExoTimeoutException: Detaching surface timed out.
	at androidx.media3.exoplayer.ExoPlayerImpl.setVideoOutputInternal(SourceFile:103)
	... 16 more

01-03 01:49:39.642  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:49:39.644  i: [ExoPlayer][EventLogger] state [eventTime=6818.10, mediaPos=0.00, window=0, IDLE]
01-03 01:49:39.644  i: [ExoPlayer][EventLogger] surfaceSize [eventTime=6818.10, mediaPos=0.00, window=0, period=0, 0, 0]
01-03 01:49:39.645  i: [Player][ExoPlayer] Setting given surfaceView 
01-03 01:49:39.645  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02e&key=%2Flibrary%2Fmetadata%2F68345&playbackTime=957386&playQueueItemID=77877&ratingKey=68345&state=stopped&time=0&timeStalled=82&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:39.647  i: [VideoAwakeBehaviour] Keep screen awake has been requested disabled for VideoAwakeBehaviour.
01-03 01:49:39.648  i: [DisplayBehaviour] New ExoPlayerEngine detected, enabling: false.
01-03 01:49:39.648  i: [Player][ExoPlayer] Using PlayQueueMediaSource
01-03 01:49:39.649  i: [ExoPlayerEngine] Media source has been created.
01-03 01:49:39.649  i: [MediaCodecVideoSyncRenderer] Setting allow dummy surface: false
01-03 01:49:39.649  i: [ExoPlayer][EventLogger] timeline [eventTime=6818.10, mediaPos=0.00, window=0, periodCount=0, windowCount=0, reason=PLAYLIST_CHANGED
01-03 01:49:39.650  i: [ExoPlayer][EventLogger] ]
01-03 01:49:39.651  i: [Player][ExoPlayer] onTimelineChanged (Position: 0 ms, Duration: 0 ms)
01-03 01:49:39.652  i: [ExoPlayer][EventLogger] positionDiscontinuity [eventTime=6818.10, mediaPos=0.00, window=0, reason=REMOVE, PositionInfo:old [mediaItem=0, period=0, pos=0], PositionInfo:new [mediaItem=0, period=-1, pos=0]]
01-03 01:49:39.653  i: [Player][ExoPlayer] onPositionDiscontinuity, Reason: 4
01-03 01:49:39.653  i: [ExoPlayer][EventLogger] mediaItem [eventTime=6818.10, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
01-03 01:49:39.653  i: [ExoPlayer][EventLogger] timeline [eventTime=6818.10, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
01-03 01:49:39.653  i: [ExoPlayer][EventLogger]   period [?]
01-03 01:49:39.654  i: [ExoPlayer][EventLogger]   window [?, seekable=false, dynamic=true]
01-03 01:49:39.654  i: [ExoPlayer][EventLogger] ]
01-03 01:49:39.654  i: [Player][ExoPlayer] onTimelineChanged (Position: 0 ms, Duration: 0 ms)
01-03 01:49:39.654  i: [ExoPlayer][EventLogger] mediaItem [eventTime=6818.10, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
01-03 01:49:39.655  i: [ExoPlayer][EventLogger] state [eventTime=6818.11, mediaPos=0.00, window=0, BUFFERING]
01-03 01:49:39.656  i: [ExoPlayer][EventLogger] surfaceSize [eventTime=6818.11, mediaPos=0.00, window=0, period=0, 1920, 1080]
01-03 01:49:39.658  i: [Player][DimensionsLayout] Resizing: 1920 x 1080
01-03 01:49:39.658  i: [Player][Timeline] Player error not known by server, reporting original player error (or a playback interrupted one)
01-03 01:49:39.658  i: [Player] Error reported: UnknownError (null)
01-03 01:49:39.658  i: [Player][Timeline] Playback stopped (error: false)
01-03 01:49:39.659  w: [Player][Timeline] player position was negative. Position time will be reported as 0.
01-03 01:49:39.660  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=320058&bufferedTime=0&context=source%3Ahub.tv.recentlyadded&duration=1153898&guid=plex%3A%2F%2Fepisode%2F66c667d37e06cbe8305bf02e&key=%2Flibrary%2Fmetadata%2F68345&playbackTime=957386&playQueueItemID=77877&ratingKey=68345&state=stopped&time=0&timeStalled=82&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:39.664  i: [TranscodeSession] Stopping...
01-03 01:49:39.665  i: [TranscodeSession] Session already stopped.
01-03 01:49:39.672  i: [Player][Error] Displaying player error...
01-03 01:49:39.680  i: [DialogBehaviour] Showing dialog.
01-03 01:49:43.825  i: [InteractionBehaviour] Slept, reason: Timeout reached.
01-03 01:49:43.826  i: [SystemUIBehaviour] Hiding the system user-interface.
01-03 01:49:45.952  i: [Player] Engine being destroyed due to player deconstruction.
01-03 01:49:45.954  i: [ExoPlayerEngine] Releasing media source due to engine destruction.
01-03 01:49:45.955  i: [ExoPlayer][EventLogger] state [eventTime=6824.41, mediaPos=0.00, window=0, IDLE]
01-03 01:49:45.955  i: [ExoPlayer][ExoPlayerImpl] Release 7da610f [AndroidXMedia3/1.1.0] [mdarcy, SHIELD Android TV, NVIDIA, 30] [media3.common, media3.exoplayer, media3.decoder, media3.datasource, media3.extractor]
01-03 01:49:45.956  i: [Player] Starting to clear huds on player destroy. Activity finishing: false, should finish activity: true
01-03 01:49:45.961  i: [MemoryOptimisationBehaviour] Expanding memory cache.
01-03 01:49:45.961  i: [VideoAwakeBehaviour] Keep screen awake has been requested disabled for VideoAwakeBehaviour.
01-03 01:49:45.961  i: [AudioFocusBehaviour] Behaviour being destroyed, giving up audio focus
01-03 01:49:45.963  i: [AudioFocusBehaviour] Given up focus.
01-03 01:49:45.963  i: [DisplayBehaviour] Reverting back to the original display mode.
01-03 01:49:45.963  i: [DisplayBehaviour] Current Mode ID: 17 / {id=17, width=1920, height=1080, fps=59.94006}
01-03 01:49:45.963  i: [DisplayBehaviour] Mode is already set to our best mode of 1920x1080 @ 59,9401Hz (17).
01-03 01:49:45.964  i: [MediaSessionBehaviour] Releasing MediaSessionHelper
01-03 01:49:45.965  i: [MediaSessionHelper] Releasing media session with tag: video
01-03 01:49:46.183  i: [PlayerService] Terminating service from stop request.
01-03 01:49:46.225  i: [PlayerService] Service been destroyed.
01-03 01:49:46.270  i: [ActivityBackgroundBehaviour] Canceling Playback: Starting new playback from onStart.
01-03 01:49:46.272  i: Resuming HomeActivityTV.
01-03 01:49:46.501  e: [ExoPlayer][EventLogger] playerFailed [eventTime=6824.95, mediaPos=0.00, window=0, errorCode=ERROR_CODE_TIMEOUT
  androidx.media3.exoplayer.ExoPlaybackException: Unexpected runtime error
      at androidx.media3.exoplayer.ExoPlayerImpl.lambda$release$5(SourceFile:9)
      at androidx.media3.exoplayer.ExoPlayerImpl.A(SourceFile:1)
      at androidx.media3.exoplayer.j1.invoke(SourceFile:1)
      at androidx.media3.common.util.ListenerSet$ListenerHolder.invoke(SourceFile:18)
      at androidx.media3.common.util.ListenerSet.lambda$queueEvent$0(SourceFile:17)
      at androidx.media3.common.util.ListenerSet.a(SourceFile:1)
      at androidx.media3.common.util.d.run(SourceFile:1)
      at androidx.media3.common.util.ListenerSet.flushEvents(SourceFile:68)
      at androidx.media3.common.util.ListenerSet.sendEvent(SourceFile:4)
      at androidx.media3.exoplayer.ExoPlayerImpl.release(SourceFile:130)
      at ci.s0.U1(SourceFile:24)
      at ci.s0.z1(SourceFile:1)
      at ci.v.invoke(SourceFile:2)
      at ci.s0.X1(SourceFile:3)
      at ci.s0.o1(SourceFile:1)
      at ci.p.run(SourceFile:1)
      at android.os.Handler.handleCallback(Handler.java:938)
      at android.os.Handler.dispatchMessage(Handler.java:99)
      at android.os.Looper.loop(Looper.java:223)
      at android.os.HandlerThread.run(HandlerThread.java:67)
  Caused by: androidx.media3.exoplayer.ExoTimeoutException: Player release timed out.
      at androidx.media3.exoplayer.ExoPlayerImpl.lambda$release$5(SourceFile:4)
      ... 19 more
]
01-03 01:49:46.502  w: [Player][ExoPlayer] Error occurred whilst engine is being destroyed, ignoring.
01-03 01:49:46.944  i: [ASS][Renderer] Surface has been destroyed, disconnecting.
01-03 01:49:46.966  i: [Player] Starting to clear huds on view detach. Activity finishing: null
01-03 01:49:50.885  i: Fetching [method:GET] https://192.168.1.19:32400/library/metadata/68338/related?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:50.892  i: [Preplay] Notifying listeners with status: EMPTY
01-03 01:49:52.518  i: Fetching [method:GET] https://192.168.1.19:32400/library/metadata/68337/related?count=25&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-03 01:49:52.522  i: [Preplay] Notifying listeners with status: EMPTY
01-03 01:49:55.542  i: Creating SettingsActivity.
01-03 01:49:55.542  i: [Activity] Resuming the application, attempting to download item and children.
01-03 01:49:55.543  i: [Boot] SettingsActivity took 0ms to run create behaviours.
01-03 01:49:55.597  i: [Boot] g took 0ms to run create behaviours.
01-03 01:49:55.598  i: [ActivityBackgroundBehaviour] Canceling Playback: Starting new playback from onStart.
01-03 01:49:55.600  i: Resuming SettingsActivity.
01-03 01:49:56.278  i: [ActivityBackgroundBehaviour] Canceling Playback: Stopping playback from onStop.
01-03 01:50:05.425  i: [UserAction] Preference 'Log réseau' changed to true
01-03 01:50:05.427  i: ------------------------------
01-03 01:50:05.427  i: Hello, Plex for Android world (debug: false)!
01-03 01:50:05.428  i: App version: 10.24.1.2319 (964727025)
01-03 01:50:05.428  i: Nano server version: 1.40.2.8395-c67dce28e
01-03 01:50:05.428  i: FFmpeg version: 2.0-ad474602b33
01-03 01:50:05.428  i: Treble version: 2.1.0.587
01-03 01:50:05.428  i: ASS version: 0.16.0
01-03 01:50:05.429  i: Manufacturer: NVIDIA  Device: mdarcy  Model: SHIELD Android TV  Product: mdarcy  Version: 11
01-03 01:50:05.429  i: Screen size: Large Screen density: XHigh Resolution: 1920x1080 DPI: 320 Touchscreen: false  Marketplace: com.android.vending  Architecture: arm64-v8a
01-03 01:50:44.510  i: [HttpServer] /192.168.1.10:2080 - GET /logging

plex media server log i have only that :

Jan 03, 2025 01:48:19.513 [2076] WARN - [Req#332740] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jan 03, 2025 01:48:20.004 [2076] WARN - [Req#33274b] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jan 03, 2025 01:49:39.788 [15284] WARN - [Req#332855] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jan 03, 2025 01:49:40.253 [15284] WARN - [Req#332865] QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
10

another log

at : 01-05 15:04:12.580

player nvidia shield full log :
logging.txt (4.3 MB)

player nvidia shield :

Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:03:31.117  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=52739&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=631725&playQueueItemID=81092&ratingKey=14263&state=playing&time=1297800&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:03:41.158  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=44314&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=641766&playQueueItemID=81092&ratingKey=14263&state=playing&time=1307807&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:03:51.179  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=36866&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=651787&playQueueItemID=81092&ratingKey=14263&state=playing&time=1317885&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:04:01.221  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=31338&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=661829&playQueueItemID=81092&ratingKey=14263&state=playing&time=1327876&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:04:11.264  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=36913&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=671872&playQueueItemID=81092&ratingKey=14263&state=playing&time=1337984&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:04:12.580  i: [MarkerBehaviour] Auto skipping credits marker to 1420086.
01-05 15:04:12.580  i: [MediaSessionBehaviour] Player seeked, updating state to PLAYING
01-05 15:04:12.581  i: [FFmpegExtractor] IO seek point for 1420085999us with 801503312 byte position.
01-05 15:04:12.581  i: [ExoPlayer][EventLogger] positionDiscontinuity [eventTime=1375.53, mediaPos=1420.08, window=0, period=0, reason=SEEK, PositionInfo:old [mediaItem=0, period=0, pos=1339386], PositionInfo:new [mediaItem=0, period=0, pos=1420085]]
01-05 15:04:12.582  i: [Player][ExoPlayer] onPositionDiscontinuity, Reason: 1
01-05 15:04:12.582  i: [ExoPlayer][EventLogger] state [eventTime=1375.53, mediaPos=1420.08, window=0, period=0, BUFFERING]
01-05 15:04:12.583  i: [EngineEventManager] onBufferingStarted (isSeeking: true)
01-05 15:04:12.600  i: [TranscodeSession] Media choice updated
01-05 15:04:12.600  i: [TranscodeSession] Pausing...
01-05 15:04:12.600  i: [ExoPlayer][EventLogger] isPlaying [eventTime=1375.53, mediaPos=1420.08, window=0, period=0, false]
01-05 15:04:12.684  i: [FFmpegExtractor] IO seek point for 1420085999us with 801503312 byte position.
01-05 15:04:12.698  i: [FFmpegExtractor] User-seek to 1420085999us, data will start from 801503312 bytes, position is 801503312 bytes.
01-05 15:04:12.699  i: [FFmpegExtractor] Applying user-seek to 1420085999us.
01-05 15:04:12.702  i: [FFmpeg] [INFO] [FFmpegExtractor] User-seeking context to 1420085999 us, original target was 1420085999 us.
01-05 15:04:12.702  i: [FFmpeg] [INFO] [CacheSource] Seeked forward in the buffer to 841755654 bytes, read position was 798245003 bytes.
01-05 15:04:12.702  i: [FFmpeg] [ERROR] [matroska,webm @ 0x31136b3bf0] Element at 0x322c2c34 ending at 0x268639e499a exceeds containing master element ending at 0x323af9f4

01-05 15:04:13.655  i: [FFmpegExtractor] Informed demuxer about the end of input at 842725876 bytes.
01-05 15:04:13.656  i: [FFmpegExtractor] Informed demuxer about the end of input at 842725876 bytes.
01-05 15:04:13.656  i: [FFmpeg] [INFO] [CacheSource] End of source has been reached at 842725876 bytes (size is 842725876 bytes).
01-05 15:04:13.657  i: [FFmpeg] [ERROR] [matroska,webm @ 0x31136b3bf0] 0x00 at pos 895154675 (0x355af9f3) invalid as first byte of an EBML number

01-05 15:04:21.301  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=681909&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=8&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:04:31.341  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=691949&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=18&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:04:41.382  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=701991&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=28&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:04:51.423  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=712030&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=38&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:04:57.645  i: [InteractionBehaviour] Woke up, reason: Interface visibility has been toggled..
01-05 15:04:57.646  i: [SystemUIBehaviour] Showing the system user-interface.
01-05 15:04:59.918  i: [TVDeckController] Switching to 2 deck from 0 deck.
01-05 15:04:59.919  i: [TVDeckController] Scrolling deck view to -496,000000 to bring deck 2 into view.
01-05 15:05:01.465  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=722073&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=48&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:05:03.458  i: [InteractionBehaviour] Slept, reason: Timeout reached.
01-05 15:05:03.467  i: [SystemUIBehaviour] Hiding the system user-interface.
01-05 15:05:11.501  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=732110&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=58&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:05:21.541  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=742149&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=68&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:05:31.581  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=752190&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=78&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:05:41.622  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=762230&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=89&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:05:51.665  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=772270&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=99&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:06:01.710  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=782316&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=109&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:06:11.754  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=792359&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=119&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:06:21.789  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=802397&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=129&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:06:31.829  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=812437&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=139&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:06:41.875  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=822483&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=149&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:06:51.913  i: Fetching [method:GET] https://192.168.1.19:32400/:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=832521&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=159&token=...zXQw&X-Plex-Client-Identifier=b7db157f52141043-com-plexapp-android&X-Plex-Token=...zXQw&X-Plex-Language=fr-fr
01-05 15:06:56.480  i: [HttpServer] /192.168.1.10:2190 - GET /logging
01-05 15:06:59.832  i: [HttpServer] /192.168.1.10:2225 - GET /favicon.ico
1 Like
11

plex media server full log debug :
Plex Media Server.log (4.7 MB)

plex media server debug extract :

an 05, 2025 15:04:00.470 [13744] DEBUG - Using X-Forwarded-For: 90.9.227.193 as remote address
Jan 05, 2025 15:04:00.470 [13744] DEBUG - Auth: authenticated user 382971569 as XXXXX75
Jan 05, 2025 15:04:00.470 [12940] DEBUG - Request: [192.168.1.25:33718 (WAN)] GET /:/timeline?ratingKey=68372&key=%2Flibrary%2Fmetadata%2F68372&playbackTime=6667824&playQueueItemID=80934&state=paused&hasMDE=1&context=source%3Acontent.library&row=0&col=1&time=6638000&duration=6829000 (22 live) #6e53d TLS GZIP Signed-in Token (XXXXX75)
Jan 05, 2025 15:04:00.470 [12940] DEBUG - [Req#6e53d] Client [wpc3jdbf7olly9mn1eyq2jl9] reporting timeline state paused, progress of 6638000/6829000ms for guid=, playbackTime=6667824ms ratingKey=68372 url=, key=/library/metadata/68372, containerKey=, metadataId=68372, source=
Jan 05, 2025 15:04:00.471 [12940] DEBUG - [Req#6e53d] [Now] User is XXXXX75 (ID: 382971569)
Jan 05, 2025 15:04:00.471 [12940] DEBUG - [Req#6e53d] [Now] Device is windows (kei-pc).
Jan 05, 2025 15:04:00.471 [12940] DEBUG - [Req#6e53d] [Now] Profile is Plex Desktop
Jan 05, 2025 15:04:00.471 [12940] DEBUG - [Req#6e53d] [Now] Updated play state for /library/metadata/68372.
Jan 05, 2025 15:04:00.471 [12940] DEBUG - [Req#6e53d] Statistics: (5jt3r2vjqopzvnlrbe4vtkj3) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 382971569
Jan 05, 2025 15:04:00.471 [13740] DEBUG - Completed: [192.168.1.25:33718] 200 GET /:/timeline?ratingKey=68372&key=%2Flibrary%2Fmetadata%2F68372&playbackTime=6667824&playQueueItemID=80934&state=paused&hasMDE=1&context=source%3Acontent.library&row=0&col=1&time=6638000&duration=6829000 (22 live) #6e53d TLS GZIP 1ms 379 bytes
Jan 05, 2025 15:04:00.889 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53860: End of file
Jan 05, 2025 15:04:01.076 [13744] DEBUG - Request came in with unrecognized domain / IP 'plex.xxx.fr' in header Host; treating as non-local
Jan 05, 2025 15:04:01.077 [12704] DEBUG - Request: [192.168.1.25:33726 (Allowed Network (Subnet))] GET /status/sessions (23 live) #6e54b TLS GZIP Signed-in Token (xxxx.plex) (61dd703437f8 (Tautulli))
Jan 05, 2025 15:04:01.077 [12704] DEBUG - [Req#6e54b] [Now] Adding 3 sessions.
Jan 05, 2025 15:04:01.077 [13740] DEBUG - Completed: [192.168.1.25:33726] 200 GET /status/sessions (23 live) #6e54b TLS GZIP 1ms 8139 bytes
Jan 05, 2025 15:04:02.522 [12940] DEBUG - Request: [192.168.1.86:44774 (Allowed Network (Subnet))] GET /:/timeline?audioStreamID=68631&bufferedTime=31338&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=661829&playQueueItemID=81092&ratingKey=14263&state=playing&time=1327876&token=xxxxxxxxxxxxxxxxxxxx (22 live) #6e52b TLS GZIP Signed-in Token (xxxx.plex) (SHIELD Salon)
Jan 05, 2025 15:04:02.523 [12940] DEBUG - [Req#6e52b] Client [b7db157f52141043-com-plexapp-android] reporting timeline state playing, progress of 1327876/1420086ms for guid=plex://episode/657b28d3351a0a707e0bbcdf, playbackTime=661829ms ratingKey=14263 url=, key=/library/metadata/14263, containerKey=, metadataId=14263, source=
Jan 05, 2025 15:04:02.523 [12940] DEBUG - [Req#6e52b] [Now] User is xxxx.plex (ID: 1)
Jan 05, 2025 15:04:02.523 [12940] DEBUG - [Req#6e52b] [Now] Device is Android (SHIELD Salon).
Jan 05, 2025 15:04:02.523 [12940] DEBUG - [Req#6e52b] [Now] Profile is Android
Jan 05, 2025 15:04:02.523 [12940] DEBUG - [Req#6e52b] [Now] Updated play state for /library/metadata/14263.
Jan 05, 2025 15:04:02.523 [12940] DEBUG - [Req#6e52b] Statistics: (b7db157f52141043-com-plexapp-android) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 1
Jan 05, 2025 15:04:02.524 [13744] DEBUG - Completed: [192.168.1.86:44774] 200 GET /:/timeline?audioStreamID=68631&bufferedTime=31338&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=661829&playQueueItemID=81092&ratingKey=14263&state=playing&time=1327876&token=xxxxxxxxxxxxxxxxxxxx (22 live) #6e52b TLS GZIP 1ms 492 bytes (pipelined: 7)
Jan 05, 2025 15:04:02.745 [13740] DEBUG - Using X-Forwarded-For: 81.65.70.237 as remote address
Jan 05, 2025 15:04:02.745 [13740] DEBUG - Auth: authenticated user 461171622 as XXXXX
Jan 05, 2025 15:04:02.746 [12940] DEBUG - Request: [192.168.1.25:33736 (WAN)] GET /:/timeline?audioStreamID=150831&bufferedTime=27745&context=source%3Ahub.tv.inprogress&duration=2548245&guid=plex%3A%2F%2Fepisode%2F5d9c1028ffd9ef001e9e5349&key=%2Flibrary%2Fmetadata%2F34080&playbackTime=2678868&playQueueItemID=81039&ratingKey=34080&state=playing&time=2520500&token=xxxxxxxxxxxxxxxxxxxx (23 live) #6e54c TLS GZIP Signed-in Token (XXXXX)
Jan 05, 2025 15:04:02.746 [12940] DEBUG - [Req#6e54c] Client [8ed6504b82a90889-com-plexapp-android] reporting timeline state playing, progress of 2520500/2548245ms for guid=plex://episode/5d9c1028ffd9ef001e9e5349, playbackTime=2678868ms ratingKey=34080 url=, key=/library/metadata/34080, containerKey=, metadataId=34080, source=
Jan 05, 2025 15:04:02.746 [12940] DEBUG - [Req#6e54c] [Now] User is XXXXX (ID: 461171622)
Jan 05, 2025 15:04:02.746 [12940] DEBUG - [Req#6e54c] [Now] Device is Android (AFTKM).
Jan 05, 2025 15:04:02.747 [12940] DEBUG - [Req#6e54c] [Now] Profile is Android
Jan 05, 2025 15:04:02.747 [12940] DEBUG - [Req#6e54c] [Now] Updated play state for /library/metadata/34080.
Jan 05, 2025 15:04:02.747 [12940] DEBUG - [Req#6e54c] Statistics: (8ed6504b82a90889-com-plexapp-android) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 461171622
Jan 05, 2025 15:04:02.747 [13744] DEBUG - Completed: [192.168.1.25:33736] 200 GET /:/timeline?audioStreamID=150831&bufferedTime=27745&context=source%3Ahub.tv.inprogress&duration=2548245&guid=plex%3A%2F%2Fepisode%2F5d9c1028ffd9ef001e9e5349&key=%2Flibrary%2Fmetadata%2F34080&playbackTime=2678868&playQueueItemID=81039&ratingKey=34080&state=playing&time=2520500&token=xxxxxxxxxxxxxxxxxxxx (23 live) #6e54c TLS GZIP 1ms 466 bytes
Jan 05, 2025 15:04:02.887 [13744] DEBUG - Request came in with unrecognized domain / IP 'plex.xxx.fr' in header Host; treating as non-local
Jan 05, 2025 15:04:02.887 [12940] DEBUG - Request: [192.168.1.25:33740 (Allowed Network (Subnet))] GET /status/sessions (22 live) #6e555 TLS GZIP Signed-in Token (xxxx.plex) (61dd703437f8 (Tautulli))
Jan 05, 2025 15:04:02.887 [12940] DEBUG - [Req#6e555] [Now] Adding 3 sessions.
Jan 05, 2025 15:04:02.888 [13740] DEBUG - Completed: [192.168.1.25:33740] 200 GET /status/sessions (22 live) #6e555 TLS GZIP 1ms 8138 bytes
Jan 05, 2025 15:04:03.895 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53872: End of file
Jan 05, 2025 15:04:06.413 [13744] DEBUG - Request came in with unrecognized domain / IP 'plex.xxx.fr' in header Host; treating as non-local
Jan 05, 2025 15:04:06.413 [12704] DEBUG - Request: [192.168.1.25:33768 (Allowed Network (Subnet))] GET /status/sessions (22 live) #6e566 TLS GZIP Signed-in Token (xxxx.plex) (61dd703437f8 (Tautulli))
Jan 05, 2025 15:04:06.413 [12704] DEBUG - [Req#6e566] [Now] Adding 3 sessions.
Jan 05, 2025 15:04:06.414 [13740] DEBUG - Completed: [192.168.1.25:33768] 200 GET /status/sessions (22 live) #6e566 TLS GZIP 1ms 8138 bytes
Jan 05, 2025 15:04:06.903 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53876: End of file
Jan 05, 2025 15:04:08.982 [14564] DEBUG - [NSB/SSDP] Parsing SSDP schema for http://192.168.1.1:49152/rootDesc.xml
Jan 05, 2025 15:04:09.047 [13744] DEBUG - Request came in with unrecognized domain / IP 'plex.xxx.fr' in header Host; treating as non-local
Jan 05, 2025 15:04:09.047 [12940] DEBUG - Request: [192.168.1.25:33796 (Allowed Network (Subnet))] GET /status/sessions (22 live) #6e56a TLS GZIP Signed-in Token (xxxx.plex) (61dd703437f8 (Tautulli))
Jan 05, 2025 15:04:09.047 [12940] DEBUG - [Req#6e56a] [Now] Adding 3 sessions.
Jan 05, 2025 15:04:09.048 [13740] DEBUG - Completed: [192.168.1.25:33796] 200 GET /status/sessions (22 live) #6e56a TLS GZIP 1ms 8138 bytes
Jan 05, 2025 15:04:09.905 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53888: End of file
Jan 05, 2025 15:04:10.471 [13744] DEBUG - Using X-Forwarded-For: 90.9.227.193 as remote address
Jan 05, 2025 15:04:10.471 [13744] DEBUG - Auth: authenticated user 382971569 as XXXXX75
Jan 05, 2025 15:04:10.471 [12704] DEBUG - Request: [192.168.1.25:33802 (WAN)] GET /:/timeline?ratingKey=68372&key=%2Flibrary%2Fmetadata%2F68372&playbackTime=6667824&playQueueItemID=80934&state=paused&hasMDE=1&context=source%3Acontent.library&row=0&col=1&time=6638000&duration=6829000 (23 live) #6e56e TLS GZIP Signed-in Token (XXXXX75)
Jan 05, 2025 15:04:10.471 [12704] DEBUG - [Req#6e56e] Client [wpc3jdbf7olly9mn1eyq2jl9] reporting timeline state paused, progress of 6638000/6829000ms for guid=, playbackTime=6667824ms ratingKey=68372 url=, key=/library/metadata/68372, containerKey=, metadataId=68372, source=
Jan 05, 2025 15:04:10.471 [12704] DEBUG - [Req#6e56e] [Now] User is XXXXX75 (ID: 382971569)
Jan 05, 2025 15:04:10.471 [12704] DEBUG - [Req#6e56e] [Now] Device is windows (kei-pc).
Jan 05, 2025 15:04:10.472 [12704] DEBUG - [Req#6e56e] [Now] Profile is Plex Desktop
Jan 05, 2025 15:04:10.472 [12704] DEBUG - [Req#6e56e] [Now] Updated play state for /library/metadata/68372.
Jan 05, 2025 15:04:10.472 [12704] DEBUG - [Req#6e56e] Statistics: (5jt3r2vjqopzvnlrbe4vtkj3) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 382971569
Jan 05, 2025 15:04:10.472 [13740] DEBUG - Completed: [192.168.1.25:33802] 200 GET /:/timeline?ratingKey=68372&key=%2Flibrary%2Fmetadata%2F68372&playbackTime=6667824&playQueueItemID=80934&state=paused&hasMDE=1&context=source%3Acontent.library&row=0&col=1&time=6638000&duration=6829000 (23 live) #6e56e TLS GZIP 1ms 379 bytes
Jan 05, 2025 15:04:10.820 [13744] DEBUG - Request came in with unrecognized domain / IP 'plex.xxx.fr' in header Host; treating as non-local
Jan 05, 2025 15:04:10.820 [12704] DEBUG - Request: [192.168.1.25:33806 (Allowed Network (Subnet))] GET /status/sessions (23 live) #6e56f TLS GZIP Signed-in Token (xxxx.plex) (61dd703437f8 (Tautulli))
Jan 05, 2025 15:04:10.820 [12704] DEBUG - [Req#6e56f] [Now] Adding 3 sessions.
Jan 05, 2025 15:04:10.821 [13740] DEBUG - Completed: [192.168.1.25:33806] 200 GET /status/sessions (23 live) #6e56f TLS GZIP 1ms 8138 bytes
Jan 05, 2025 15:04:12.567 [12940] DEBUG - Request: [192.168.1.86:44774 (Allowed Network (Subnet))] GET /:/timeline?audioStreamID=68631&bufferedTime=36913&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=671872&playQueueItemID=81092&ratingKey=14263&state=playing&time=1337984&token=xxxxxxxxxxxxxxxxxxxx (21 live) #6e554 TLS GZIP Signed-in Token (xxxx.plex) (SHIELD Salon)
Jan 05, 2025 15:04:12.567 [12940] DEBUG - [Req#6e554] Client [b7db157f52141043-com-plexapp-android] reporting timeline state playing, progress of 1337984/1420086ms for guid=plex://episode/657b28d3351a0a707e0bbcdf, playbackTime=671872ms ratingKey=14263 url=, key=/library/metadata/14263, containerKey=, metadataId=14263, source=
Jan 05, 2025 15:04:12.568 [12940] DEBUG - [Req#6e554] [Now] User is xxxx.plex (ID: 1)
Jan 05, 2025 15:04:12.568 [12940] DEBUG - [Req#6e554] [Now] Device is Android (SHIELD Salon).
Jan 05, 2025 15:04:12.568 [12940] DEBUG - [Req#6e554] [Now] Profile is Android
Jan 05, 2025 15:04:12.568 [12940] DEBUG - [Req#6e554] [Now] Updated play state for /library/metadata/14263.
Jan 05, 2025 15:04:12.568 [12940] DEBUG - [Req#6e554] Statistics: (b7db157f52141043-com-plexapp-android) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 1
Jan 05, 2025 15:04:12.569 [13744] DEBUG - Completed: [192.168.1.86:44774] 200 GET /:/timeline?audioStreamID=68631&bufferedTime=36913&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=671872&playQueueItemID=81092&ratingKey=14263&state=playing&time=1337984&token=xxxxxxxxxxxxxxxxxxxx (21 live) #6e554 TLS GZIP 1ms 492 bytes (pipelined: 8)
Jan 05, 2025 15:04:12.785 [13744] DEBUG - Using X-Forwarded-For: 81.65.70.237 as remote address
Jan 05, 2025 15:04:12.786 [13744] DEBUG - Auth: authenticated user 461171622 as XXXXX
Jan 05, 2025 15:04:12.786 [12940] DEBUG - Request: [192.168.1.25:33812 (WAN)] GET /:/timeline?audioStreamID=150831&bufferedTime=17527&context=source%3Ahub.tv.inprogress&duration=2548245&guid=plex%3A%2F%2Fepisode%2F5d9c1028ffd9ef001e9e5349&key=%2Flibrary%2Fmetadata%2F34080&playbackTime=2688910&playQueueItemID=81039&ratingKey=34080&state=playing&time=2530718&token=xxxxxxxxxxxxxxxxxxxx (22 live) #6e57b TLS GZIP Signed-in Token (XXXXX)
Jan 05, 2025 15:04:12.786 [12940] DEBUG - [Req#6e57b] Client [8ed6504b82a90889-com-plexapp-android] reporting timeline state playing, progress of 2530718/2548245ms for guid=plex://episode/5d9c1028ffd9ef001e9e5349, playbackTime=2688910ms ratingKey=34080 url=, key=/library/metadata/34080, containerKey=, metadataId=34080, source=
Jan 05, 2025 15:04:12.786 [12940] DEBUG - [Req#6e57b] [Now] User is XXXXX (ID: 461171622)
Jan 05, 2025 15:04:12.786 [12940] DEBUG - [Req#6e57b] [Now] Device is Android (AFTKM).
Jan 05, 2025 15:04:12.787 [12940] DEBUG - [Req#6e57b] [Now] Profile is Android
Jan 05, 2025 15:04:12.787 [12940] DEBUG - [Req#6e57b] [Now] Updated play state for /library/metadata/34080.
Jan 05, 2025 15:04:12.787 [12940] DEBUG - [Req#6e57b] Statistics: (8ed6504b82a90889-com-plexapp-android) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 461171622
Jan 05, 2025 15:04:12.787 [13740] DEBUG - Completed: [192.168.1.25:33812] 200 GET /:/timeline?audioStreamID=150831&bufferedTime=17527&context=source%3Ahub.tv.inprogress&duration=2548245&guid=plex%3A%2F%2Fepisode%2F5d9c1028ffd9ef001e9e5349&key=%2Flibrary%2Fmetadata%2F34080&playbackTime=2688910&playQueueItemID=81039&ratingKey=34080&state=playing&time=2530718&token=xxxxxxxxxxxxxxxxxxxx (22 live) #6e57b TLS GZIP 1ms 466 bytes
Jan 05, 2025 15:04:12.912 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53892: End of file
Jan 05, 2025 15:04:13.985 [13740] DEBUG - Failed to stream media, client probably disconnected after 375193600 bytes: 10054 - Une connexion existante a dû être fermée par l’hôte distant
Jan 05, 2025 15:04:13.985 [13740] DEBUG - Completed after connection close: [192.168.1.86:44766] 206 GET /library/parts/14137/1719562927/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (22 live) #6da8e TLS 503976ms 375193600 bytes (pipelined: 1) (range: bytes=432117840-) 
Jan 05, 2025 15:04:13.986 [12704] DEBUG - Request: [192.168.1.86:44774 (Allowed Network (Subnet))] GET /library/parts/14137/1719562927/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (21 live) #6e584 TLS Signed-in Token (xxxx.plex) (SHIELD Salon) (range: bytes=801503312-) 
Jan 05, 2025 15:04:13.989 [12704] DEBUG - Content-Length of Y:\plex-series\anime\Solo Leveling (2024) {tmdb-127532} {imdb-tt21209876} {tvdb-389597}\Saison 1\Solo Leveling - S01E11 - [.MULTi.1080p.WEBRiP.x264-T3KASHi-HDL].mkv is 41222564 (of total: 842725876).
Jan 05, 2025 15:04:14.955 [13744] DEBUG - Completed: [192.168.1.86:44774] 206 GET /library/parts/14137/1719562927/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (20 live) #6e584 TLS 969ms 41222564 bytes (pipelined: 9) (range: bytes=801503312-) 
Jan 05, 2025 15:04:15.916 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53904: End of file
Jan 05, 2025 15:04:18.920 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53919: End of file
Jan 05, 2025 15:04:20.469 [13744] DEBUG - Using X-Forwarded-For: 90.9.227.193 as remote address
Jan 05, 2025 15:04:20.469 [13744] DEBUG - Auth: authenticated user 382971569 as XXXXX75
Jan 05, 2025 15:04:20.470 [12704] DEBUG - Request: [192.168.1.25:33834 (WAN)] GET /:/timeline?ratingKey=68372&key=%2Flibrary%2Fmetadata%2F68372&playbackTime=6667824&playQueueItemID=80934&state=paused&hasMDE=1&context=source%3Acontent.library&row=0&col=1&time=6638000&duration=6829000 (22 live) #6e599 TLS GZIP Signed-in Token (XXXXX75)
Jan 05, 2025 15:04:20.470 [12704] DEBUG - [Req#6e599] Client [wpc3jdbf7olly9mn1eyq2jl9] reporting timeline state paused, progress of 6638000/6829000ms for guid=, playbackTime=6667824ms ratingKey=68372 url=, key=/library/metadata/68372, containerKey=, metadataId=68372, source=
Jan 05, 2025 15:04:20.470 [12704] DEBUG - [Req#6e599] [Now] User is XXXXX75 (ID: 382971569)
Jan 05, 2025 15:04:20.470 [12704] DEBUG - [Req#6e599] [Now] Device is windows (kei-pc).
Jan 05, 2025 15:04:20.470 [12704] DEBUG - [Req#6e599] [Now] Profile is Plex Desktop
Jan 05, 2025 15:04:20.470 [12704] DEBUG - [Req#6e599] [Now] Updated play state for /library/metadata/68372.
Jan 05, 2025 15:04:20.470 [12704] DEBUG - [Req#6e599] Statistics: (5jt3r2vjqopzvnlrbe4vtkj3) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 382971569
Jan 05, 2025 15:04:20.471 [13740] DEBUG - Completed: [192.168.1.25:33834] 200 GET /:/timeline?ratingKey=68372&key=%2Flibrary%2Fmetadata%2F68372&playbackTime=6667824&playQueueItemID=80934&state=paused&hasMDE=1&context=source%3Acontent.library&row=0&col=1&time=6638000&duration=6829000 (22 live) #6e599 TLS GZIP 1ms 379 bytes
Jan 05, 2025 15:04:20.899 [13740] DEBUG - Request came in with unrecognized domain / IP 'plex.xxx.fr' in header Host; treating as non-local
Jan 05, 2025 15:04:20.900 [12940] DEBUG - Request: [192.168.1.25:33838 (Allowed Network (Subnet))] GET /status/sessions (21 live) #6e59a TLS GZIP Signed-in Token (xxxx.plex) (61dd703437f8 (Tautulli))
Jan 05, 2025 15:04:20.900 [12940] DEBUG - [Req#6e59a] [Now] Adding 3 sessions.
Jan 05, 2025 15:04:20.900 [13744] DEBUG - Completed: [192.168.1.25:33838] 200 GET /status/sessions (21 live) #6e59a TLS GZIP 0ms 8141 bytes
Jan 05, 2025 15:04:21.924 [13744] DEBUG - Request: didn't get any data from 192.168.1.83:53930: End of file
Jan 05, 2025 15:04:22.418 [12536] DEBUG - [Req#6e47c] BPQ: onConsiderProcessing: Idle (true)
Jan 05, 2025 15:04:22.418 [12536] DEBUG - [Req#6e47c] BPQ: [Idle] -> [Processing]
Jan 05, 2025 15:04:22.420 [12536] DEBUG - [Req#6e47c] BPQ: generating queue items from 0 generator(s)
Jan 05, 2025 15:04:22.420 [12536] DEBUG - [Req#6e47c] BPQ: generated 0 item(s) for queue
Jan 05, 2025 15:04:22.420 [12536] DEBUG - [Req#6e47c] PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Jan 05, 2025 15:04:22.420 [12536] DEBUG - [Req#6e47c] PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Jan 05, 2025 15:04:22.421 [12536] DEBUG - [Req#6e47c] BPQ: [Processing] -> [Idle]
Jan 05, 2025 15:04:22.421 [12536] DEBUG - [Req#6e47c] BPQ: garbageCollect - version - forced - -1
Jan 05, 2025 15:04:22.421 [12536] DEBUG - [Req#6e47c] Versions: garbage collecting
Jan 05, 2025 15:04:22.422 [12536] DEBUG - [Req#6e47c] Versions: garbage collected in 0.0 seconds
Jan 05, 2025 15:04:22.603 [12704] DEBUG - Request: [192.168.1.86:44774 (Allowed Network (Subnet))] GET /:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=681909&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=8&token=xxxxxxxxxxxxxxxxxxxx (21 live) #6e596 TLS GZIP Signed-in Token (xxxx.plex) (SHIELD Salon)
Jan 05, 2025 15:04:22.604 [12704] DEBUG - [Req#6e596] Client [b7db157f52141043-com-plexapp-android] reporting timeline state buffering, progress of 1420085/1420086ms for guid=plex://episode/657b28d3351a0a707e0bbcdf, playbackTime=681909ms ratingKey=14263 url=, key=/library/metadata/14263, containerKey=, metadataId=14263, source=
Jan 05, 2025 15:04:22.604 [12704] DEBUG - [Req#6e596] [Now] User is xxxx.plex (ID: 1)
Jan 05, 2025 15:04:22.604 [12704] DEBUG - [Req#6e596] [Now] Device is Android (SHIELD Salon).
Jan 05, 2025 15:04:22.604 [12704] DEBUG - [Req#6e596] [Now] Profile is Android
Jan 05, 2025 15:04:22.604 [12704] DEBUG - [Req#6e596] [Now] Updated play state for /library/metadata/14263.
Jan 05, 2025 15:04:22.604 [12704] DEBUG - [Req#6e596] Statistics: (b7db157f52141043-com-plexapp-android) Reporting active playback in state 2 of type 4 (scrobble: 0) for account 1
Jan 05, 2025 15:04:22.605 [13740] DEBUG - Completed: [192.168.1.86:44774] 200 GET /:/timeline?audioStreamID=68631&bufferedTime=0&duration=1420086&guid=plex%3A%2F%2Fepisode%2F657b28d3351a0a707e0bbcdf&key=%2Flibrary%2Fmetadata%2F14263&playbackTime=681909&playQueueItemID=81092&ratingKey=14263&state=buffering&time=1420085&timeStalled=8&token=xxxxxxxxxxxxxxxxxxxx (21 live) #6e596 TLS GZIP 1ms 492 bytes (pipelined: 10)
Jan 05, 2025 15:04:22.829 [13744] DEBUG - Using X-Forwarded-For: 81.65.70.237 as remote address
Jan 05, 2025 15:04:22.829 [13744] DEBUG - Auth: authenticated user 461171622 as XXXXX
Jan 05, 2025 15:04:22.829 [12704] DEBUG - Request: [192.168.1.25:33848 (WAN)] GET /:/timeline?audioStreamID=150831&bufferedTime=7471&context=source%3Ahub.tv.inprogress&duration=2548245&guid=plex%3A%2F%2Fepisode%2F5d9c1028ffd9ef001e9e5349&key=%2Flibrary%2Fmetadata%2F34080&playbackTime=2698949&playQueueItemID=81039&ratingKey=34080&state=playing&time=2540774&token=xxxxxxxxxxxxxxxxxxxx (22 live) #6e5a9 TLS GZIP Signed-in Token (XXXXX)
Jan 05, 2025 15:04:22.829 [12704] DEBUG - [Req#6e5a9] Client [8ed6504b82a90889-com-plexapp-android] reporting timeline state playing, progress of 2540774/2548245ms for guid=plex://episode/5d9c1028ffd9ef001e9e5349, playbackTime=2698949ms ratingKey=34080 url=, key=/library/metadata/34080, containerKey=, metadataId=34080, source=
Jan 05, 2025 15:04:22.830 [12704] DEBUG - [Req#6e5a9] [Now] User is XXXXX (ID: 461171622)
Jan 05, 2025 15:04:22.830 [12704] DEBUG - [Req#6e5a9] [Now] Device is Android (AFTKM).
Jan 05, 2025 15:04:22.830 [12704] DEBUG - [Req#6e5a9] [Now] Profile is Android
Jan 05, 2025 15:04:22.830 [12704] DEBUG - [Req#6e5a9] [Now] Updated play state for /library/metadata/34080.
Jan 05, 2025 15:04:22.830 [12704] DEBUG - [Req#6e5a9] Statistics: (8ed6504b82a90889-com-plexapp-android) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 461171622
Jan 05, 2025 15:04:22.830 [13740] DEBUG - Completed: [192.168.1.25:33848] 200 GET /:/timeline?audioStreamID=150831&bufferedTime=7471&context=source%3Ahub.tv.inprogress&duration=2548245&guid=plex%3A%2F%2Fepisode%2F5d9c1028ffd9ef001e9e5349&key=%2Flibrary%2Fmetadata%2F34080&playbackTime=2698949&playQueueItemID=81039&ratingKey=34080&state=playing&time=2540774&token=xxxxxxxxxxxxxxxxxxxx (22 live) #6e5a9 TLS GZIP 1ms 466 bytes
Jan 05, 2025 15:04:23.044 [13744] DEBUG - Request came in with unrecognized domain / IP 'plex.xxx.fr' in header Host; treating as non-local
Jan 05, 2025 15:04:23.044 [12940] DEBUG - Request: [192.168.1.25:33852 (Allowed Network (Subnet))] GET /status/sessions (22 live) #6e5b7 TLS GZIP Signed-in Token (xxxx.plex) (61dd703437f8 (Tautulli))
Jan 05, 2025 15:04:23.045 [12940] DEBUG - [Req#6e5b7] [Now] Adding 3 sessions.
Jan 05, 2025 15:04:23.045 [13740] DEBUG - Completed: [192.168.1.25:33852] 200 GET /status/sessions (22 live) #6e5b7 TLS GZIP 1ms 8146 bytes
Jan 05, 2025 15:04:23.984 [13776] DEBUG - [HttpClient/HCl#2c3c] HTTP simulating 408 after curl timeout
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.16 (Denon Home Sound Bar 550)
Jan 05, 2025 15:04:23.984 [14564] DEBUG - [NSB/SSDP] Parsing SSDP schema for http://192.168.1.69:80/plugin/discovery/discovery.xml
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.16 (Denon Home Sound Bar 550)
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.16 (AiosServices)
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.16 (Denon Home Sound Bar 550)
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.16 (Denon Home Sound Bar 550)
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 25.046848 seconds: 192.168.1.69 (OctoPrint instance on octopi)
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.100 (Denon Home 150  Surround Gauche)
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.100 (Denon Home 150  Surround Gauche)
Jan 05, 2025 15:04:23.984 [11652] DEBUG - SSDP departed after not being seen for 24.664109 seconds: 192.168.1.100 (AiosServices)
1 Like
12

@Atomatth this can help ?

13

I analyzed my mkv files, no error with ffmpeg or mkvinfo, the problem is random…
if I restart the video the problem does not reappear on the same video…
This looks like caching issues, I detected the issue on 2 different shields, on local network and remotely.

sometimes I have a black screen with or without a charger, sometimes it’s the last image of the video (at the time of auto skip credit), or even the skip of an episode…

I had the problem with different logs, sometimes a stack trace, sometimes just an ffmpeg error, or no noticeable trace.

The problem appears to have existed for several years.
I don’t know if the cache is corrupted, if it’s too small…

From what I’ve observed, this happens most easily with short videos of varying size with 5.1 within the same season.
and the problem always occurs in direct stream (I never transcode)

14

Up @Atomatth :slight_smile:

15

Thanks! We been able to reproduce the issue. It looks like there is something to due to network connectivity. No ETA on when it’ll be resolved unfortunately.

16

already, if you were able to reproduce the problem, I am happy, it is already a big step towards solving the problem. for the moment I disabled the “I/O cache” and until now I have not had the problem anymore, but I can afford this because it is on my local network, remotely this cache is necessary… if ever I notice the problem despite the cache disabled I will comment on the topic. hoping that the problem is resolved soon :slight_smile:

Thx for you reply :slight_smile:

17

Note: The problem also occurs with the I/O cache option disabled, but seems less frequent

18

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.