Server Version#: 1.40.2.8395 on Raspberry Pi 3/Raspbian Linux 6.1.25-v8+ #1647
Player Version#: 7.18 (Roku)
Roku h/w Version #: 4660X - Roku Ultra
Roku s/w Version #: 12.5.5 - build 4174-46
For the last several months, my Roku Plex client simply stops/crashes after ~40-50 minutes of play. It only seems to happen when playing HD content, but happens on both movies and TV shows. I typically have subtitles on. It seems also to mostly happen when playing content that has 7.1 audio enabled – PMS transcodes the audio stream to 3.1 when I start playing. The transcode takes ~10 minutes and is stored on a drive with plenty of space available (typically ~10% disk utilization). The crash happens well after (30-40 minutes) the transcode completes. I keep tail /var/log/syslog running and it reports nothing, nor does the Roku provide any error message.
I was able to capture the point in the logs when the last crash happened while watching Sherlock - S4E00 (The Abominable Bride). It crashed last Sunday at about 40 minutes into the content, and today at 47:57. I recently replaced a switch on the network thinking that was the problem, but to no avail.
Here is a snippet from the (attached) log. The crash happened Apr 26, 2024 16:21:47.
Apr 26, 2024 16:19:27.572 [547529564384] DEBUG - [Req#4d2b2/Transcode/71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4] Asked for segment 2980 from session.
Apr 26, 2024 16:19:27.591 [547529564384] DEBUG - [Req#4d2b2/Transcode/71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4] Returning segment 2980 from session
Apr 26, 2024 16:19:27.591 [547529564384] DEBUG - Content-Length of /Shared/Backup/Plex_Transcoder_Cache/Transcode/Sessions/plex-transcode-71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4-86c86685-d334-45b3-a4fe-97a3a5aa0d44/media-02980.vtt is 57 (of total: 57).
Apr 26, 2024 16:19:27.592 [547654852832] DEBUG - Completed: [192.168.1.16:40727] 200 GET /video/:/transcode/universal/session/71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4/vtt-base/02980.vtt?protocol=hls&mediaIndex=0&waitForSegments=1&videoQuality=100&session=71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4&mediaBufferSize=50000&offset=0&partIndex=0&location=lan&videoResolution=3840x2160&directPlay=0&path=%2Flibrary%2Fmetadata%2F26124&directStream=1&subtitles=auto (9 live) #4d2b2 TLS 20ms 57 bytes (pipelined: 44)
Apr 26, 2024 16:19:27.593 [547654852832] DEBUG - Removed transcode data consumer, active count 3 => 2
Apr 26, 2024 16:19:27.644 [547656962272] DEBUG - Completed: [192.168.1.16:40723] 200 GET /video/:/transcode/universal/session/71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4/base/02963.ts?protocol=hls&mediaIndex=0&waitForSegments=1&videoQuality=100&session=71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4&mediaBufferSize=50000&offset=0&partIndex=0&location=lan&videoResolution=3840x2160&directPlay=0&path=%2Flibrary%2Fmetadata%2F26124&directStream=1&subtitles=auto (9 live) #4d281 TLS 85ms 1335552 bytes (pipelined: 126)
Apr 26, 2024 16:19:27.645 [547656962272] DEBUG - Removed transcode data consumer, active count 2 => 1
Apr 26, 2024 16:19:27.655 [547654852832] DEBUG - Completed: [192.168.1.16:40726] 200 GET /video/:/transcode/universal/session/71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4/base/02962.ts?protocol=hls&mediaIndex=0&waitForSegments=1&videoQuality=100&session=71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4&mediaBufferSize=50000&offset=0&partIndex=0&location=lan&videoResolution=3840x2160&directPlay=0&path=%2Flibrary%2Fmetadata%2F26124&directStream=1&subtitles=auto (9 live) #4d288 TLS 153ms 1293816 bytes (pipelined: 108)
Apr 26, 2024 16:19:27.655 [547654852832] DEBUG - Removed transcode data consumer, active count 1 => 0
Apr 26, 2024 16:19:47.595 [547584356576] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Apr 26, 2024 16:19:47.595 [547584356576] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=3, flags=0x1003, change=0x0
Apr 26, 2024 16:19:47.595 [547584356576] DEBUG - Network change.
Apr 26, 2024 16:19:47.595 [547584356576] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 26, 2024 16:19:47.595 [547584356576] DEBUG - Network change notification but nothing changed.
Apr 26, 2024 16:20:47.596 [547584356576] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Apr 26, 2024 16:20:47.596 [547584356576] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=3, flags=0x1003, change=0x0
Apr 26, 2024 16:20:47.596 [547584356576] DEBUG - Network change.
Apr 26, 2024 16:20:47.596 [547584356576] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 26, 2024 16:20:47.597 [547584356576] DEBUG - Network change notification but nothing changed.
Apr 26, 2024 16:21:47.597 [547584356576] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Apr 26, 2024 16:21:47.597 [547584356576] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=3, flags=0x1003, change=0x0
Apr 26, 2024 16:21:47.597 [547584356576] DEBUG - Network change.
Apr 26, 2024 16:21:47.597 [547584356576] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 26, 2024 16:21:47.598 [547584356576] DEBUG - Network change notification but nothing changed.
Apr 26, 2024 16:22:28.766 [547609669856] DEBUG - Shutting down idle session 71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4 (idle time is 180 seconds)
Apr 26, 2024 16:22:28.766 [547609669856] DEBUG - Killing job.
Apr 26, 2024 16:22:28.767 [547609669856] DEBUG - Signalling job ID 1208804 with 9
Apr 26, 2024 16:22:28.767 [547609669856] DEBUG - Job was already killed, not killing again.
Apr 26, 2024 16:22:28.767 [547609669856] DEBUG - Stopping transcode session 71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4
Apr 26, 2024 16:22:28.767 [547520078048] DEBUG - Cleaning directory for session 71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4 (/Shared/Backup/Plex_Transcoder_Cache/Transcode/Sessions/plex-transcode-71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4-86c86685-d334-45b3-a4fe-97a3a5aa0d44)
Apr 26, 2024 16:22:29.021 [547609669856] DEBUG - Transcoder: Cleaning old transcode directories.
Apr 26, 2024 16:22:29.024 [547609669856] DEBUG - Transcoder: Cleaning old transcode directory: "/Shared/Backup/Plex_Transcoder_Cache/Transcode/Sessions/plex-transcode-71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4-86c86685-d334-45b3-a4fe-97a3a5aa0d44"
Apr 26, 2024 16:22:29.599 [547510747360] DEBUG - NetworkServiceBrowser: PLAYER departed after not being seen for 182.009927 seconds: 192.168.1.16
Apr 26, 2024 16:22:33.938 [547609669856] DEBUG - Whacked session 71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4, 0 remaining.
Apr 26, 2024 16:22:33.938 [547609669856] DEBUG - Killed 1 idle sessions out of a total of 1.
Apr 26, 2024 16:22:40.596 [547510747360] DEBUG - [Req#28ce4/Transcode] Streaming Resource: Timing out session 0x7f817b6308:b12fd969a2e9d0c0ef2cf64f7d84eaca
Apr 26, 2024 16:22:40.596 [547495080160] DEBUG - [Req#28ce4/Transcode] Job was already killed, not killing again.
Apr 26, 2024 16:22:40.596 [547495080160] DEBUG - [Req#28ce4/Transcode] Job was already killed, not killing again.
Apr 26, 2024 16:22:40.596 [547495080160] DEBUG - [Req#28ce4/Transcode] Transcoder: Cleaning old transcode directories.
Apr 26, 2024 16:22:40.596 [547510747360] DEBUG - [Req#28ce4/Transcode] Streaming Resource: Terminated session 0x7f817b6308:b12fd969a2e9d0c0ef2cf64f7d84eaca with reason Client stopped playback.
Apr 26, 2024 16:22:40.596 [547510747360] DEBUG - [Req#28ce4/Transcode] Streaming Resource: Removing session 0x7f817b6308:b12fd969a2e9d0c0ef2cf64f7d84eaca
Apr 26, 2024 16:22:40.596 [547510747360] DEBUG - [Req#28ce4/Transcode] Streaming Resource: Timing out session 0x7f817b6308:b12fd969a2e9d0c0ef2cf64f7d84eaca
Apr 26, 2024 16:22:40.596 [547510747360] DEBUG - [Req#28ce4/Transcode] Streaming Resource: Removing session 0x7f817b6308:token=xxxxxxxxxxxxxxxxxxxx
Apr 26, 2024 16:22:40.600 [547495080160] DEBUG - [Req#28ce4/Transcode] Whacked session 71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4, 0 remaining.
Apr 26, 2024 16:22:47.603 [547584356576] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Apr 26, 2024 16:22:47.603 [547584356576] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=3, flags=0x1003, change=0x0
Apr 26, 2024 16:22:47.603 [547584356576] DEBUG - Network change.
Apr 26, 2024 16:22:47.603 [547584356576] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 26, 2024 16:22:47.604 [547584356576] DEBUG - Network change notification but nothing changed.
Apr 26, 2024 16:22:56.658 [547495080160] DEBUG - [Now] Removing idle session for device b12fd969a2e9d0c0ef2cf64f7d84eaca_Video.
Apr 26, 2024 16:22:56.658 [547495080160] DEBUG - Statistics: (b12fd969a2e9d0c0ef2cf64f7d84eaca) Reporting active playback in state 3 of type 4 (scrobble: 0) for account 1
Apr 26, 2024 16:22:56.663 [547495080160] DEBUG - Cleaning directory for session 71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4 (/Shared/Backup/Plex_Transcoder_Cache/Transcode/Sessions/plex-transcode-71f39bf6-ee7b-4d8d-b44b-8dd75306da05-4-86c86685-d334-45b3-a4fe-97a3a5aa0d44)
Apr 26, 2024 16:23:47.602 [547584356576] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Apr 26, 2024 16:23:47.602 [547584356576] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=3, flags=0x1003, change=0x0
Apr 26, 2024 16:23:47.602 [547584356576] DEBUG - Network change.
Would love to finally find a solution to this problem.
