Resume issue from Plex server

Plex Players Streaming Devices
1

Server Version#: 1.18.7.2457 (Synology 718+
Player Version#: 7.28.0.15501 (Fire Stick 4K)

Sometimes, when resuming a movie or a show, the player keeps showing the loading icon and never resumes. I can press the back button, but then it loses the resume point. So the episode starts from the beginning.

The log looks like this,

Feb 24, 2020 22:44:26.846 [0x7fd543fff700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:26.846 [0x7fd543159700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:26.848 [0x7fd540567700] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Feb 24, 2020 22:44:26.849 [0x7fd540567700] DEBUG - Photo cache obtained 18444 bytes from https://192.168.2.238:32400/library/media/3281/chapterImages/1?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 24, 2020 22:44:26.849 [0x7fd540567700] DEBUG - Saving original media file to /volume1/Plex/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/aa/aaa33427ba1df36cd6d7b6d0cdec0f94d19fc42a.jpg
Feb 24, 2020 22:44:26.851 [0x7fd543159700] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Feb 24, 2020 22:44:26.852 [0x7fd543159700] DEBUG - Photo cache obtained 56910 bytes from https://192.168.2.238:32400/library/media/3281/chapterImages/2?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 24, 2020 22:44:26.852 [0x7fd543159700] DEBUG - Saving original media file to /volume1/Plex/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/c5/c5d11917df3a17bbc7cbad86345ee348551ae5b7.jpg
Feb 24, 2020 22:44:26.852 [0x7fd543fff700] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Feb 24, 2020 22:44:26.853 [0x7fd543fff700] DEBUG - Photo cache obtained 136177 bytes from https://192.168.2.238:32400/library/media/3281/chapterImages/3?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 24, 2020 22:44:26.853 [0x7fd543fff700] DEBUG - Saving original media file to /volume1/Plex/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/5f/5f20321a0e3651e46832bf8b123d0966c36bb2c9.jpg
Feb 24, 2020 22:44:26.864 [0x7fd5425a1700] DEBUG - Created thumbnail of size 512x288, has pixels: 1
Feb 24, 2020 22:44:26.880 [0x7fd57bfff700] DEBUG - Completed: [192.168.2.203:58890] 200 GET /photo/:/transcode?height=512&width=512&url=https%3A%2F%2F192.168.2.238%3A32400%2Flibrary%2Fmedia%2F3281%2FchapterImages%2F4%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (15 live) TLS GZIP 90ms 29297 bytes (pipelined: 1)
Feb 24, 2020 22:44:26.903 [0x7fd540567700] DEBUG - Created thumbnail of size 512x288, has pixels: 1
Feb 24, 2020 22:44:26.911 [0x7fd543159700] DEBUG - Created thumbnail of size 512x288, has pixels: 1
Feb 24, 2020 22:44:26.913 [0x7fd57bfff700] DEBUG - Completed: [192.168.2.203:58888] 200 GET /photo/:/transcode?height=512&width=512&url=https%3A%2F%2F192.168.2.238%3A32400%2Flibrary%2Fmedia%2F3281%2FchapterImages%2F1%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (15 live) TLS GZIP 68ms 2638 bytes (pipelined: 1)
Feb 24, 2020 22:44:26.916 [0x7fd543fff700] DEBUG - Created thumbnail of size 512x288, has pixels: 1
Feb 24, 2020 22:44:26.925 [0x7fd57bfff700] DEBUG - Completed: [192.168.2.203:58886] 200 GET /photo/:/transcode?height=512&width=512&url=https%3A%2F%2F192.168.2.238%3A32400%2Flibrary%2Fmedia%2F3281%2FchapterImages%2F2%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (15 live) TLS GZIP 80ms 16737 bytes (pipelined: 1)
Feb 24, 2020 22:44:26.934 [0x7fd57bfff700] DEBUG - Completed: [192.168.2.203:58884] 200 GET /photo/:/transcode?height=512&width=512&url=https%3A%2F%2F192.168.2.238%3A32400%2Flibrary%2Fmedia%2F3281%2FchapterImages%2F3%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (15 live) TLS GZIP 89ms 32342 bytes (pipelined: 1)
Feb 24, 2020 22:44:33.057 [0x7fd57bd11700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:33.057 [0x7fd543159700] DEBUG - Request: [192.168.2.203:58892 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=1421660307-) 
Feb 24, 2020 22:44:33.059 [0x7fd543159700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 43358 (of total: 1421703665).
Feb 24, 2020 22:44:33.060 [0x7fd57bd11700] DEBUG - Completed: [192.168.2.203:58892] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 3ms 43358 bytes (range: bytes=1421660307-) 
Feb 24, 2020 22:44:33.076 [0x7fd57bfff700] DEBUG - Failed to stream media, client probably disconnected after 96649216 bytes: 104 - Connection reset by peer
Feb 24, 2020 22:44:33.076 [0x7fd57bfff700] DEBUG - Completed after connection close: [192.168.2.203:58882] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 8743ms 96649216 bytes (range: bytes=7647-) 
Feb 24, 2020 22:44:33.247 [0x7fd57bd11700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:33.247 [0x7fd5425a1700] DEBUG - Request: [192.168.2.203:58894 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=81714779-) 
Feb 24, 2020 22:44:33.250 [0x7fd5425a1700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 1339988886 (of total: 1421703665).
Feb 24, 2020 22:44:33.462 [0x7fd57bfff700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:33.462 [0x7fd540567700] DEBUG - Request: [192.168.2.203:58896 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=98978354-) 
Feb 24, 2020 22:44:33.465 [0x7fd540567700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 1322725311 (of total: 1421703665).
Feb 24, 2020 22:44:33.485 [0x7fd57bfff700] DEBUG - Failed to stream media, client probably disconnected after 901120 bytes: 104 - Connection reset by peer
Feb 24, 2020 22:44:33.485 [0x7fd57bfff700] DEBUG - Completed after connection close: [192.168.2.203:58894] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 237ms 901120 bytes (range: bytes=81714779-) 
Feb 24, 2020 22:44:33.678 [0x7fd57bfff700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:33.678 [0x7fd543fff700] DEBUG - Request: [192.168.2.203:58898 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=355025705-) 
Feb 24, 2020 22:44:33.683 [0x7fd543fff700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 1066677960 (of total: 1421703665).
Feb 24, 2020 22:44:33.714 [0x7fd57bd11700] DEBUG - Failed to stream media, client probably disconnected after 1245184 bytes: 104 - Connection reset by peer
Feb 24, 2020 22:44:33.715 [0x7fd57bd11700] DEBUG - Completed after connection close: [192.168.2.203:58896] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 252ms 1245184 bytes (range: bytes=98978354-) 
Feb 24, 2020 22:44:33.903 [0x7fd57bfff700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:33.903 [0x7fd5425a1700] DEBUG - Request: [192.168.2.203:58900 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=393182817-) 
Feb 24, 2020 22:44:33.906 [0x7fd5425a1700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 1028520848 (of total: 1421703665).
Feb 24, 2020 22:44:33.924 [0x7fd57bd11700] DEBUG - Failed to stream media, client probably disconnected after 753664 bytes: 104 - Connection reset by peer
Feb 24, 2020 22:44:33.924 [0x7fd57bd11700] DEBUG - Completed after connection close: [192.168.2.203:58898] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 245ms 753664 bytes (range: bytes=355025705-) 
Feb 24, 2020 22:44:34.134 [0x7fd57bfff700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:34.134 [0x7fd543159700] DEBUG - Request: [192.168.2.203:58902 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=497589032-) 
Feb 24, 2020 22:44:34.137 [0x7fd543159700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 924114633 (of total: 1421703665).
Feb 24, 2020 22:44:34.164 [0x7fd57bd11700] DEBUG - Failed to stream media, client probably disconnected after 1097728 bytes: 104 - Connection reset by peer
Feb 24, 2020 22:44:34.164 [0x7fd57bd11700] DEBUG - Completed after connection close: [192.168.2.203:58900] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 261ms 1097728 bytes (range: bytes=393182817-) 
Feb 24, 2020 22:44:34.437 [0x7fd57bfff700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:34.437 [0x7fd540567700] DEBUG - Request: [192.168.2.203:58904 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=580295998-) 
Feb 24, 2020 22:44:34.440 [0x7fd540567700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 841407667 (of total: 1421703665).
Feb 24, 2020 22:44:34.455 [0x7fd57bd11700] DEBUG - Failed to stream media, client probably disconnected after 1622016 bytes: 104 - Connection reset by peer
Feb 24, 2020 22:44:34.455 [0x7fd57bd11700] DEBUG - Completed after connection close: [192.168.2.203:58902] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 320ms 1622016 bytes (range: bytes=497589032-) 
Feb 24, 2020 22:44:34.703 [0x7fd57bfff700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:34.703 [0x7fd543fff700] DEBUG - Request: [192.168.2.203:58906 (Subnet)] GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS Signed-in Token (protik77) (range: bytes=652170268-) 
Feb 24, 2020 22:44:34.707 [0x7fd543fff700] DEBUG - Content-Length of /volume1/downloads/shows/Westworld/Season 2/Westworld (2016) - S02E03 - VirtĂą e Fortuna (1080p AMZN WEB-DL x265 RZeroX).mkv is 769533397 (of total: 1421703665).
Feb 24, 2020 22:44:34.727 [0x7fd57bd11700] DEBUG - Failed to stream media, client probably disconnected after 1867776 bytes: 104 - Connection reset by peer
Feb 24, 2020 22:44:34.728 [0x7fd57bd11700] DEBUG - Completed after connection close: [192.168.2.203:58904] 206 GET /library/parts/3361/1582608448/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=74944 (16 live) TLS 290ms 1867776 bytes (range: bytes=580295998-) 
Feb 24, 2020 22:44:35.073 [0x7fd57bd11700] DEBUG - Auth: authenticated user 1 as protik77
Feb 24, 2020 22:44:35.073 [0x7fd5425a1700] DEBUG - Request: [192.168.2.203:58876 (Subnet)] GET /:/timeline?audioStreamID=11295&bufferedTime=0&duration=3582996&guid=com.plexapp.agents.thetvdb%3A%2F%2F296762%2F2%2F3%3Flang%3Den&key=%2Flibrary%2Fmetadata%2F2212&playbackTime=9964&playQueueItemID=3196&ratingKey=2212&state=paused&time=1356471&timeStalled=3&token=xxxxxxxxxxxxxxxxxxxx (15 live) TLS GZIP Signed-in Token (protik77)
Feb 24, 2020 22:44:35.075 [0x7fd5425a1700] DEBUG - Client [783673ddc30eb613-com-plexapp-android] reporting timeline state paused, progress of 1356471/3582996ms for guid=com.plexapp.agents.thetvdb://296762/2/3?lang=en, playbackTime=9964ms ratingKey=2212 url=, key=/library/metadata/2212, containerKey=, metadataId=2212, source=
Feb 24, 2020 22:44:35.076 [0x7fd5425a1700] DEBUG - Play progress on 2212 'VirtĂą e Fortuna' - got played 1356471 ms by account 1!
2

Bumping it up for visibility.

3

This is getting more frequent and ridiculous every day. It’s not limited to fire stick only. Happened from Shield as well. Posting log again from Fire Stick.

Don’t know how to get attention of devs about this.

Plex Media Server Logs_2020-04-20_11-37-20.zip (906.8 KB)

4

Daily bump up comment.

5

Daily bump up comment.

This time with more failure logs from Shield TV: Plex Media Server Logs_2020-04-22_11-30-29.zip (999.5 KB)

6

Is it possible this only happens when you try to resume a HEVC/H.265 file?

7

I haven’t checked the type of the file. It happens quite frequently. Usually when I first try to resume a playback.

8

I have the same problem I also made a Topic if you want to check it out I can’t resume HEVC/H.265 movies/series but i can resume AVC/H.264 fils I don’t understand why. Can you check if it also only happens with H.265 files on your server? (Also I saw that it doesn’t happen when you force the video to be transcoded.)

1 Like
9

Today it happened for a h.264 file. I will keep any eye for this.

Also I am going to add one comment each day to this thread. That’s the best way I could think of to get the attention of devs without throwing obscenities.

1 Like
10

Daily bump comment.

11

Here’s another log dump.

Plex Media Server Logs_2020-04-23_21-53-19.zip (884.0 KB)

12

Daily bump up comment.

13

Can you provide the log from the Fire TV instead?

Edit - Also, you need to turn on “debug” logging for PMS. There isn’t enough info in your logs to identify the problem.

14

Here’s the log from my FireStick when it happened. I tried to resume from 29 minute which failed. It just showed loading icon. I pressed back and the resume data was lost and the movie started from the beginning.

FireStick_0502.log (194.1 KB)

Edit: This time debug logging was not enabled in my PMS. I turned it on now and will post again when this happens.

15

According to your log, it tried to resume at the point you stopped but it hit the end of the file.

05-02 11:56:45.636 i: [Player][ExoPlayer] onTimelineChanged (Position: 1753697 ms)
05-02 11:56:46.034 i: [FFmpeg] [INFO] [Cache] Cache is enabled, connecting cache context.
05-02 11:56:46.116 i: [FFmpeg] [INFO] [Cache] Worker thread has been enabled and will start processing data.
05-02 11:56:46.129 i: [FFmpeg] [INFO] [Cache] Source seek requested to 4582627658 bytes with 0 whence.
05-02 11:56:46.131 i: [FFmpeg] [INFO] [Cache] Seek found to 4582627658 bytes.
05-02 11:56:46.379 i: [FFmpeg] [INFO] [Cache] Seek complete to 4582627658 bytes.
05-02 11:56:46.383 i: [FFmpeg] [INFO] [Cache] Seek has returned with a response of 4582627658.
05-02 11:56:46.439 i: [FFmpeg] [INFO] [Cache] End of source has been reached at 4582629304 bytes.

How large is your file? Are you been able to play past that point if you start from the beginning?

16

This happens for all sizes. If I press back and click on play, it starts from the beginning. It plays fine for the whole time. To resume, I just have to manually start from that point.

17

Happened again today on a 6.93GB file. I got both client and server log. I took the logs right after this happened, so everything should be latest. Client shows the same error.

05-07 21:02:37.637  i: [FFmpeg] [INFO] [Cache] Seek complete to 7437572519 bytes.
05-07 21:02:37.640  i: [FFmpeg] [INFO] [Cache] Seek has returned with a response of 7437572519.
05-07 21:02:37.646  i: [FFmpeg] [INFO] [Cache] End of source has been reached at 7437683477 bytes.
05-07 21:02:37.667  i: [FFmpeg] [INFO] [Cache] Source seek requested to 5069682 bytes with 0 whence.
05-07 21:02:37.668  i: [FFmpeg] [INFO] [Cache] Seek found to 5069682 bytes.
05-07 21:02:37.825  i: [FFmpeg] [INFO] [Cache] Seek complete to 5069682 bytes.
05-07 21:02:37.825  i: [FFmpeg] [INFO] [Cache] Seek has returned with a response of 5069682.
05-07 21:02:37.827  i: [FFmpeg] [INFO] [Cache] Source seek requested to 1135536625 bytes with 0 whence.

client.log (60.7 KB)

Plex Media Server Logs_2020-05-07_21-07-03.zip (3.3 MB)

18

Thank I will take a look. Can you also provide me the XML for that movie from PMS?

19

Sure. Here you go.

3737.zip (11.2 KB)

20

Happened again today.

Server logs: Plex Media Server Logs_2020-05-23_21-19-07.zip (3.6 MB)
App logs: plex_aftv_log_0523.zip (129.7 KB)
File xml: file.zip (1.5 KB)