Suddenly cannot connect to Roon Core

john_gillies · July 24, 2022, 3:10pm

Roon Core Machine

Core runs on a Debian Linux Server
Debian 5.18.2-1~bpo11+1 (2022-06-14) x86_64 GNU/Linux
Intel i7 with 8Gb RAM

Networking Gear & Setup Details

Professional Network - OPNSENSE firewall, Core wired, Unfi Wifi Access Mesh Access Points

Connected Audio Devices

Number of Tracks in Library

Circa 22,000

Description of Issue

I was listening to music and stopped - one minute later I attempted to start Roon again.
Using my tablet - failed to connect to Roon Core
Using my phone - failed to connect to Roon Core

The Network works perfectly ping, ssh, back and forth. DNS resolution perfect.
Stopped and restarted the Roon Server using systemd (several times) both locally and remotely - same result.
Finally rebooted the server and checked the status of the Roon Service with systemd (running) - still unable to connect!.

I am a a total loss

Can someone take a look at the logs (full log available), please.

Many thanks,
John

This is the connection detail from the server log RoonServer_Log.txt

07/24 15:37:40 Trace: [raat] Endpoint discovered: Sooloos.Audio.Raat.DiscoveryData
07/24 15:37:40 Info: [raat] Initializing RoonReady Device
07/24 15:37:40 Info: [raat]     Address:         192.168.0.11
07/24 15:37:40 Info: [raat]     Port:            35535
07/24 15:37:40 Info: [raat]     IsDev:           False
07/24 15:37:40 Info: [raat]     UniqueId:        0a8cf805-db43-4a36-a551-90a1ce630534
07/24 15:37:40 Info: [raat]     ProtocolVersion: 3
07/24 15:37:40 Info: [raat]     Version:         4.3.0.6408
07/24 15:37:40 Info: [raat]     RaatVersion:     1.1.38
07/24 15:37:40 Info: [raat]     Model:           Mu-so
07/24 15:37:40 Info: [raat]     Vendor:          Naim Audio
07/24 15:37:40 Info: [raat]     VendorModel:     Naim Audio Mu-so
07/24 15:37:40 Info: [raat]     Serial:          1609030657
07/24 15:37:40 Info: [raat]     OutputName:      
07/24 15:37:40 Info: [raat]     AutoName:  

07/24 15:37:40 Trace: [raat] Endpoint discovered: Sooloos.Audio.Raat.DiscoveryData
07/24 15:37:40 Info: [raat] Initializing RoonReady Device
07/24 15:37:40 Info: [raat]     Address:         192.168.0.6
07/24 15:37:40 Info: [raat]     Port:            39350
07/24 15:37:40 Info: [raat]     IsDev:           False
07/24 15:37:40 Info: [raat]     UniqueId:        CambridgeAudio:000cef36
07/24 15:37:40 Info: [raat]     ProtocolVersion: 3
07/24 15:37:40 Info: [raat]     Version:         v022-a-124+a
07/24 15:37:40 Info: [raat]     RaatVersion:     1.1.38
07/24 15:37:40 Info: [raat]     Model:           851N
07/24 15:37:40 Info: [raat]     Vendor:          Cambridge Audio
07/24 15:37:40 Info: [raat]     VendorModel:     Cambridge Audio 851N
07/24 15:37:40 Info: [raat]     Serial:          000cef36
07/24 15:37:40 Info: [raat]     OutputName:      
07/24 15:37:40 Info: [raat]     AutoName:        music

07/24 15:37:42 Trace: [raatserver] [sood] Refreshing device list

07/24 15:37:44 Trace: [devicemanager/kef] connecting to 192.168.0.30:50001 to confirm version #

07/24 15:37:44 Trace: [devicemanager/kef] reported version, string: <module>p20.5102011250.105243</module><system>2.6.0</system>, number: 5102011250

07/24 15:37:44 Trace: [devicemanager/kef] FOUND Device IP=192.168.0.30 UDN=uuid:5f9ec1b3-ed59-1900-4530-84171502079d Manufacturer=KEF ModelName=SP3994 ModelNumber=94

