Playback stops at random. No error message

[MickeMilld]

Server Version#: 4.116.1
Player Version#: Apple TV

I have a problem with playback. I use Apple TV on local network and it stops at random. I get no error message, and when I restart the movie it starts from the beginning. This also happens on remote play. The server is on Ubuntu, files on Synology NAS. The last lines of the logs:

Dec 10, 2023 20:32:12.481 [140527565466424] DEBUG - Checking if time for scheduled update
Dec 10, 2023 20:32:14.011 [140527593507640] DEBUG - Request: [192.168.1.102:56525 (Subnet)] GET /:/timeline?bufferedTime=18&col=16&context=source%3Acontent.library&duration=10822187&key=%2Flibrary%2Fmetadata%2F5098&location=lan&playQueueItemID=159&playbackTime=1076929&ratingKey=5098&row=16&state=playing&time=1077076 (7 live) #12cf9e TLS GZIP Signed-in Token (MickeMilld) (Apple TV)
Dec 10, 2023 20:32:14.011 [140527593507640] DEBUG - [Req#12cf9e] Client [02EF7AC1-FD5D-4D78-8D9F-505C3959CC1D] reporting timeline state playing, progress of 1077076/10822187ms for guid=, playbackTime=1076929ms ratingKey=5098 url=, key=/library/metadata/5098, containerKey=, metadataId=5098, source=
Dec 10, 2023 20:32:14.012 [140527593507640] DEBUG - [Req#12cf9e] Play progress on 5098 ‘Oppenheimer’ - got played 1077076 ms by account 1!
Dec 10, 2023 20:32:14.012 [140527593507640] DEBUG - [Req#12cf9e] [Now] User is MickeMilld (ID: 1)
Dec 10, 2023 20:32:14.012 [140527593507640] DEBUG - [Req#12cf9e] [Now] Device is tvOS (Apple TV).
Dec 10, 2023 20:32:14.012 [140527593507640] DEBUG - [Req#12cf9e] [Now] Profile is tvOS
Dec 10, 2023 20:32:14.012 [140527593507640] DEBUG - [Req#12cf9e] [Now] Updated play state for /library/metadata/5098.
Dec 10, 2023 20:32:14.012 [140527593507640] DEBUG - [Req#12cf9e] Statistics: (02EF7AC1-FD5D-4D78-8D9F-505C3959CC1D) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Dec 10, 2023 20:32:14.013 [140528061152056] DEBUG - Completed: [192.168.1.102:56525] 200 GET /:/timeline?bufferedTime=18&col=16&context=source%3Acontent.library&duration=10822187&key=%2Flibrary%2Fmetadata%2F5098&location=lan&playQueueItemID=159&playbackTime=1076929&ratingKey=5098&row=16&state=playing&time=1077076 (7 live) #12cf9e TLS GZIP 2ms 497 bytes (pipelined: 228)
Dec 10, 2023 20:32:15.508 [140527554919224] DEBUG - Checking if time for scheduled update
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=4, flags=0x1003, change=0x0
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - Network change.
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - NetworkInterface: Notified of network changed (force=0)
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - Network change notification but nothing changed.
Dec 10, 2023 20:32:57.146 [140528061152056] DEBUG - Request: didn’t get any data from [::ffff:127.0.0.1]:58242: End of file
Dec 10, 2023 20:32:57.450 [140527593507640] DEBUG - Request: [127.0.0.1:46178 (Loopback)] GET /:/prefs (11 live) #12cfaa GZIP Signed-in Token (MickeMilld) (Firefox)
Dec 10, 2023 20:32:57.451 [140527646292792] DEBUG - Request: [127.0.0.1:46188 (Loopback)] GET /accounts/1 (12 live) #12cfab GZIP Signed-in Token (MickeMilld) (Firefox)
Dec 10, 2023 20:32:57.452 [140528059042616] DEBUG - Completed: [127.0.0.1:46188] 200 GET /accounts/1 (13 live) #12cfab GZIP 0ms 652 bytes (pipelined: 1)
Dec 10, 2023 20:32:57.452 [140527646292792] DEBUG - Request: [127.0.0.1:46204 (Loopback)] GET /myplex/account (13 live) #12cfac GZIP Signed-in Token (MickeMilld) (Firefox)
Dec 10, 2023 20:32:57.453 [140528059042616] DEBUG - Completed: [127.0.0.1:46204] 200 GET /myplex/account (13 live) #12cfac GZIP 0ms 4090 bytes (pipelined: 1)
Dec 10, 2023 20:32:57.454 [140528059042616] DEBUG - Completed: [127.0.0.1:46178] 200 GET /:/prefs (13 live) #12cfaa GZIP 4ms 9968 bytes (pipelined: 1)
Dec 10, 2023 20:33:00.810 [140528059042616] DEBUG - WebSocket: client initiated close
Dec 10, 2023 20:33:00.810 [140528061152056] DEBUG - NotificationStream: Removing because of close
Dec 10, 2023 20:33:00.810 [140528059042616] DEBUG - handleStreamRead code 2: End of file
Dec 10, 2023 20:33:00.810 [140528059042616] DEBUG - Completed after connection close: [127.0.0.1:40998] -3 GET /:/websockets/notifications (14 live) #12cb8c GZIP 528945ms 29488 bytes
Dec 10, 2023 20:33:00.812 [140527624469304] DEBUG - Request: [127.0.0.1:46212 (Loopback)] GET /diagnostics/logs (13 live) #12cfad GZIP Signed-in Token (MickeMilld) (Firefox)
Dec 10, 2023 20:33:00.812 [140527624469304] DEBUG - [Req#12cfad] Diagnostics: Building logfile zip

[ChuckPa]

@MickeMilld

Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - NetworkInterface: received Netlink message len=64, type=RTM_NEWLINK, flags=0x0
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - NetworkInterface: Netlink information message family=0, type=1, index=4, flags=0x1003, change=0x0
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - Network change.
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - NetworkInterface: Notified of network changed (force=0)
Dec 10, 2023 20:32:34.258 [140528022092600] DEBUG - Network change notification but nothing changed.
Dec 10, 2023 20:32:57.146 [140528061152056] DEBUG - Request: didn’t get any data from [::ffff:127.0.0.1]:58242: End of file
Dec 10, 2023 20:32:57.450 [140527593507640] DEBUG - Request: [127.0.0.1:46178

Whenever a machine’s DHCP address is refreshed, the OS interrupts the TCP/IP stack for a moment. Your logs show this.

Check your DHCP server’s lease interval. It’s probably set to something very short.

86400 seconds = 1 day. Start there and increase as needed.

The ideal solution is to set the server’s IP address as STATIC assigned.
It will never change after that.