Thoughts on an occasional problem please.
Running Dietpi on an Intel NUC with ethernet to Unifi switch adjacent to it, delivering to Linn player over SongCast on the same switch…
Suddenly everything stops and control interface goes to “Nothing playing”.
RAATserver log file gets this at 18:07:03:
Trace: [.NET ThreadPool Worker] [RAATServer] network reachability changed, refreshing discovery
Trace: [14] [raatmanager] updating network interfaces
Trace: [14] [discovery] stopping
Trace: [14] closing multicast
Trace: [14] [discovery] closing unicast send socket
Trace: [14] [discovery] closing unicast recv socket
Trace: [14] [discovery] starting
and the RoonServer log gets this:
Trace: [.NET ThreadPool Worker] [broker/accounts] network reachability changed. refreshing
Trace: [Broker:Misc] [broker/accounts] [heartbeat] now=01/28/2025 18:07:04 nextauthrefresh=01/28/2025 18:40:14 nextmachineallocate=01/28/2025 19:25:20
Trace: [Broker:Misc] [broker/accounts] doing auth refresh, next=01/28/2025 18:40:14
Trace: [Broker:Misc] [broker/accounts] refreshing account info for email='xxxxx@xxxxx.com' userid=xxxxxxxxxxxx
Trace: [Broker:Misc] [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/login
Trace: [Broker:Misc] [fiveaccountserver] BODY token=xxxxxxx
Debug: [Broker:Media] [tidal] network reachability changed. refreshing token
Debug: [Broker:Media] [kkbox] network reachability changed. refreshing token
Trace: [Broker:Mobile] [mobile] [remoteconnectivity] Port Verification started due to: network reachability changed, port verification not in progress, starting a new attempt
Trace: [Broker:RoonApi] [roonapi] network reachability changed. Kicking off discovery cycle
Trace: [.NET ThreadPool Worker] [push2] retrying connection in 0ms
Trace: [.NET ThreadPool Worker] [push2] exception thrown. restarting connection (The operation was canceled.)
Trace: [Broker:Transport] [raat] [sood] Refreshing device list
0
It looks to me as though there has been a temporary interruption in network connectivity, but I’m not seeing any errors elsewhere and ping tests to the NUC have not shown any problems.
There is also this, 3 seconds earlier – a regular DHCP request (returning the same IP address). Coincidence or related?
Jan 28 17:40:09 roonserver dhclient[561]: bound to 192.168.10.53 -- renewal in 1611 seconds.
Jan 28 18:07:00 roonserver dhclient[561]: DHCPREQUEST for 192.168.10.53 on eth0 to 192.168.10.1 port 67
Jan 28 18:07:00 roonserver dhclient[561]: DHCPACK of 192.168.10.53 from 192.168.10.1
Jan 28 18:07:00 roonserver Roon Server[1620]: DnsChangeNotifier::DnsChanged
Jan 28 18:07:00 roonserver dhclient[561]: bound to 192.168.10.53 -- renewal in 1205 seconds.
Where should I start looking?
Also (and possibly unrelated, but still puzzling), 8 mins earlier the roonserver log got this jumble all on one line:
01/28 18:00:20 Trace: [.NET ThreadPool Worker] [mobile] [remoteconnectivity] {"logs":[["01/28/2025 18:00:20 getting local InterNetwork address
for external connections from OS","got InterNetwork address for google.com: 172.217.16.228","local InterNetwork address: 192.168.10.53"],"01/
28/2025 18:00:20 Got local address of 192.168.10.53 for interface that connects to the external world\n",["01/28/2025 18:00:20 getting local I
nterNetworkV6 address for external connections from OS","got InterNetworkV6 address for google.com: 2a00:1450:4009:826::2004","got exception t
rying to get local InterNetworkV6 address: System.Net.Internals.SocketExceptionFactory+ExtendedSocketException (101): Network is unreachable [
2a00:1450:4009:826::2004]:11942\n at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)\n at Syst
em.Net.Sockets.Socket.Connect(EndPoint remoteEP)\n at System.Net.Sockets.UdpClient.Connect(IPEndPoint endPoint)\n at System.Threading.Exec
utionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
\n--- End of stack trace from previous location ---\n at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot, Thread th
readPoolThread)\n--- End of stack trace from previous location ---\n at Sooloos.Broker.Mobile.Module.GetLocalAddressForExternalConnection(Ad
dressFamily af)"],["01/28/2025 18:00:20 getting external ip address from Roon webserver","01/28/2025 18:00:20 Got external ip address from Roo
n controlled web service: 82.aaa.bbb.ccc"],["01/28/2025 18:00:20 check external ip address 82.aaa.bbb.ccc start","01/28/2025 18:00:20 Request
from Roon controlled server to Roon Core at https://82.aaa.bbb.ccc:40468 was successful","01/28/2025 18:00:20 Overall status of attempt to ver
ify port opening: Success\n"]],"diagnostics":{"ipv4_connectivity":{"status":"Success","status_code":200,"error":""},"external_ip":{"actual_ext
ernal_ip":"82.aaa.bbb.ccc","actual_external_ipv6":"null","router_external_ip":"null"},"natpmp_autoconfig":{"status":"NotFound"},"upnp_autoconf
ig":{"status":"NotFound"}}}