07/24 15:37:44 Warn: [client/kef] Client created for id: uuid:5f9ec1b3-ed59-1900-4530-84171502079d

07/24 15:37:44 Trace: [device/kef] [kef] device constructor, UDN: uuid:5f9ec1b3-ed59-1900-4530-84171502079d modelname: LSX remoteIP: 192.168.0.30

07/24 15:37:44 Info: [devicedb] [autodetect] Match DeviceAutodetectData[Type=Kef Vendor=Kef Model=LSX DescriptiveName=KEF LSX] => KEF LSX

Here is the log output for the events…

07/24 15:39:50 Trace: [zone SGP611] Loading from /var/roon/RoonServer/Database/Core/8cc57ac52ba44974abca168c8c5d42a2/transport/zone_1601bef7157989ff98c1bf861b86b3b1bb4f.db

07/24 15:39:50 Trace: [zone SGP611] Suspend

07/24 15:39:50 Info: [zone SGP611] Canceling Pending Sleep

07/24 15:39:50 Trace: [SGP611] [zoneplayer/raat] Endpoint Default Output Initial State: Idle

07/24 15:39:50 Info: [transport] created zone SGP611

07/24 15:39:50 Info: [transport] with endpoint Default Output (23:1:7915f7be-ff89-c198-bf86-1b86b3b1bb4f) [IsAvailable=True]

07/24 15:39:50 Trace: [SGP611] [Inactive] [STOPPED @ 0:00]

07/24 15:39:50 Trace: [zone SGP611] Loaded Queue=0 Tracks Swim=Inactive AutoSwim=True Loop=Disabled Shuffle=False

07/24 15:39:53 Trace: [remoting/brokerserver] [initconn 192.168.0.33:52028=>192.168.0.5:9332] Connected

07/24 15:39:53 Trace: [remoting/brokerserver] [initconn 192.168.0.33:52028=>192.168.0.5:9332] Initialized Fresh Session

07/24 15:39:53 Info: [remoting/serverconnectionv2] Client connected: 192.168.0.33:52028

07/24 15:39:53 Trace: [SOOD] Adding User IP 192.168.0.33

07/24 15:39:53 Trace: [raat] [sood] Refreshing device list

07/24 15:39:53 Trace: [raatserver] [sood] Refreshing device list

07/24 15:39:54 Debug: [easyhttp] [24] POST to https://discovery.roonlabs.net/1/query returned after 438 ms, status code: 200

07/24 15:39:54 Info: [stats] 12645mb Virtual, 297mb Physical, 36mb Managed, 306 Handles, 70 Threads

07/24 15:39:56 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=5637c4c3-40b4-4985-9730-81d5ddb3200a BrokerName='files' }

07/24 15:39:56 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800988 DisplayValue='1.8 (build 988) stable' Branch='stable' } }

07/24 15:40:07 Debug: [easyhttp] [25] POST to https://device-map.roonlabs.net/1/register returned after 453 ms, status code: 200

07/24 15:40:07 Trace: [devicemap] device map updated

07/24 15:40:09 Info: [stats] 12660mb Virtual, 298mb Physical, 41mb Managed, 316 Handles, 72 Threads

07/24 15:40:24 Info: [stats] 12604mb Virtual, 293mb Physical, 45mb Managed, 316 Handles, 63 Threads

07/24 15:40:39 Info: [stats] 12596mb Virtual, 295mb Physical, 46mb Managed, 318 Handles, 60 Threads

07/24 15:40:54 Info: [stats] 12644mb Virtual, 295mb Physical, 50mb Managed, 328 Handles, 65 Threads

07/24 15:41:09 Info: [stats] 12612mb Virtual, 295mb Physical, 51mb Managed, 328 Handles, 62 Threads

07/24 15:41:24 Info: [stats] 12612mb Virtual, 295mb Physical, 55mb Managed, 328 Handles, 63 Threads

