Server Version#: 1.20.1.3252
Player Version#: Roku Ultra 6.6.12.6662-64d4a08ac
Hardwired
Movie: Star Trek 2009 1080p (H.264) MKV
Stops at 14:22 and goes to next movie every time. Movie does play if I manually scan past time and select chapters after. Movie played before without issue, just started within the last week. No updates on server (unknown if Plex app on Roku updated).
Does not affect any other movie (that I’m aware of).
Cannot replicate on web version, only affects Roku.
Log snippet from Roku Plex Log. We see it playing at 20:53:02 and then stopping at 20:53:10.715 without a reason nor playback error.
Media Info
Codec H264
Bitrate 32449 kbps
Language English
Bit Depth 8
Chroma Location left
Chroma Subsampling 4:2:0
Coded Height 1088
Coded Width 1920
Color Primaries bt709
Color Range tv
Color Space bt709
Color Trc bt709
Frame Rate 23.976 fps
Height 1080
Level 4.1
Profile high
Ref Frames 4
Scan Type progressive
Width 1920
Display Title 1080p (H.264)
Extended Display Title 1080p (H.264)
Codec TRUEHD
Channels 6
Bitrate 3603 kbps
Language English
Audio Channel Layout 5.1(side)
Bit Depth 24
Sampling Rate 48000 Hz
Title Surround 5.1
Display Title English (TRUEHD 5.1)
Extended Display Title Surround 5.1 (English TRUEHD)
Codec AC3
Channels 6
Bitrate 640 kbps
Language English
Audio Channel Layout 5.1(side)
Sampling Rate 48000 Hz
Title Surround 5.1
Display Title English (AC3 5.1)
Extended Display Title Surround 5.1 (English AC3)
Codec PGS
Bitrate 37 kbps
Language English
Display Title English (PGS)
Extended Display Title English (PGS)
Codec PGS
Bitrate 42 kbps
Language English
Display Title English (PGS)
Extended Display Title English (PGS)
Codec PGS
Language Français
Display Title Français (PGS)
Extended Display Title Français (PGS)
Player Logs
10/26/2020 20:53:02.409 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Requesting GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/:/timeline?playbackTime=70000&time=854000&state=playing&ratingKey=129&key=%2Flibrary%2Fmetadata%2F129&col=5&row=1&context=source%3Acontent.library&duration=7610272&playQueueItemID=5494
10/26/2020 20:53:02.425 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Got response 200 GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/:/timeline?playbackTime=70000&time=854000&state=playing&ratingKey=129&key=%2Flibrary%2Fmetadata%2F129&col=5&row=1&context=source%3Acontent.library&duration=7610272&playQueueItemID=5494 - 15ms
10/26/2020 20:53:02.426 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Transformed (TimelineTransform) GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/:/timeline?playbackTime=70000&time=854000&state=playing&ratingKey=129&key=%2Flibrary%2Fmetadata%2F129&col=5&row=1&context=source%3Acontent.library&duration=7610272&playQueueItemID=5494 - 0ms
10/26/2020 20:53:02.429 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Updated timeline info {roAssociativeArray}
playerState: playing
10/26/2020 20:53:09.571 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Current bandwidth reading: time=8:53 PM available=93.9 Mbps (93864 kbps)
10/26/2020 20:53:10.715 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Player state changed to finished (hasAd=false)
10/26/2020 20:53:10.723 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Stop requested with reason:
10/26/2020 20:53:10.724 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Stop requested with reason:
10/26/2020 20:53:10.724 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) VideoPlaybackController::OnPlaybackFinished: Handling finished playback event. forceClose=false, userClose=false, isPlayed=true:0.113272, playbackError=false
10/26/2020 20:53:10.724 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) VideoPlaybackController::OnPlaybackFinished: nextType=postPlay
10/26/2020 20:53:10.725 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Stop requested with reason:
10/26/2020 20:53:10.725 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) VideoPlaybackController::OnPlaybackFinished: Going to show post play.
10/26/2020 20:53:10.726 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (VideoPlaybackScreen) Navigating to screen PostPlayScreen, options: {roAssociativeArray}
enableAutoPlay: true
nextItem: invalid
previousItem: roSGNode
screenName: VideoPlaybackScreen
10/26/2020 20:53:10.726 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (VideoPlaybackScreen) Closing matching screen for PostPlayScreen before we navigate to it.
10/26/2020 20:53:10.752 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PostPlayDetails) Consuming existing global locale info.
10/26/2020 20:53:10.779 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (Navigation) Navigated to screen PostPlayScreen, activateKind new
10/26/2020 20:53:10.802 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] DEBUG (PostPlayScreen) Reported metrics: client:view
10/26/2020 20:53:10.803 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Player state changed to stopped (hasAd=false)
10/26/2020 20:53:10.804 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Playback ended: eventType=normal stopReason=postPlay sentItemStart=true
10/26/2020 20:53:10.804 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Setting playback active=false sendPlaybackEvent=true
10/26/2020 20:53:10.805 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] DEBUG (PlaybackMgr) Reported metrics: playback:itemend
10/26/2020 20:53:10.988 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Player state changed to stopped (hasAd=false)
10/26/2020 20:53:10.742 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Requesting GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/:/timeline?playbackTime=83000&time=862000&state=stopped&ratingKey=129&key=%2Flibrary%2Fmetadata%2F129&col=5&row=1&context=source%3Acontent.library&duration=7610272&playQueueItemID=5494
10/26/2020 20:53:11.025 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Requesting GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/hubs/metadata/129/postplay?count=20
10/26/2020 20:53:11.078 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] DEBUG (GeneralTask) [Metrics] Metrics::Flush: Already have permissions; processing events.
10/26/2020 20:53:11.079 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] DEBUG (GeneralTask) [Metrics] ProcessEvents: rawEventCount=2 allowedEventCount=2
10/26/2020 20:53:11.080 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] DEBUG (GeneralTask) [Metrics] ProcessEvents: Sending 2 metrics events in batches of 10
10/26/2020 20:53:11.081 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] DEBUG (GeneralTask) [Metrics] ProcessEvents: Sending batch of 2 metrics events
10/26/2020 20:53:11.083 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Requesting POST https://analytics.plex.tv/collect/event
10/26/2020 20:53:11.086 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Got response 200 GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/:/timeline?playbackTime=83000&time=862000&state=stopped&ratingKey=129&key=%2Flibrary%2Fmetadata%2F129&col=5&row=1&context=source%3Acontent.library&duration=7610272&playQueueItemID=5494 - 344ms
10/26/2020 20:53:11.087 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Transformed (TimelineTransform) GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/:/timeline?playbackTime=83000&time=862000&state=stopped&ratingKey=129&key=%2Flibrary%2Fmetadata%2F129&col=5&row=1&context=source%3Acontent.library&duration=7610272&playQueueItemID=5494 - 0ms
10/26/2020 20:53:11.088 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PlaybackMgr) Updated timeline info {roAssociativeArray}
playerState: stopped
10/26/2020 20:53:11.261 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Got response 200 POST https://analytics.plex.tv/collect/event - 178ms
10/26/2020 20:53:11.341 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Got response 200 GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/hubs/metadata/129/postplay?count=20 - 317ms
10/26/2020 20:53:11.590 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Transformed (PostPlayInfoTransform) GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/hubs/metadata/129/postplay?count=20 - 234ms
10/26/2020 20:53:11.740 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Requesting GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/library/metadata/129
10/26/2020 20:53:11.751 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Requesting GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/library/metadata/229
10/26/2020 20:53:11.775 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Got response 200 GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/library/metadata/129 - 35ms
10/26/2020 20:53:11.860 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Transformed (PostPlayDetailsTransform) GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/library/metadata/129 - 38ms
10/26/2020 20:53:11.888 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Got response 200 GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/library/metadata/229 - 137ms
10/26/2020 20:53:11.933 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (GeneralTask) Transformed (PostPlayDetailsTransform) GET https://192-168-128-26.f40bb178eff64643b582a040b554d8da.plex.direct:32400/library/metadata/229 - 31ms
10/26/2020 20:53:11.977 [41039759:xxx@xxx.com:f5959063-e9ec-5992-9049-f33ea944909f] INFO (PostPlayDetails) Post Play auto play: enabled=true lastItem=7610.27 lastInteraction=60
Plex Server Log.txt (41.3 KB)
