Transcoding randomly stops after x minutes

Plex Media Server NAS & Devices
1

Server Version#: 1.22.3.4523
Player Version#: 8.17.1.25326 (a6ce558e)
Both running on Shield TV Pro 4k (model number P2897)

Plex Media Server Logs_2021-05-23_21-01-40.zip (997.3 KB)

Video freezes, audio stops, but UI remains responsive and timer keeps going. There’s no spinner in the middle of the picture. I can go forward and back, pause and resume, etc, but there’s no audio, and the picture remains frozen. If I exit the player and resume playback then it usually starts back up, but stops again eventually.

As far as I can tell this only happens when transcoding, not with direct play.

Every time this happens I can see a network change event in the logs at around the same time. Can this be the cause?

May 23, 2021 21:00:20.754 [7721] DEBUG - NetworkInterface: received Netlink message len=1276, type=RTM_NEWLINK, flags=0x0
May 23, 2021 21:00:20.754 [7721] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=7, flags=0x11003, change=0x0
May 23, 2021 21:00:20.754 [7721] DEBUG - Network change.
May 23, 2021 21:00:20.754 [7721] DEBUG - NetworkInterface: Notified of network changed (force=0)
May 23, 2021 21:00:20.798 [7721] DEBUG - Detected primary interface: 0.0.0.0
May 23, 2021 21:00:20.798 [7721] DEBUG - Network interfaces:
May 23, 2021 21:00:20.798 [7721] DEBUG -  * 1 lo (127.0.0.1) (loopback: 1)
May 23, 2021 21:00:20.798 [7721] DEBUG -  * 7 wlan0 (192.168.0.249) (loopback: 0)
May 23, 2021 21:00:20.798 [7721] DEBUG -  * 1 lo (::1%1) (loopback: 1)
May 23, 2021 21:00:20.809 [7721] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 23, 2021 21:00:20.809 [7721] DEBUG - NetworkService: Dispatch network change after two second delay.
May 23, 2021 21:00:20.809 [7721] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 23, 2021 21:00:20.809 [7721] DEBUG - NetworkService: Dispatch network change after two second delay.
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Dispatch network change after two second delay.
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Dispatch network change after two second delay.
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Dispatch network change after two second delay.
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 23, 2021 21:00:20.810 [7721] DEBUG - NetworkService: Dispatch network change after two second delay.
May 23, 2021 21:00:21.492 [7304] DEBUG - EventSource: Failure in ReadData (103 - Software caused connection abort).
May 23, 2021 21:00:21.493 [7303] DEBUG - EventSource: Failure in ReadData (103 - Software caused connection abort).
May 23, 2021 21:00:21.532 [7304] DEBUG - MyPlex: We appear to have lost Internet connectivity, resetting device URL cache.
May 23, 2021 21:00:21.532 [7304] ERROR - EventSource: Retrying in 15 seconds.
May 23, 2021 21:00:21.532 [7304] DEBUG - handleStreamRead code 103: Software caused connection abort
May 23, 2021 21:00:21.611 [7303] ERROR - EventSource: Retrying in 15 seconds.
May 23, 2021 21:00:21.612 [7304] DEBUG - NotificationStream: Removing because of error
May 23, 2021 21:00:21.612 [7304] DEBUG - Completed after connection close: [192.168.0.249:33722] -3 GET /:/websockets/notifications (7 live) TLS GZIP 1844771ms 322562 bytes
May 23, 2021 21:00:21.612 [7303] DEBUG - handleStreamRead code 103: Software caused connection abort
May 23, 2021 21:00:21.612 [7303] DEBUG - NotificationStream: Removing because of error
May 23, 2021 21:00:21.612 [7303] DEBUG - Completed after connection close: [192.168.0.249:33738] -3 GET /:/websockets/notifications (7 live) TLS GZIP 1842085ms 322428 bytes
May 23, 2021 21:00:21.976 [12012] DEBUG - [Transcode] Transcoder segment range: 0 - 57 (57)
May 23, 2021 21:00:21.980 [10425] DEBUG - [Transcode] Transcoder segment range: 0 - 58 (57)
May 23, 2021 21:00:22.064 [12010] DEBUG - [Transcode/89aa6af90df4f755-com-plexapp-android] Returning segment 57 from session
May 23, 2021 21:00:22.064 [12010] DEBUG - Content-Length of /storage/emulated/0/Android/data/com.plexapp.mediaserver.smb/cache/Transcode/Sessions/plex-transcode-89aa6af90df4f755-com-plexapp-android-8d361a7c-7834-4abc-adc5-2869fe3d831b/media-00057.ts is 1328186 (of total: 1328186).
May 23, 2021 21:00:22.179 [12010] ERROR - Caught exception trying to stream file: /storage/emulated/0/Android/data/com.plexapp.mediaserver.smb/cache/Transcode/Sessions/plex-transcode-89aa6af90df4f755-com-plexapp-android-8d361a7c-7834-4abc-adc5-2869fe3d831b/media-00057.ts: write: Software caused connection abort
May 23, 2021 21:00:22.180 [7303] DEBUG - Completed after connection close: [192.168.0.249:40952] 500 GET /video/:/transcode/universal/session/89aa6af90df4f755-com-plexapp-android/base/00057.ts (3 live) TLS 1619ms 0 bytes (pipelined: 29)
May 23, 2021 21:00:22.180 [7303] DEBUG - Removed transcode data consumer, active count 1 => 0
May 23, 2021 21:00:22.815 [7722] DEBUG - Network change for advertiser.
May 23, 2021 21:00:22.816 [7722] ERROR - Error broadcasting message: send_to: Network is unreachable
2

It appears the device hosting your PMS is periodically losing internet connection.

What is hosting your PMS, and what is your home network setup? You’re not hosting Plex on wifi correct?

Edit: Reading the logs, something is constantly changing on your network and it looks like it’s freaking out the PMS. I’m curious to hear what your network setup is. It might be a case of DHCP vs static IP or who knows what.

3

Setup was as follows:

|R|
|o| <===wire===> DiskStation (movie files)
|u|
|t|
|e| <===wifi===> Shield (Plex server, Plex client)
|r|

So the client is connecting to the server locally on the Shield, and the server was reading the files from the NAS through wifi. The NAS is on static ip configuration (don’t remember why), everything else on the network (including the Shield) is on DHCP.

I switched the Shield to wired connection a couple of days ago, and haven’t had the issue reoccur since then, so hopefully I’m good now. I was unable to figure out what is/was going on on the Shield with the wifi interface, and what was generating the network change events, but the Plex transcoder is the only thing that cannot handle it. There are no issues with any other apps on the Shield (even Plex was fine when using direct play), or with anything else on any of my other devices.

4

Excellent, I think that should fix your problem. I found in my case the Shield and NAS worked best under wired connections, but of course I can still stream to wireless clients.

Good luck!

5

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