07/24 15:41:39 Info: [stats] 12604mb Virtual, 295mb Physical, 55mb Managed, 330 Handles, 61 Threads

07/24 15:41:54 Info: [stats] 12628mb Virtual, 301mb Physical, 60mb Managed, 340 Handles, 66 Threads

07/24 15:42:09 Info: [stats] 12596mb Virtual, 298mb Physical, 60mb Managed, 340 Handles, 59 Threads

07/24 15:42:24 Info: [stats] 12604mb Virtual, 298mb Physical, 63mb Managed, 340 Handles, 61 Threads

07/24 15:42:39 Trace: [broker/accounts] [heartbeat] now=24/07/2022 14:42:39 nextauthrefresh=24/07/2022 15:37:40 nextmachineallocate=24/07/2022 18:37:39

07/24 15:42:39 Info: [stats] 12596mb Virtual, 298mb Physical, 64mb Managed, 342 Handles, 60 Threads

07/24 15:42:54 Info: [stats] 12612mb Virtual, 298mb Physical, 68mb Managed, 352 Handles, 62 Threads

07/24 15:43:09 Info: [stats] 12596mb Virtual, 298mb Physical, 68mb Managed, 352 Handles, 60 Threads

07/24 15:43:24 Info: [stats] 12604mb Virtual, 312mb Physical, 72mb Managed, 352 Handles, 63 Threads

07/24 15:43:39 Info: [stats] 12604mb Virtual, 310mb Physical, 73mb Managed, 354 Handles, 63 Threads

07/24 15:43:52 Info: [remoting/serverconnectionv2] Client disconnected: 192.168.0.33:52028

07/24 15:43:52 Trace: [raatserver] [Default Output] lost client connection. Retrying

07/24 15:43:52 Info: [raatserver] [Default Output] connecting (attempt 1)

07/24 15:43:52 Info: [transport] destroyed zone SGP611 was playing? False

07/24 15:43:52 Trace: [zone SGP611] Suspend

07/24 15:43:52 Info: [zone SGP611] Canceling Pending Sleep

07/24 15:43:52 Info: [zone SGP611] Canceling Pending Sleep

07/24 15:43:52 Warn: [raat_ll/client] [Default Output] failed to connect Connection refused

07/24 15:43:52 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] lost client connection. Retrying

07/24 15:43:52 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] connecting (attempt 1)

07/24 15:43:52 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:43:52 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] client connection failed. Retrying in 500ms

07/24 15:43:52 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] connecting (attempt 2)

07/24 15:43:52 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:43:52 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] client connection failed. Retrying in 750ms

07/24 15:43:53 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] connecting (attempt 3)

07/24 15:43:53 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:43:53 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] client connection failed. Retrying in 1125ms

07/24 15:43:54 Info: [stats] 12628mb Virtual, 311mb Physical, 78mb Managed, 356 Handles, 67 Threads

07/24 15:43:55 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] connecting (attempt 4)

07/24 15:43:55 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:43:55 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] client connection failed. Retrying in 1687ms

07/24 15:43:56 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] connecting (attempt 5)

07/24 15:43:56 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:43:56 Trace: [raatserver] [RaatServer SGP611 @ 192.168.0.33:9200] client connection failed. Giving up

07/24 15:43:56 Trace: [raat] [sood] Refreshing device list

07/24 15:44:07 Debug: [easyhttp] [26] POST to https://device-map.roonlabs.net/1/register returned after 511 ms, status code: 200

07/24 15:44:07 Trace: [devicemap] device map updated

07/24 15:44:09 Info: [stats] 12628mb Virtual, 320mb Physical, 84mb Managed, 357 Handles, 65 Threads

07/24 15:44:24 Info: [stats] 12628mb Virtual, 318mb Physical, 87mb Managed, 357 Handles, 63 Threads

07/24 15:44:38 Trace: [raat] RAATServer discovered: RaatServer Redmi Note 4 @ 192.168.0.51:9200

