Mar 28 09:03:01 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Mar 28 09:03:02 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.204 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Mar 28 09:03:05 rivoessential-dev0 volumio[28272]: error: MyVolumio Plugin failed to start in a timely fashion Mar 28 09:03:05 rivoessential-dev0 volumio[28272]: [Metrics] CommandRouter: 100s 242.75ms Mar 28 09:03:05 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::volumiosetStartupVolume Mar 28 09:03:05 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:05 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 09:03:05 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::Close All Modals sent Mar 28 09:03:05 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::Close All Modals sent Mar 28 09:03:06 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 28 09:03:06 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 09:03:06 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:09 rivoessential-dev0 volumio[28272]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 09:03:11 rivoessential-dev0 volumio[28272]: warn: QOBUZ login failed: No access token received Mar 28 09:03:11 rivoessential-dev0 volumio[28272]: info: Stopping AccessToken refresher cron for QOBUZ Mar 28 09:03:11 rivoessential-dev0 volumio[28272]: info: AccessToken refresher cron started for QOBUZ Mar 28 09:03:11 rivoessential-dev0 volumio[28272]: info: Adding QOBUZ REST API Endpoints Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: error: No access token received for TIDAL Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: BOOT COMPLETED Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 09:03:12 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 28 09:03:12 rivoessential-dev0 sudo[28860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 28 09:03:12 rivoessential-dev0 sudo[28860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 09:03:12 rivoessential-dev0 sudo[28860]: pam_unix(sudo:session): session closed for user root Mar 28 09:03:12 rivoessential-dev0 sudo[28862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 28 09:03:12 rivoessential-dev0 sudo[28862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 09:03:12 rivoessential-dev0 systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Mar 28 09:03:12 rivoessential-dev0 systemd[1]: raat-daemon.service: Deactivated successfully. Mar 28 09:03:12 rivoessential-dev0 systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Mar 28 09:03:12 rivoessential-dev0 systemd[1]: raat-daemon.service: Consumed 7.922s CPU time. Mar 28 09:03:13 rivoessential-dev0 systemd[1]: Started raat-daemon.service - RAAT DAEMON. Mar 28 09:03:13 rivoessential-dev0 sudo[28862]: pam_unix(sudo:session): session closed for user root Mar 28 09:03:13 rivoessential-dev0 volumio[28272]: info: Raat Daemon started successfully Mar 28 09:03:13 rivoessential-dev0 volumio[28272]: info: Executing endpoint restartRAATSocket Mar 28 09:03:13 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 28 09:03:13 rivoessential-dev0 volumio[28272]: info: CorePlayQueue::getTrack 0 Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: RAAT: Requesting Headphone Status Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: MCU Signalled Headphone Mode Disabled Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: Reporting Headphone State: false Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 09:03:15 rivoessential-dev0 volumio[28272]: info: Updating RAAT Signal Path Mar 28 09:03:17 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.200 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Mar 28 09:03:18 rivoessential-dev0 go-librespot[28791]: time="2026-03-28T09:03:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: i/o timeout (Client.Timeout exceeded while awaiting headers)" Mar 28 09:03:18 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 09:03:18 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Restarting Browsing Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: info: Discovery: Restart already pending, ignoring duplicate call Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.204 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Mar 28 09:03:19 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.106 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Mar 28 09:03:22 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 28 09:03:22 rivoessential-dev0 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:03:22 rivoessential-dev0 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:03:22 rivoessential-dev0 go-librespot[28896]: go-librespot daemon starting... Mar 28 09:03:22 rivoessential-dev0 go-librespot[28897]: time="2026-03-28T09:03:22+01:00" level=info msg="running go-librespot 0.7.1" Mar 28 09:03:22 rivoessential-dev0 go-librespot[28897]: time="2026-03-28T09:03:22+01:00" level=debug msg="app state loaded" Mar 28 09:03:22 rivoessential-dev0 go-librespot[28897]: time="2026-03-28T09:03:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 09:03:25 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.204 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Mar 28 09:03:25 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.204 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Mar 28 09:03:25 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Mar 28 09:03:25 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Mar 28 09:03:25 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Mar 28 09:03:26 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.106 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 Mar 28 09:03:32 rivoessential-dev0 volumio[28272]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vmod-a0&variante=rivoessential&version=4.125&uuid=c2c1b354b5a85979ae8b8cd8f9ccd050" http://updates.volumio.org/downloader-v1/track-device Mar 28 09:03:32 rivoessential-dev0 volumio[28272]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 28 09:03:32 rivoessential-dev0 volumio[28272]: Dload Upload Total Spent Left Speed Mar 28 09:03:32 rivoessential-dev0 volumio[28272]: [2.2K blob data] Mar 28 09:03:32 rivoessential-dev0 volumio[28272]: retrying in 5 seconds, trial 1 Mar 28 09:03:32 rivoessential-dev0 volumio[28272]: info: Volumio Calling Home Mar 28 09:03:35 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Mar 28 09:03:35 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.200 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Mar 28 09:03:49 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.204 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Mar 28 09:03:50 rivoessential-dev0 go-librespot[28897]: time="2026-03-28T09:03:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 28 09:03:50 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 09:03:50 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 09:03:53 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 28 09:03:53 rivoessential-dev0 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:03:53 rivoessential-dev0 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:03:53 rivoessential-dev0 go-librespot[28961]: go-librespot daemon starting... Mar 28 09:03:53 rivoessential-dev0 go-librespot[28962]: time="2026-03-28T09:03:53+01:00" level=info msg="running go-librespot 0.7.1" Mar 28 09:03:53 rivoessential-dev0 go-librespot[28962]: time="2026-03-28T09:03:53+01:00" level=debug msg="app state loaded" Mar 28 09:03:53 rivoessential-dev0 go-librespot[28962]: time="2026-03-28T09:03:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 09:03:56 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.200 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Mar 28 09:03:56 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.106 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Mar 28 09:03:58 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Mar 28 09:03:59 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Mar 28 09:04:03 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Mar 28 09:04:10 rivoessential-dev0 volumio[28272]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vmod-a0&variante=rivoessential&version=4.125&uuid=c2c1b354b5a85979ae8b8cd8f9ccd050" http://updates.volumio.org/downloader-v1/track-device Mar 28 09:04:10 rivoessential-dev0 volumio[28272]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 28 09:04:10 rivoessential-dev0 volumio[28272]: Dload Upload Total Spent Left Speed Mar 28 09:04:10 rivoessential-dev0 volumio[28272]: [2.2K blob data] Mar 28 09:04:10 rivoessential-dev0 volumio[28272]: retrying in 5 seconds, trial 2 Mar 28 09:04:10 rivoessential-dev0 volumio[28272]: info: Volumio Calling Home Mar 28 09:04:17 rivoessential-dev0 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: Discovery: adding d8822d33-eba4-4dae-afa0-2896872a8186 Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: Discovery: Found device RivoEssential-dev0 Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::volumioGetState Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: CorePlayQueue::getTrack 0 Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: MRS: Pushing multiroomSync output for this device Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: MRS: Pushing multiroomSync output Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: Adding audio output: Mar 28 09:04:21 rivoessential-dev0 volumio[28272]: info: Adding audio output: Mar 28 09:04:22 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Mar 28 09:04:23 rivoessential-dev0 go-librespot[28962]: time="2026-03-28T09:04:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Mar 28 09:04:23 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 09:04:23 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 09:04:25 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.106 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Mar 28 09:04:26 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.106 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Mar 28 09:04:26 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 28 09:04:26 rivoessential-dev0 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:04:26 rivoessential-dev0 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:04:26 rivoessential-dev0 go-librespot[29047]: go-librespot daemon starting... Mar 28 09:04:26 rivoessential-dev0 go-librespot[29048]: time="2026-03-28T09:04:26+01:00" level=info msg="running go-librespot 0.7.1" Mar 28 09:04:26 rivoessential-dev0 go-librespot[29048]: time="2026-03-28T09:04:26+01:00" level=debug msg="app state loaded" Mar 28 09:04:26 rivoessential-dev0 go-librespot[29048]: time="2026-03-28T09:04:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 09:04:30 rivoessential-dev0 volumio[28272]: info: Discovery: this is already registered, d8822d33-eba4-4dae-afa0-2896872a8186 Mar 28 09:04:30 rivoessential-dev0 volumio[28272]: info: Discovery: Found device RivoEssential-dev0 Mar 28 09:04:30 rivoessential-dev0 volumio[28272]: info: CoreCommandRouter::volumioGetState Mar 28 09:04:30 rivoessential-dev0 volumio[28272]: info: CorePlayQueue::getTrack 0 Mar 28 09:04:30 rivoessential-dev0 go-librespot[29048]: time="2026-03-28T09:04:30+01:00" level=debug msg="new websocket client" Mar 28 09:04:30 rivoessential-dev0 volumio[28272]: info: Connection to go-librespot Websocket established Mar 28 09:04:33 rivoessential-dev0 volumio[28272]: info: Getting Spotify volume Mar 28 09:04:33 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Mar 28 09:04:34 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.106 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Mar 28 09:04:37 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.106 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Mar 28 09:04:41 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Mar 28 09:04:41 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.200 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.204 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: verbose: New Socket.io Connection to 192.168.1.207:3000 from 192.168.1.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Mar 28 09:04:54 rivoessential-dev0 go-librespot[29048]: time="2026-03-28T09:04:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 28 09:04:54 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 09:04:54 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: Error: socket hang up Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: at connResetException (node:internal/errors:720:14) Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: at Socket.emit (node:events:526:35) Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: code: 'ECONNRESET', Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: response: undefined Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: } Mar 28 09:04:54 rivoessential-dev0 volumio[28272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 09:04:57 rivoessential-dev0 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 28 09:04:57 rivoessential-dev0 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:04:57 rivoessential-dev0 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 09:04:57 rivoessential-dev0 go-librespot[29112]: go-librespot daemon starting... Mar 28 09:04:57 rivoessential-dev0 go-librespot[29113]: time="2026-03-28T09:04:57+01:00" level=info msg="running go-librespot 0.7.1" Mar 28 09:04:57 rivoessential-dev0 go-librespot[29113]: time="2026-03-28T09:04:57+01:00" level=debug msg="app state loaded" Mar 28 09:04:58 rivoessential-dev0 go-librespot[29113]: time="2026-03-28T09:04:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 09:04:59 rivoessential-dev0 sudo[29123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 09:03' Mar 28 09:04:59 rivoessential-dev0 sudo[29123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b61be8fb6c94ef2315c73ceee681c670289f33a3" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoessential" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Mar 26 18:07:23 UTC 2026" VOLUMIO_VERSION="4.125" VOLUMIO_HARDWARE="vmod-a0" VOLUMIO_DEVICENAME="VMOD-A0" VOLUMIO_VENDOR_MODEL="Volumio Rivo Essential" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Essential" VOLUMIO_HASH="a3151897d5adcfdafda7e893bf517453"