Hi, when skipping or resuming in a video file, the client ends up with the following error message on the TV: “An unexpected playback problem occurred.”.
Playback works well when playing the videos from start - this problem shows when skipping ahead or resuming.
I can reproduce this, both using the 4.x Tizen client and the preview client. I have also triggered the same issue by pausing and then resuming while staying in the playback screen. In the attached logs however, I only did a skip/resume to around the 1-hour mark to reproduce the issue.
In the attached logs I first played “Inception” from the beginning, with correct playback results. Then I skipped ahead to around the 1-hour mark, and got BufferingTimeout. Bascially, if I try to skip to any chapter (except for 0:00 - the first chapter) playback will fail.
I’ve tried to compile a timeline of the above reproduction, from the log file. Note that I have removed a lot of lines in between! The below is a condensed and stripped interpretation, not a log file extract. Trying to aid the support process.
Jul 24, 2020 16:45:07.810 [12624] INFO - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] Navigated to screen: PreplayScreen
Jul 24, 2020 16:45:23.829 [12624] DEBUG - Codecs: hardware transcoding: testing API nvenc
Jul 24, 2020 16:45:24.459 [12624] DEBUG - TPU: hardware transcoding: final decoder: nvdec, final encoder: nvenc
Jul 24, 2020 16:45:24.630 [14512] DEBUG - Completed: [127.0.0.1:55526] 200 PUT /video/:/transcode/session/gmjronuigwqrp4ypynycskkr/cdd3f3c1-24b0-440d-8b8d-edbc0ec90443/progress/streamDetail?index=31&id=0&codec=hdmv_pgs_subtitle&type=subtitle&language=tur (6 live) 0ms 195 bytes (pipelined: 38) (range: bytes=0-)
Jul 24, 2020 16:45:24.631 [12624] DEBUG - Started session successfully: gmjronuigwqrp4ypynycskkr
Jul 24, 2020 16:45:25.168 [15224] INFO - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] Navigated to screen: VisualMediaPlaybackScreen
Jul 24, 2020 16:45:25.170 [15224] INFO - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [PlaybackSessionController] playback state changed to: buffering
Jul 24, 2020 16:45:29.959 [6196] INFO - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [AVPlay[1] (IDLE)] Buffering...
Jul 24, 2020 16:45:35.099 [6196] INFO - [Plex for Samsung]
[droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [AVPlay[1] (READY)] onBuffering: 55%
Jul 24, 2020 16:45:35.100 [6196] INFO - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [AVPlay[1] (READY)] Buffering Complete
Jul 24, 2020 16:45:35.592 [15224] DEBUG - Returning segment 9 from session
Jul 24, 2020 16:45:50.991 [12624] DEBUG - Asked for segment 24 from session.
Jul 24, 2020 16:45:51.384 [6196] DEBUG - Transcoder segment range: 0 - 24 (24)
Jul 24, 2020 16:45:51.392 [12624] DEBUG - Returning segment 24 from session
Playback works well, until I skip ahead in the client at around 16:46:00 (tried to time it)
Jul 24, 2020 16:46:02.427 [6196] DEBUG - Client [idfu4np9oqpc6c7rnnhf1ilu] reporting timeline state playing, progress of 30576/8888299ms for guid=, playbackTime=30ms ratingKey=6 url=, key=/library/metadata/6, containerKey=, metadataId=6, source=
Jul 24, 2020 16:46:07.242 [15224] DEBUG - Statistics: (idfu4np9oqpc6c7rnnhf1ilu) Reporting active playback in state 2 of type 1 (scrobble: 0) for account 1
Jul 24, 2020 16:46:07.243 [14512] DEBUG - Completed: [192.168.1.127:45553] 200 GET /:/timeline?key=%2Flibrary%2Fmetadata%2F6&ratingKey=6&state=buffering&time=34579&duration=8888299&playbackTime=34&playQueueItemID=32 (8 live) GZIP 2ms 832 bytes (pipelined: 8)
Jul 24, 2020 16:46:07.279 [14512] DEBUG - Failed to stream media, client probably disconnected after 2163504 bytes: 10054 - An existing connection was forcibly closed by the remote host
Jul 24, 2020 16:46:07.280 [6196] DEBUG - Client [idfu4np9oqpc6c7rnnhf1ilu] reporting timeline state buffering, progress of 3681552/8888299ms for guid=, playbackTime=34ms ratingKey=6 url=, key=/library/metadata/6, containerKey=, metadataId=6, source=
Jul 24, 2020 16:46:07.281 [6196] DEBUG - Play progress on 6 'Inception' - got played 3681552 ms by account 1!
Jul 24, 2020 16:46:07.282 [6196] DEBUG - [Now] Updated play state for /library/metadata/6.
Jul 24, 2020 16:46:07.282 [6196] DEBUG - Statistics: (idfu4np9oqpc6c7rnnhf1ilu) Reporting active playback in state 2 of type 1 (scrobble: 0) for account 1
Jul 24, 2020 16:46:07.294 [6196] DEBUG - Asked for segment 3681 from session.
Jul 24, 2020 16:46:07.294 [6196] DEBUG - We're in a seek: asked for 3681, min/max available was 0/39
Jul 24, 2020 16:46:07.294 [6196] DEBUG - Segment #3681 WON.
Jul 24, 2020 16:46:07.294 [6196] DEBUG - Killing job.
Jul 24, 2020 16:46:07.395 [6196] DEBUG - Job was already killed, not killing again.
Jul 24, 2020 16:46:07.395 [1716] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 2744 is -1059143458 (intentional termination)
Jul 24, 2020 16:46:07.395 [6196] DEBUG - Stopping transcode session gmjronuigwqrp4ypynycskkr
Jul 24, 2020 16:46:07.401 [6196] DEBUG - Starting a transcode session gmjronuigwqrp4ypynycskkr at offset 3681.0 (state=3)
Jul 24, 2020 16:46:07.406 [15224] DEBUG - [Now] Adding 1 sessions.
Jul 24, 2020 16:46:07.407 [6196] DEBUG - Jobs: Starting child process with pid 15200
Jul 24, 2020 16:46:07.407 [6196] DEBUG - Started session successfully: gmjronuigwqrp4ypynycskkr
Jul 24, 2020 16:46:07.407 [12936] DEBUG - Streaming Resource: Resetting play progress due to seek
Jul 24, 2020 16:46:11.841 [12624] INFO - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [PlaybackSessionController] playback state changed to: buffering
Jul 24, 2020 16:46:20.724 [15224] DEBUG - Transcoder segment range: 3677 - 3677 (3677)
Jul 24, 2020 16:46:36.978 [15224] DEBUG - Client [idfu4np9oqpc6c7rnnhf1ilu] reporting timeline state stopped, progress of 3681552/8888299ms for guid=, playbackTime=34ms ratingKey=6 url=, key=/library/metadata/6, containerKey=, metadataId=6, source=
Jul 24, 2020 16:46:36.978 [15224] DEBUG - Play progress on 6 'Inception' - got played 3681552 ms by account 1!
Jul 24, 2020 16:46:36.985 [15224] DEBUG - Statistics: (idfu4np9oqpc6c7rnnhf1ilu) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1
Jul 24, 2020 16:46:36.985 [15224] DEBUG - Streaming Resource: Terminating session 0x547b810:idfu4np9oqpc6c7rnnhf1ilu which is using transcoder slot. Used slots is now 0
Jul 24, 2020 16:46:36.985 [12032] DEBUG - Killing job.
Jul 24, 2020 16:46:36.985 [15224] DEBUG - Streaming Resource: Terminated session 0x547b810:idfu4np9oqpc6c7rnnhf1ilu with reason Client stopped playback.
Jul 24, 2020 16:46:36.985 [15224] DEBUG - Streaming Resource: Removing session 0x547b810:idfu4np9oqpc6c7rnnhf1ilu
Jul 24, 2020 16:46:37.096 [9576] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 15200 is -1059143458 (intentional termination)
Jul 24, 2020 16:46:37.096 [6196] WARN - Transcode runner appears to have died.
Jul 24, 2020 16:46:37.096 [14984] DEBUG - Streaming Resource: Resetting play progress due to seek
Jul 24, 2020 16:46:37.096 [5048] DEBUG - Job was already killed, not killing again.
Jul 24, 2020 16:46:37.096 [9576] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 15200 is -1059143458 (intentional termination)
Jul 24, 2020 16:46:37.096 [6196] WARN - Transcode runner appears to have died.
Jul 24, 2020 16:46:37.096 [14984] DEBUG - Streaming Resource: Resetting play progress due to seek
Jul 24, 2020 16:46:37.096 [12032] DEBUG - Job was already killed, not killing again.
Jul 24, 2020 16:46:37.096 [12032] DEBUG - Stopping transcode session gmjronuigwqrp4ypynycskkr
Jul 24, 2020 16:46:37.097 [5048] DEBUG - Job was already killed, not killing again.
Jul 24, 2020 16:46:42.049 [12624] ERROR - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [AVPlay[1] (PLAYING)] onAVPlayError: PLAYER_ERROR_CONNECTION_FAILED
Jul 24, 2020 16:46:42.049 [12624] INFO - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [PlaybackSessionController] playback state changed to: stopped
Jul 24, 2020 16:46:42.049 [12624] ERROR - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] Playback error occurred. Reason: BufferingTimeout, Player took too long buffering, stopping playback
Jul 24, 2020 16:46:42.049 [12624] WARN - [Plex for Samsung] [droolproblems@protonmail.com:idfu4np9oqpc6c7rnnhf1ilu] [PlaybackSessionController] could not retry, reporting error
This log message sticks out as possibly extra interesting to me: Transcode runner appears to have died.
We currently cannot pause for toilet breaks during movie nights, so help is appreciated! 
Server Version#: 4.38.2
Player Version#: 5.0.13
Logs: Plex Media Server Logs_2020-07-24_16-47-05.zip (48.8 KB)