07/24 15:44:38 Info: [raatserver] GOT SERVER 2f09beab-2f5a-afe7-8757-6150113d413a::2f09beab2f5aafe787576150113d413a @ 192.168.0.51:9200 Redmi Note 4 PROTOVER=1 RAATVER=1.1.38

07/24 15:44:38 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 1)

07/24 15:44:38 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connected

07/24 15:44:38 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}

07/24 15:44:38 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "default", "type": "android", "auto_enable": true, "name": "Default Output", "is_system_output": true, "config": {"unique_id": "63e417c4-8af9-6c8b-3b8d-db4df5c39cb9", "output": {"type": "android", "name": "Default Output", "device": "default", "force_max_volume": false}, "external_config": {"is_private": true}, "volume": {"type": "android", "device": "default", "exclusive_mode": null}}, "auto_name": "Redmi Note 4"}]}

07/24 15:44:38 Info: [raatserver] GOT DEVICE 2f09beab2f5aafe787576150113d413a::default Type=android Name=Default Output

07/24 15:44:39 Info: [stats] 12596mb Virtual, 318mb Physical, 88mb Managed, 360 Handles, 62 Threads

07/24 15:44:40 Trace: [remoting/brokerserver] [initconn 192.168.0.51:47036=>192.168.0.5:9332] Connected

07/24 15:44:40 Warn: [remoting/brokerserver] [initconn 192.168.0.51:47036=>192.168.0.5:9332] failed: System.Exception: got unexpected broker id in REQ_HANDSHAKE: 25960a0c-6b44-4789-9b82-cd4ec7da2eae

at Sooloos.Broker.Distributed.InitConnectionV2.Go()

07/24 15:44:40 Trace: [remoting/brokerserver] [initconn 192.168.0.51:47060=>192.168.0.5:9332] Connected

07/24 15:44:40 Warn: [remoting/brokerserver] [initconn 192.168.0.51:47060=>192.168.0.5:9332] failed: System.Exception: got unexpected broker id in REQ_HANDSHAKE: 25960a0c-6b44-4789-9b82-cd4ec7da2eae

at Sooloos.Broker.Distributed.InitConnectionV2.Go()

07/24 15:44:42 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] lost client connection. Retrying

07/24 15:44:42 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 1)

07/24 15:44:42 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:44:42 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 500ms

07/24 15:44:43 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 2)

07/24 15:44:43 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:44:43 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 750ms

07/24 15:44:43 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 3)

07/24 15:44:43 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:44:43 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 1125ms

07/24 15:44:45 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 4)

07/24 15:44:45 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:44:45 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 1687ms

07/24 15:44:46 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 5)

07/24 15:44:46 Warn: [rnet/RnetJsonClient] failed to connect Connection refused

07/24 15:44:46 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Giving up

07/24 15:44:46 Trace: [raat] [sood] Refreshing device list

07/24 15:44:54 Info: [stats] 12644mb Virtual, 321mb Physical, 95mb Managed, 371 Handles, 68 Threads

07/24 15:44:57 Debug: [easyhttp] [27] POST to https://device-map.roonlabs.net/1/register returned after 447 ms, status code: 200

07/24 15:44:57 Trace: [devicemap] device map updated

07/24 15:45:09 Info: [stats] 12620mb Virtual, 324mb Physical, 97mb Managed, 371 Handles, 63 Threads

07/24 15:45:24 Info: [stats] 12620mb Virtual, 327mb Physical, 100mb Managed, 371 Handles, 62 Threads

07/24 15:45:39 Info: [stats] 12596mb Virtual, 327mb Physical, 101mb Managed, 373 Handles, 62 Threads

07/24 15:45:45 Trace: [raat] RAATServer discovered: RaatServer Redmi Note 4 @ 192.168.0.51:9200

07/24 15:45:45 Info: [raatserver] GOT SERVER 2f09beab-2f5a-afe7-8757-6150113d413a::2f09beab2f5aafe787576150113d413a @ 192.168.0.51:9200 Redmi Note 4 PROTOVER=1 RAATVER=1.1.38

07/24 15:45:45 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 1)

07/24 15:45:46 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connected

