Plex ending episodes before the end of the actual episode

Plex Players Streaming Devices
1

Server Version#: Ubuntu - Version 1.19.5.3112
Player Version#: Samsung TV - Plex app Version 5.1.0

Direct stream, no transcoding.

Plex is ending tv shows early. Sometimes as early as 3 or 4 minutes in to the episode. What is happening?! I have not extra plugins, the setup is fairly basic (4 HDD’s, no RAID, no Roku devices. Just my Plex server connected with ethernet, and my Samsung smartTV that is connected with Ethernet as well. All apps are up-to-date. The server has been restarted, the TV has been restarted.

Any and all help would be appreciated!

2

If streaming is ending early, the app is ending playback.

Can you recreate and capture the premature end so I can confirm?

Please verify DEBUG logging is ON and VERBOSE logging is OFF (don’t forget to save any changes) before recreating.

When you’ve captured, Download the Logs ZIP file and attach it here please

3

Okay,

I’ve done what you ask, the player ended the tv show about 3 min into the episode, and this happened at 12:10pm

Plex Media Server Logs_2020-08-27_12-11-25.zip (4.5 MB)

4

Would you please check your networking?

  1. DHCP server refresh interval
  2. Cabling?

Something is forcing a server adapter reset – which will force terminate any open playbacks.

Aug 27, 2020 12:11:21.030 [0x7fc34b7fe700] DEBUG - Content-Length is -1 (of total: -1).
Aug 27, 2020 12:11:24.256 [0x7fc317fff700] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Aug 27, 2020 12:11:24.256 [0x7fc317fff700] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=3, flags=0x11003, change=0x0
Aug 27, 2020 12:11:24.256 [0x7fc317fff700] DEBUG - Network change.
Aug 27, 2020 12:11:24.256 [0x7fc317fff700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Aug 27, 2020 12:11:24.256 [0x7fc317fff700] DEBUG - Network change notification but nothing changed.
Aug 27, 2020 12:11:24.502 [0x7fc3668f8700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:11:24.502 [0x7fc2c6ffd700] DEBUG - Request: [10.100.100.250:59328 (Subnet)] GET /diagnostics/logs (8 live) TLS GZIP Signed-in Token (jay@aperturejay.com)

As for the playback early termination, I am seeing two playbacks.
Which should I be looking at?

Aug 27, 2020 12:09:49.085 [0x7fc3670f9700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:49.085 [0x7fc3557fa700] DEBUG - Request: [10.100.100.247:40916 (Subnet)] GET /:/timeline?key=%2Flibrary%2Fmetadata%2F34605&ratingKey=34605&state=playing&time=151480&duration=1488160&playbackTime=92&playQueueItemID=92498 (11 live) GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:49.087 [0x7fc3557fa700] DEBUG - Client [hh5ucdj1mks90wfpd2enlv7v] reporting timeline state playing, progress of 151480/1488160ms for guid=, playbackTime=92ms ratingKey=34605 url=, key=/library/metadata/34605, containerKey=, metadataId=34605, source=
Aug 27, 2020 12:09:49.088 [0x7fc3557fa700] DEBUG - Play progress on 34605 'Episode One' - got played 151480 ms by account 1!
Aug 27, 2020 12:09:49.091 [0x7fc3557fa700] DEBUG - [Now] User is jay@aperturejay.com (ID: 1)
Aug 27, 2020 12:09:49.091 [0x7fc3557fa700] DEBUG - [Now] Device is Tizen (TV 2018).
Aug 27, 2020 12:09:49.091 [0x7fc3557fa700] DEBUG - [Now] Profile is Generic
Aug 27, 2020 12:09:49.091 [0x7fc3557fa700] DEBUG - [Now] Updated play state for /library/metadata/34605.
Aug 27, 2020 12:09:49.091 [0x7fc3557fa700] DEBUG - Statistics: (hh5ucdj1mks90wfpd2enlv7v) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 1
Aug 27, 2020 12:09:49.097 [0x7fc3670f9700] DEBUG - Completed: [10.100.100.247:40916] 200 GET /:/timeline?key=%2Flibrary%2Fmetadata%2F34605&ratingKey=34605&state=playing&time=151480&duration=1488160&playbackTime=92&playQueueItemID=92498 (11 live) GZIP 12ms 764 bytes (pipelined: 20)
Aug 27, 2020 12:09:49.957 [0x7fc3557fa700] DEBUG - Transcoder segment range: 0 - 348 (348)
Aug 27, 2020 12:09:55.105 [0x7fc3567fc700] DEBUG - Transcoder segment range: 0 - 349 (349)
Aug 27, 2020 12:09:59.271 [0x7fc3668f8700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.272 [0x7fc3650f5700] DEBUG - Request: [10.100.100.247:40916 (Subnet)] GET /:/timeline?key=%2Flibrary%2Fmetadata%2F34605&ratingKey=34605&state=stopped&time=161375&duration=1488160&playbackTime=102&playQueueItemID=92498 (9 live) GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:59.273 [0x7fc3650f5700] DEBUG - Client [hh5ucdj1mks90wfpd2enlv7v] reporting timeline state stopped, progress of 161375/1488160ms for guid=, playbackTime=102ms ratingKey=34605 url=, key=/library/metadata/34605, containerKey=, metadataId=34605, source=
Aug 27, 2020 12:09:59.275 [0x7fc3650f5700] DEBUG - Play progress on 34605 'Episode One' - got played 161375 ms by account 1!
Aug 27, 2020 12:09:59.289 [0x7fc3650f5700] DEBUG - [Now] User is jay@aperturejay.com (ID: 1)
Aug 27, 2020 12:09:59.289 [0x7fc3650f5700] DEBUG - [Now] Device is Tizen (TV 2018).
Aug 27, 2020 12:09:59.289 [0x7fc3650f5700] DEBUG - [Now] Profile is Generic
Aug 27, 2020 12:09:59.289 [0x7fc3650f5700] DEBUG - [Now] Updated play state for /library/metadata/34605.
Aug 27, 2020 12:09:59.290 [0x7fc3650f5700] DEBUG - Statistics: (hh5ucdj1mks90wfpd2enlv7v) Reporting active playback in state 3 of type 4 (scrobble: 0) for account 1
Aug 27, 2020 12:09:59.291 [0x7fc3650f5700] DEBUG - Streaming Resource: Terminated session 0x7fc3402b62a0:hh5ucdj1mks90wfpd2enlv7v with reason Client stopped playback.
Aug 27, 2020 12:09:59.291 [0x7fc3650f5700] DEBUG - Streaming Resource: Removing session 0x7fc3402b62a0:hh5ucdj1mks90wfpd2enlv7v
Aug 27, 2020 12:09:59.291 [0x7fc2fcff9700] DEBUG - Killing job.
Aug 27, 2020 12:09:59.291 [0x7fc2fcff9700] DEBUG - Signalling job ID 4859 with 9
Aug 27, 2020 12:09:59.291 [0x7fc2fcff9700] DEBUG - Job was already killed, not killing again.
Aug 27, 2020 12:09:59.291 [0x7fc2fcff9700] DEBUG - Stopping transcode session sbxon75jn92ukom00ox0f6rg
Aug 27, 2020 12:09:59.291 [0x7fc3648f4700] DEBUG - Cleaning directory for session sbxon75jn92ukom00ox0f6rg (/mnt/4TB/Stream/temptrancode/Transcode/Sessions/plex-transcode-sbxon75jn92ukom00ox0f6rg-94ea6d28-4d05-493e-a421-6eedabfc4d0d)
Aug 27, 2020 12:09:59.292 [0x7fc3670f9700] DEBUG - Completed: [10.100.100.247:40916] 200 GET /:/timeline?key=%2Flibrary%2Fmetadata%2F34605&ratingKey=34605&state=stopped&time=161375&duration=1488160&playbackTime=102&playQueueItemID=92498 (9 live) GZIP 20ms 731 bytes (pipelined: 21)
Aug 27, 2020 12:09:59.294 [0x7fc370cf3700] DEBUG - Jobs: '/snap/plexmediaserver/108/Plex Transcoder' exit code for process 4859 is -9 (signal: Killed)
Aug 27, 2020 12:09:59.301 [0x7fc2fcff9700] DEBUG - Transcoder: Cleaning old transcode directories.
Aug 27, 2020 12:09:59.302 [0x7fc2fcff9700] DEBUG - Transcoder: Cleaning old transcode directory: "/mnt/4TB/Stream/temptrancode/Transcode/Sessions/plex-transcode-sbxon75jn92ukom00ox0f6rg-94ea6d28-4d05-493e-a421-6eedabfc4d0d"
Aug 27, 2020 12:09:59.319 [0x7fc3670f9700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.319 [0x7fc354ff9700] DEBUG - Request: [10.100.100.247:40916 (Subnet)] GET /video/:/transcode/universal/stop?session=sbxon75jn92ukom00ox0f6rg (8 live) GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:59.319 [0x7fc3658f6700] DEBUG - Found session GUID of sbxon75jn92ukom00ox0f6rg in session start.
Aug 27, 2020 12:09:59.319 [0x7fc3658f6700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
Aug 27, 2020 12:09:59.319 [0x7fc3670f9700] DEBUG - Completed: [10.100.100.247:40916] 200 GET /video/:/transcode/universal/stop?session=sbxon75jn92ukom00ox0f6rg (8 live) GZIP 0ms 195 bytes (pipelined: 22)
Aug 27, 2020 12:09:59.329 [0x7fc3668f8700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.329 [0x7fc3557fa700] DEBUG - Request: [10.100.100.250:59296 (Subnet)] GET /status/sessions (9 live) TLS GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:59.329 [0x7fc3557fa700] DEBUG - [Now] Adding 0 sessions.
Aug 27, 2020 12:09:59.329 [0x7fc2fcff9700] DEBUG - Whacked session sbxon75jn92ukom00ox0f6rg, 0 remaining.
Aug 27, 2020 12:09:59.329 [0x7fc2fcff9700] DEBUG - Cleaning directory for session sbxon75jn92ukom00ox0f6rg (/mnt/4TB/Stream/temptrancode/Transcode/Sessions/plex-transcode-sbxon75jn92ukom00ox0f6rg-94ea6d28-4d05-493e-a421-6eedabfc4d0d)
Aug 27, 2020 12:09:59.329 [0x7fc3668f8700] DEBUG - Completed: [10.100.100.250:59296] 200 GET /status/sessions (9 live) TLS GZIP 0ms 429 bytes (pipelined: 1)
Aug 27, 2020 12:09:59.344 [0x7fc3670f9700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.344 [0x7fc3567fc700] DEBUG - Request: [10.100.100.250:59297 (Subnet)] GET /library/sections/2/onDeck?includeExternalMedia=1 (14 live) TLS Page 0-16 GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:59.344 [0x7fc3668f8700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.344 [0x7fc3670f9700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.344 [0x7fc3650f5700] DEBUG - Request: [10.100.100.250:59298 (Subnet)] GET /library/sections/2/all?type=4&sort=originallyAvailableAt:desc&includeExternalMedia=1 (14 live) TLS Page 0-50 GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:59.344 [0x7fc356ffd700] DEBUG - Request: [10.100.100.250:59300 (Subnet)] GET /library/sections/2/all?type=4&sort=viewUpdatedAt:desc&viewOffset%3E=300&viewUpdatedAt%3E=1588865021&includeExternalMedia=1 (14 live) TLS Page 0-15 GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:59.344 [0x7fc3670f9700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.344 [0x7fc3668f8700] DEBUG - Auth: authenticated user 1 as jay@aperturejay.com
Aug 27, 2020 12:09:59.344 [0x7fc3557fa700] DEBUG - Request: [10.100.100.250:59301 (Subnet)] GET /library/sections/2/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&type=4&includeExternalMedia=1 (14 live) TLS Page 0-50 GZIP Signed-in Token (jay@aperturejay.com)
Aug 27, 2020 12:09:59.345 [0x7fc354ff9700] DEBUG - Request: [10.100.100.250:59299 (Subnet)] GET /hubs/home/recentlyAdded?type=2&sectionID=2&includeExternalMedia=1 (14 live) TLS Page 0-49 GZIP Signed-in Token (jay@aperturejay.com)
5

I’ll double check all the cables, and the DHCP settings. (we don’t have any other connectivity issues currently. My wife and I both work from home and are able to both conduct zoom conferences at the same time without any issue)

I’ll set the DHCP timeout for the PMS to 24hrs.

User 1 - jay@aperturejay.com is my account. This is the one experiencing issues.

6

This is weird. When I try to play the first episode of a central park the episode is ended 3 min in. But when I have rick and morty playing, it just plays through all of the episodes just fine. Same with Brooklyn 99…

Very weird.

7

Might it be the file itself? Force Plex to Analyze it again and see what happens.

8

I tried re analyzing the files, that didn’t work.

I ended up replacing the file, and it works just fine now.

Thanks for all of your help!

9

I’m having a similar issue. Plex stops streaming files with about one minute left. I have checked the files and they play fine on my computer. It doesn’t happen every time but it’s frequent.

10

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