Server Version#: 1.40.5.8897
Player Version#: Latest Fire Stick app version idk
The video appears to be playing normally as far as I can tell in the logs and then it seems as though the client is reporting it reached the end of the file when it hasn’t yet?
Auto-skip credits is disabled and it just jumps straight back to the selection screen. Happening on Fire Stick and Chromecast only when utilizing Remote Playback. Does not happen to me at home on my FireTV.
<If providing server logs please do NOT turn on verbose logging, only debug logging should be enabled>
Aug 24, 2024 21:57:12.157 [12860] DEBUG - [Req#3baa] Client [56c04e1289ef514c-com-plexapp-android] reporting timeline state playing, progress of 5292646/5889173ms for guid=plex://movie/5fb93a32e19a1d002dda206b, playbackTime=5293088ms ratingKey=4926 url=, key=/library/metadata/4926, containerKey=, metadataId=4926, source=
Aug 24, 2024 21:57:12.159 [12860] DEBUG - [Req#3baa] Play progress on 4926 ‘See How They Run’ - got played 5292646 ms by account 24620062!
Aug 24, 2024 21:57:12.159 [12860] DEBUG - [Req#3baa] [Now] User is REDACTEDuser1 (ID: 24620062)
Aug 24, 2024 21:57:12.159 [12860] DEBUG - [Req#3baa] [Now] Device is Android (AFTN).
Aug 24, 2024 21:57:12.162 [12860] DEBUG - [Req#3baa] [Now] Profile is Android
Aug 24, 2024 21:57:12.162 [12860] DEBUG - [Req#3baa] [Now] Updated play state for /library/metadata/4926.
Aug 24, 2024 21:57:12.162 [12860] DEBUG - [Req#3baa] Statistics: (56c04e1289ef514c-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 24620062
Aug 24, 2024 21:57:12.164 [10564] DEBUG - Completed: [68.12.199.214:57140] 200 GET /:/timeline?audioStreamID=107878&bufferedTime=596527&duration=5889173&guid=plex%3A%2F%2Fmovie%2F5fb93a32e19a1d002dda206b&key=%2Flibrary%2Fmetadata%2F4926&playbackTime=5293088&playQueueItemID=60349&ratingKey=4926&state=playing&time=5292646&token=xxxxxxxxxxxxxxxxxxxx (5 live) #3baa TLS GZIP 8ms 495 bytes (pipelined: 260)
Aug 24, 2024 21:57:22.192 [10564] DEBUG - Auth: authenticated user 24620062 as REDACTEDuser1
Aug 24, 2024 21:57:22.193 [12860] DEBUG - Request: [68.12.199.214:57140 (WAN)] GET /:/timeline?audioStreamID=107878&bufferedTime=586504&duration=5889173&guid=plex%3A%2F%2Fmovie%2F5fb93a32e19a1d002dda206b&key=%2Flibrary%2Fmetadata%2F4926&playbackTime=5303129&playQueueItemID=60349&ratingKey=4926&state=playing&time=5302669&token=xxxxxxxxxxxxxxxxxxxx (5 live) #3bad TLS GZIP Signed-in Token (REDACTEDuser1)
Aug 24, 2024 21:57:22.195 [12860] DEBUG - [Req#3bad] Client [56c04e1289ef514c-com-plexapp-android] reporting timeline state playing, progress of 5302669/5889173ms for guid=plex://movie/5fb93a32e19a1d002dda206b, playbackTime=5303129ms ratingKey=4926 url=, key=/library/metadata/4926, containerKey=, metadataId=4926, source=
Aug 24, 2024 21:57:22.198 [12860] DEBUG - [Req#3bad] Library item 4926 ‘See How They Run’ got played by account 24620062!
Aug 24, 2024 21:57:22.370 [12860] DEBUG - [Req#3bad] [Now] User is REDACTEDuser1 (ID: 24620062)
Aug 24, 2024 21:57:22.370 [12860] DEBUG - [Req#3bad] [Now] Device is Android (AFTN).
Aug 24, 2024 21:57:22.372 [12860] DEBUG - [Req#3bad] [Now] Profile is Android
Aug 24, 2024 21:57:22.372 [12860] DEBUG - [Req#3bad] [Now] Updated play state for /library/metadata/4926.
Aug 24, 2024 21:57:22.372 [12860] DEBUG - [Req#3bad] Statistics: (56c04e1289ef514c-com-plexapp-android) Reporting active playback in state 0 of type 1 (scrobble: 1) for account 24620062
Aug 24, 2024 21:57:22.375 [10532] DEBUG - Completed: [68.12.199.214:57140] 200 GET /:/timeline?audioStreamID=107878&bufferedTime=586504&duration=5889173&guid=plex%3A%2F%2Fmovie%2F5fb93a32e19a1d002dda206b&key=%2Flibrary%2Fmetadata%2F4926&playbackTime=5303129&playQueueItemID=60349&ratingKey=4926&state=playing&time=5302669&token=xxxxxxxxxxxxxxxxxxxx (5 live) #3bad TLS GZIP 181ms 493 bytes (pipelined: 261)
Aug 24, 2024 21:57:32.202 [1500] DEBUG - [Req#3bad/ViewStateSync] Starting
Aug 24, 2024 21:57:32.202 [1500] DEBUG - [Req#3bad/ViewStateSync] Got token for user 1 (REDACTEDuser2)
Aug 24, 2024 21:57:32.202 [1500] DEBUG - [Req#3bad/ViewStateSync] Got token for user 2422585 (REDACTEDuser3)
Aug 24, 2024 21:57:32.203 [1500] DEBUG - [Req#3bad/ViewStateSync/HCl#2e4] HTTP requesting GET https://plex.tv/api/v2/server/users/3266069/view_state_sync
Aug 24, 2024 21:57:32.695 [4500] DEBUG - [HttpClient/HCl#2e4] HTTP/2.0 (0.5s) 200 response from GET https://plex.tv/api/v2/server/users/3266069/view_state_sync
Aug 24, 2024 21:57:32.696 [11692] DEBUG - [Req#3bad/ViewStateSync] Starting for user 1
Aug 24, 2024 21:57:32.696 [11692] DEBUG - [Req#3bad/ViewStateSync] Sending state
Aug 24, 2024 21:57:32.697 [11692] DEBUG - [Req#3bad/ViewStateSync] All state sent
Aug 24, 2024 21:57:32.697 [11692] DEBUG - [Req#3bad/ViewStateSync] Getting state
Aug 24, 2024 21:57:32.697 [11692] DEBUG - [Req#3bad/ViewStateSync/HCl#2e5] HTTP requesting GET https://scrobbles.plex.tv/state/3266069?after=MTcyNDU1MjQ2MjI1Mi02NmNhOTUwZTM3ODMyNjc5OGEwNGE3MjA%3D&count=100
Aug 24, 2024 21:57:32.865 [4500] DEBUG - [HttpClient/HCl#2e5] HTTP/2.0 (0.2s) 200 response from GET https://scrobbles.plex.tv/state/3266069?after=MTcyNDU1MjQ2MjI1Mi02NmNhOTUwZTM3ODMyNjc5OGEwNGE3MjA%3D&count=100
Aug 24, 2024 21:57:32.865 [11692] DEBUG - [Req#3bad/ViewStateSync] Reading 0 states
Aug 24, 2024 21:57:32.865 [11692] DEBUG - [Req#3bad/ViewStateSync] Done getting states
Aug 24, 2024 21:57:32.865 [11692] DEBUG - [Req#3bad/ViewStateSync/HCl#2e6] HTTP requesting GET https://plex.tv/api/v2/server/users/2422585/view_state_sync
Aug 24, 2024 21:57:33.025 [4500] DEBUG - [HttpClient/HCl#2e6] HTTP/2.0 (0.2s) 200 response from GET https://plex.tv/api/v2/server/users/2422585/view_state_sync (reused)
Aug 24, 2024 21:57:33.026 [11692] DEBUG - [Req#3bad/ViewStateSync] Starting for user 2422585
Aug 24, 2024 21:57:33.026 [11692] DEBUG - [Req#3bad/ViewStateSync] Sending state
Aug 24, 2024 21:57:33.026 [11692] DEBUG - [Req#3bad/ViewStateSync] All state sent
Aug 24, 2024 21:57:33.026 [11692] DEBUG - [Req#3bad/ViewStateSync] Getting state
Aug 24, 2024 21:57:33.026 [11692] DEBUG - [Req#3bad/ViewStateSync/HCl#2e7] HTTP requesting GET https://scrobbles.plex.tv/state/2422585?after=MTcyNDIxNjcwNzA3OC02NmM1NzU4MzM3ODMyNjc5OGE0ZjQ3MzA%3D&count=100
Aug 24, 2024 21:57:33.097 [4500] DEBUG - [HttpClient/HCl#2e7] HTTP/2.0 (0.1s) 200 response from GET https://scrobbles.plex.tv/state/2422585?after=MTcyNDIxNjcwNzA3OC02NmM1NzU4MzM3ODMyNjc5OGE0ZjQ3MzA%3D&count=100 (reused)
Aug 24, 2024 21:57:33.097 [11692] DEBUG - [Req#3bad/ViewStateSync] Reading 0 states
Aug 24, 2024 21:57:33.097 [11692] DEBUG - [Req#3bad/ViewStateSync] Done getting states
Aug 24, 2024 21:57:33.097 [11692] DEBUG - [Req#3bad/ViewStateSync] Finished
Aug 24, 2024 21:58:22.212 [4108] DEBUG - [Req#3bad] BPQ: onConsiderProcessing: Idle (true)
Aug 24, 2024 21:58:22.212 [4108] DEBUG - [Req#3bad] BPQ: [Idle] → [Processing]
Aug 24, 2024 21:58:22.214 [4108] DEBUG - [Req#3bad] BPQ: generating queue items from 0 generator(s)
Aug 24, 2024 21:58:22.214 [4108] DEBUG - [Req#3bad] BPQ: generated 0 item(s) for queue
Aug 24, 2024 21:58:22.214 [4108] DEBUG - [Req#3bad] PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Aug 24, 2024 21:58:22.214 [4108] DEBUG - [Req#3bad] PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Aug 24, 2024 21:58:22.215 [4108] DEBUG - [Req#3bad] BPQ: [Processing] → [Idle]
Aug 24, 2024 21:58:22.215 [4108] DEBUG - [Req#3bad] BPQ: garbageCollect - version - forced - -1
Aug 24, 2024 21:58:22.215 [4108] DEBUG - [Req#3bad] Versions: garbage collecting
Aug 24, 2024 21:58:22.216 [4108] DEBUG - [Req#3bad] Versions: garbage collected in 0.0 seconds
Aug 24, 2024 21:58:47.195 [10532] DEBUG - WebSocket: client initiated close
Aug 24, 2024 21:58:47.195 [10532] DEBUG - NotificationStream: Removing because of close
Aug 24, 2024 21:58:47.215 [10564] DEBUG - handleStreamRead code 2: End of file