07/24 15:45:46 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}

07/24 15:45:46 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "default", "type": "android", "name": "Default Output", "is_system_output": true, "config": {"unique_id": "63e417c4-8af9-6c8b-3b8d-db4df5c39cb9", "output": {"type": "android", "name": "Default Output", "force_max_volume": false, "device": "default"}, "external_config": {"is_private": true}, "volume": {"type": "android", "device": "default", "exclusive_mode": null}}, "auto_enable": true, "auto_name": "Redmi Note 4"}]}

07/24 15:45:46 Info: [raatserver] GOT DEVICE 2f09beab2f5aafe787576150113d413a::default Type=android Name=Default Output

07/24 15:45:47 Trace: [remoting/brokerserver] [initconn 192.168.0.51:47130=>192.168.0.5:9332] Connected

07/24 15:45:48 Trace: [remoting/brokerserver] [initconn 192.168.0.51:47130=>192.168.0.5:9332] Initialized Fresh Session

07/24 15:45:48 Info: [remoting/serverconnectionv2] Client connected: 192.168.0.51:47130

07/24 15:45:48 Trace: [SOOD] Adding User IP 192.168.0.51

07/24 15:45:48 Trace: [raat] [sood] Refreshing device list

07/24 15:45:48 Trace: [raatserver] [sood] Refreshing device list

07/24 15:45:48 Debug: [easyhttp] [28] POST to https://discovery.roonlabs.net/1/query returned after 450 ms, status code: 200

07/24 15:45:49 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=5637c4c3-40b4-4985-9730-81d5ddb3200a BrokerName='files' }

07/24 15:45:49 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800988 DisplayValue='1.8 (build 988) stable' Branch='stable' } }

07/24 15:45:54 Info: [stats] 12636mb Virtual, 339mb Physical, 69mb Managed, 310 Handles, 69 Threads

07/24 15:45:59 Debug: [easyhttp] [29] POST to https://device-map.roonlabs.net/1/register returned after 447 ms, status code: 200

07/24 15:45:59 Trace: [devicemap] device map updated

07/24 15:46:09 Info: [stats] 12636mb Virtual, 340mb Physical, 70mb Managed, 310 Handles, 67 Threads

07/24 15:46:24 Info: [stats] 12628mb Virtual, 338mb Physical, 73mb Managed, 310 Handles, 64 Threads

07/24 15:46:39 Info: [stats] 12628mb Virtual, 338mb Physical, 74mb Managed, 312 Handles, 65 Threads

07/24 15:46:54 Info: [stats] 12628mb Virtual, 338mb Physical, 78mb Managed, 322 Handles, 66 Threads

07/24 15:47:09 Info: [stats] 12644mb Virtual, 338mb Physical, 80mb Managed, 322 Handles, 70 Threads

07/24 15:47:24 Info: [stats] 12628mb Virtual, 339mb Physical, 83mb Managed, 322 Handles, 66 Threads

07/24 15:47:39 Trace: [broker/accounts] [heartbeat] now=24/07/2022 14:47:39 nextauthrefresh=24/07/2022 15:37:40 nextmachineallocate=24/07/2022 18:37:39

07/24 15:47:39 Info: [stats] 12628mb Virtual, 339mb Physical, 84mb Managed, 324 Handles, 64 Threads

07/24 15:47:54 Info: [stats] 12628mb Virtual, 339mb Physical, 88mb Managed, 334 Handles, 64 Threads

07/24 15:48:09 Info: [stats] 12588mb Virtual, 339mb Physical, 89mb Managed, 334 Handles, 59 Threads

07/24 15:48:24 Info: [stats] 12588mb Virtual, 339mb Physical, 92mb Managed, 334 Handles, 60 Threads

07/24 15:48:39 Info: [stats] 12588mb Virtual, 339mb Physical, 92mb Managed, 336 Handles, 61 Threads

07/24 15:48:54 Info: [stats] 12620mb Virtual, 341mb Physical, 96mb Managed, 346 Handles, 64 Threads

07/24 15:49:09 Info: [stats] 12596mb Virtual, 339mb Physical, 96mb Managed, 346 Handles, 60 Threads

07/24 15:49:24 Info: [stats] 12596mb Virtual, 339mb Physical, 99mb Managed, 346 Handles, 60 Threads

07/24 15:49:39 Info: [stats] 12588mb Virtual, 339mb Physical, 99mb Managed, 348 Handles, 62 Threads

07/24 15:49:54 Info: [stats] 12612mb Virtual, 339mb Physical, 104mb Managed, 358 Handles, 63 Threads

07/24 15:50:09 Info: [stats] 12588mb Virtual, 337mb Physical, 104mb Managed, 358 Handles, 62 Threads

07/24 15:50:24 Info: [stats] 12588mb Virtual, 337mb Physical, 108mb Managed, 358 Handles, 60 Threads

07/24 15:50:39 Info: [stats] 12588mb Virtual, 337mb Physical, 109mb Managed, 360 Handles, 63 Threads

07/24 15:50:54 Info: [stats] 12620mb Virtual, 337mb Physical, 113mb Managed, 370 Handles, 66 Threads

07/24 15:51:09 Info: [stats] 12596mb Virtual, 337mb Physical, 113mb Managed, 370 Handles, 60 Threads

07/24 15:51:17 Info: [remoting/serverconnectionv2] Client disconnected: 192.168.0.51:47130

07/24 15:51:17 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] lost client connection. Retrying

07/24 15:51:17 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 1)

07/24 15:51:24 Info: [stats] 12612mb Virtual, 339mb Physical, 117mb Managed, 369 Handles, 62 Threads

07/24 15:51:27 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 500ms

07/24 15:51:28 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 2)

07/24 15:51:38 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 750ms

07/24 15:51:39 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 3)

07/24 15:51:39 Info: [stats] 12604mb Virtual, 339mb Physical, 118mb Managed, 371 Handles, 65 Threads

07/24 15:51:49 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 1125ms

07/24 15:51:50 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 4)

07/24 15:51:54 Info: [stats] 12628mb Virtual, 343mb Physical, 122mb Managed, 381 Handles, 66 Threads

07/24 15:52:00 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 1687ms

07/24 15:52:01 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 5)

07/24 15:52:09 Info: [stats] 12596mb Virtual, 343mb Physical, 122mb Managed, 381 Handles, 60 Threads

07/24 15:52:11 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Giving up

07/24 15:52:11 Trace: [raat] [sood] Refreshing device list

07/24 15:52:22 Debug: [easyhttp] [30] POST to https://device-map.roonlabs.net/1/register returned after 513 ms, status code: 200

07/24 15:52:22 Trace: [devicemap] device map updated

07/24 15:52:24 Info: [stats] 12636mb Virtual, 348mb Physical, 131mb Managed, 381 Handles, 67 Threads

07/24 15:52:39 Trace: [broker/accounts] [heartbeat] now=24/07/2022 14:52:39 nextauthrefresh=24/07/2022 15:37:40 nextmachineallocate=24/07/2022 18:37:39

07/24 15:52:39 Info: [stats] 12604mb Virtual, 349mb Physical, 132mb Managed, 383 Handles, 65 Threads

07/24 15:52:54 Info: [stats] 12628mb Virtual, 353mb Physical, 135mb Managed, 393 Handles, 66 Threads

07/24 15:52:58 Trace: [raat] RAATServer discovered: RaatServer Redmi Note 4 @ 192.168.0.51:9200

07/24 15:52:58 Info: [raatserver] GOT SERVER 2f09beab-2f5a-afe7-8757-6150113d413a::2f09beab2f5aafe787576150113d413a @ 192.168.0.51:9200 Redmi Note 4 PROTOVER=1 RAATVER=1.1.38

07/24 15:52:58 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 1)

07/24 15:52:58 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connected

07/24 15:52:58 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}

07/24 15:52:58 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "default", "type": "android", "name": "Default Output", "is_system_output": true, "config": {"unique_id": "63e417c4-8af9-6c8b-3b8d-db4df5c39cb9", "output": {"type": "android", "name": "Default Output", "force_max_volume": false, "device": "default"}, "external_config": {"is_private": true}, "volume": {"type": "android", "device": "default", "exclusive_mode": null}}, "auto_enable": true, "auto_name": "Redmi Note 4"}]}

07/24 15:52:58 Info: [raatserver] GOT DEVICE 2f09beab2f5aafe787576150113d413a::default Type=android Name=Default Output

07/24 15:53:00 Trace: [remoting/brokerserver] [initconn 192.168.0.51:47311=>192.168.0.5:9332] Connected

07/24 15:53:00 Trace: [remoting/brokerserver] [initconn 192.168.0.51:47311=>192.168.0.5:9332] Resumed Session

07/24 15:53:00 Trace: [remoting/remotingprotocolv2] resume send 564 messages, 19KiB

07/24 15:53:00 Trace: [raat] [sood] Refreshing device list

07/24 15:53:00 Trace: [raatserver] [sood] Refreshing device list

07/24 15:53:00 Debug: [easyhttp] [31] POST to https://discovery.roonlabs.net/1/query returned after 443 ms, status code: 200

07/24 15:53:09 Debug: [easyhttp] [32] POST to https://device-map.roonlabs.net/1/register returned after 447 ms, status code: 200

07/24 15:53:09 Trace: [devicemap] device map updated

07/24 15:53:09 Info: [stats] 12652mb Virtual, 366mb Physical, 148mb Managed, 409 Handles, 72 Threads

07/24 15:53:24 Info: [stats] 12604mb Virtual, 368mb Physical, 151mb Managed, 409 Handles, 61 Threads

07/24 15:53:39 Info: [stats] 12604mb Virtual, 369mb Physical, 152mb Managed, 411 Handles, 62 Threads

07/24 15:53:54 Info: [stats] 12612mb Virtual, 373mb Physical, 156mb Managed, 421 Handles, 63 Threads

07/24 15:54:09 Info: [stats] 12588mb Virtual, 372mb Physical, 156mb Managed, 421 Handles, 59 Threads

07/24 15:54:24 Info: [stats] 12588mb Virtual, 375mb Physical, 159mb Managed, 421 Handles, 60 Threads

07/24 15:54:39 Info: [stats] 12588mb Virtual, 375mb Physical, 160mb Managed, 423 Handles, 60 Threads

07/24 15:54:54 Info: [stats] 12623mb Virtual, 378mb Physical, 67mb Managed, 305 Handles, 64 Threads

07/24 15:55:09 Info: [stats] 12615mb Virtual, 376mb Physical, 68mb Managed, 305 Handles, 64 Threads

07/24 15:55:24 Info: [stats] 12599mb Virtual, 375mb Physical, 70mb Managed, 305 Handles, 60 Threads

07/24 15:55:39 Info: [stats] 12599mb Virtual, 377mb Physical, 71mb Managed, 307 Handles, 61 Threads

07/24 15:55:54 Info: [stats] 12615mb Virtual, 379mb Physical, 74mb Managed, 317 Handles, 63 Threads

07/24 15:56:09 Info: [stats] 12607mb Virtual, 379mb Physical, 75mb Managed, 317 Handles, 61 Threads

07/24 15:56:24 Info: [stats] 12607mb Virtual, 379mb Physical, 78mb Managed, 317 Handles, 62 Threads

07/24 15:56:39 Info: [stats] 12591mb Virtual, 377mb Physical, 79mb Managed, 319 Handles, 60 Threads

07/24 15:56:54 Info: [stats] 12607mb Virtual, 377mb Physical, 83mb Managed, 329 Handles, 63 Threads

07/24 15:56:58 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] lost client connection. Retrying

07/24 15:56:58 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 1)

07/24 15:56:58 Info: [remoting/serverconnectionv2] Client disconnected: 192.168.0.51:47130

07/24 15:57:08 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 500ms

07/24 15:57:09 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 2)

07/24 15:57:09 Info: [stats] 12599mb Virtual, 378mb Physical, 83mb Managed, 328 Handles, 60 Threads

07/24 15:57:19 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 750ms

07/24 15:57:19 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 3)

07/24 15:57:24 Info: [stats] 12607mb Virtual, 378mb Physical, 86mb Managed, 328 Handles, 62 Threads

07/24 15:57:29 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 1125ms

07/24 15:57:30 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 4)

07/24 15:57:39 Trace: [broker/accounts] [heartbeat] now=24/07/2022 14:57:39 nextauthrefresh=24/07/2022 15:37:40 nextmachineallocate=24/07/2022 18:37:39

07/24 15:57:39 Info: [stats] 12599mb Virtual, 378mb Physical, 87mb Managed, 330 Handles, 61 Threads

07/24 15:57:40 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Retrying in 1687ms

07/24 15:57:42 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] connecting (attempt 5)

07/24 15:57:52 Trace: [raatserver] [RaatServer Redmi Note 4 @ 192.168.0.51:9200] client connection failed. Giving up

07/24 15:57:52 Trace: [raat] [sood] Refreshing device list

07/24 15:57:54 Info: [stats] 12631mb Virtual, 380mb Physical, 93mb Managed, 340 Handles, 68 Threads

07/24 15:58:03 Debug: [easyhttp] [33] POST to https://device-map.roonlabs.net/1/register returned after 508 ms, status code: 200

07/24 15:58:03 Trace: [devicemap] device map updated

07/24 15:58:09 Info: [stats] 12631mb Virtual, 380mb Physical, 96mb Managed, 340 Handles, 67 Threads

07/24 15:58:24 Info: [stats] 12607mb Virtual, 378mb Physical, 99mb Managed, 340 Handles, 63 Threads

07/24 15:58:39 Info: [stats] 12599mb Virtual, 378mb Physical, 100mb Managed, 342 Handles, 63 Threads

Martin_Webster · July 24, 2022, 5:17pm

Try rebooting your router.

john_gillies · July 24, 2022, 5:38pm

Thank you for your suggestion.
Sadly makes no difference.

benjamin · July 26, 2022, 11:54pm

Hey @john_gillies,

Ben here with the support team, thanks for writing in! I’m sorry to hear you’re having issues connecting to your core.

After taking a deeper look into your account, I see you were able to reconnect to your core within the last 5 minutes, and so I wanted to check in to see if you’re still running into issues? If so, please simplify your setup as much as possible, with your core being hardwired directly to your router, and let me know if your issues persist.

We’ll be monitoring this thread for your reply

john_gillies · July 27, 2022, 5:36pm

Hello Ben,
Thanks for the attention.

Here is my current status - Roon is WORKING again - thank goodness.

After the inability to connect to Core, I opted to connect to another Core - in effect setting up all my devices and files again.
In the hope that I would not need to re-scan my Library, I attempted to restore my backup - but Roon returned Your Database is corrupt!

I logged into the server with SSH and cleaned up the files as advised in Roon Help (rename db folder).

I reconnected to Roon from my Android Tablet and then went though the time consuming process of setting up the Library - several hours for 22,000 tracks! Backup created.

During this process Roon proceeded unprompted to UPGRADE to build 1021 - this seemed successful as did the Library scan and subsequent new Backup.
However next attempt to connect to the Core failed.
Only fixed by new Core (worked) + restore Backup (failed) then new Core(worked) + re-scan library (worked) + new Backup (completed but not tested).

Sys logs indicate no server issues throughout.
Similarly the OPNSense firewall router logs show no issues.

I do not think I have ascertained the original cause of the inability to connect to the Core.
The process to get Roon working again has been muddied by the upgrade that occurred during the work.
Now that I am more knowledgeable about the Roon installation, I shall monitor with a view to capturing any future events.

So back to listening to music at last.

Any further thoughts from you Ben, or anyone in the |Community is most welcome,
John

system · July 29, 2022, 5:36am

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