Feb 12 20:37:00 volumio volumio[1337]: info: Discovery: Started advertising with name: Volumio Feb 12 20:37:05 volumio ntpd[1181]: PROTO: 165.227.160.4 unlink local addr 192.168.1.165 -> Feb 12 20:37:07 volumio dhcpcd[1029]: eth0: carrier acquired Feb 12 20:37:07 volumio dhcpcd[1029]: eth0: config file changed, expiring leases Feb 12 20:37:07 volumio kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control tx Feb 12 20:37:07 volumio dhcpcd[1029]: eth0: IAID 67:22:61:1d Feb 12 20:37:07 volumio dhcpcd[1029]: eth0: probing address 192.168.1.165/24 Feb 12 20:37:07 volumio ifplugd(eth0)[1180]: Link beat detected. Feb 12 20:37:07 volumio ifplugd(eth0)[1180]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 12 20:37:08 volumio ifplugd(eth0)[1180]: Program executed successfully. Feb 12 20:37:08 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:08 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:08 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:08 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:08 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:08 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:08 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:08 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:08 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:08 volumio dhcpcd[1029]: eth0: soliciting an IPv6 router Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: New ethernet state: connected Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: First start: no Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: === END TRANSITION === Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Feb 12 20:37:08 volumio sudo[9436]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Feb 12 20:37:08 volumio sudo[9436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 20:37:08 volumio dhcpcd[9437]: dhcpcd not running Feb 12 20:37:08 volumio sudo[9436]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:08 volumio wireless.js[8692]: dhcpcd not running Feb 12 20:37:08 volumio wireless.js[8692]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 12 20:37:09 volumio wpa_supplicant[9149]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 12 20:37:09 volumio wpa_supplicant[9149]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 12 20:37:09 volumio wpa_supplicant[9149]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Feb 12 20:37:09 volumio sudo[9445]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 12 20:37:09 volumio sudo[9445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 20:37:09 volumio avahi-daemon[9279]: Withdrawing address record for 192.168.1.165 on wlan0. Feb 12 20:37:09 volumio avahi-daemon[9279]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.165. Feb 12 20:37:09 volumio dhcpcd[1029]: wlan0: pid 9446 deleted IP address 192.168.1.165/24 Feb 12 20:37:09 volumio dhcpcd[1029]: wlan0: deleting route to 192.168.1.0/24 Feb 12 20:37:09 volumio avahi-daemon[9279]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 12 20:37:09 volumio dhcpcd[1029]: wlan0: deleting default route via 192.168.1.1 Feb 12 20:37:09 volumio sudo[9445]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:09 volumio volumio[1337]: info: Discovery: A device disappeared from network Feb 12 20:37:09 volumio volumio[1337]: info: Discovery: Device volumio disappeared from network Feb 12 20:37:09 volumio wpa_supplicant[9149]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Feb 12 20:37:09 volumio wpa_supplicant[9149]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:56:27:0f:b5:b3 reason=3 locally_generated=1 Feb 12 20:37:09 volumio wpa_supplicant[9149]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 12 20:37:09 volumio sudo[9449]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 12 20:37:09 volumio sudo[9449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 20:37:09 volumio dhcpcd[1029]: wlan0: probing address 192.168.1.165/24 Feb 12 20:37:09 volumio dhcpcd[1029]: wlan0: carrier lost - roaming Feb 12 20:37:09 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 20:37:09 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 12 20:37:09 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 12 20:37:09 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 20:37:09 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 20:37:09 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 20:37:09 volumio volumio[1337]: info: Discovery: adding cd371ee5-22b4-4e9b-b606-2ea60a7e91a0 Feb 12 20:37:09 volumio volumio[1337]: info: Discovery: Found device Volumio Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:09 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:09 volumio volumio[1337]: info: Discovery: this is already registered, cd371ee5-22b4-4e9b-b606-2ea60a7e91a0 Feb 12 20:37:09 volumio volumio[1337]: info: Discovery: Found device Volumio Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:09 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:09 volumio sudo[9449]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:09 volumio welcome[9460]: Resolved ip:[0] Feb 12 20:37:09 volumio wireless.js[8692]: WIRELESS.JS - INFO: Cleaning previous... Feb 12 20:37:09 volumio wpa_supplicant[9149]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 12 20:37:09 volumio wpa_supplicant[9149]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Feb 12 20:37:09 volumio wpa_supplicant[9149]: wlan0: CTRL-EVENT-TERMINATING Feb 12 20:37:09 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:09 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:09 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:09 volumio volumio5-onboarding[9302]: time=2026-02-12T20:37:09.580+02:00 level=INFO msg="enabling BLE discovery" Feb 12 20:37:09 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 20:37:09 volumio dhcpcd[1029]: wlan0: carrier lost Feb 12 20:37:09 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 20:37:09 volumio sudo[9468]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 20:37:09 volumio sudo[9468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 20:37:09 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 12 20:37:09 volumio sudo[9468]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:09 volumio wireless.js[8692]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Feb 12 20:37:09 volumio wireless.js[8692]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 1ms Feb 12 20:37:09 volumio wireless.js[8692]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 2c:cf:67:22:61:1e) Feb 12 20:37:09 volumio sudo[9495]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 12 20:37:09 volumio sudo[9495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:37:09 volumio sudo[9495]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:09 volumio sudo[9503]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 12 20:37:09 volumio sudo[9503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:37:09 volumio ntpd[1181]: PROTO: 78.62.132.20 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:10 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:10 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:10 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:10 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:10 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:10 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:10 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:10 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:10 volumio ntpd[1181]: IO: Deleting interface #10 wlan0, 192.168.1.165#123, interface stats: received=0, sent=5, dropped=0, active_time=19 secs Feb 12 20:37:10 volumio ntpd[1181]: PROTO: 193.219.94.180 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio ntpd[1181]: PROTO: 5.20.0.21 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio ntpd[1181]: PROTO: 212.59.0.1 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio ntpd[1181]: PROTO: 5.20.0.20 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio ntpd[1181]: PROTO: 193.219.61.120 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio ntpd[1181]: PROTO: 212.59.0.2 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio ntpd[1181]: PROTO: 185.177.150.85 unlink local addr 192.168.1.165 -> Feb 12 20:37:10 volumio volumio-remote-updater[991]: curl_easy_perform() failed: Couldn't resolve host name Feb 12 20:37:10 volumio volumio-remote-updater[991]: terminate called after throwing an instance of 'std::invalid_argument' Feb 12 20:37:10 volumio volumio-remote-updater[991]: what(): parse error - unexpected end of input Feb 12 20:37:10 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Feb 12 20:37:10 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Feb 12 20:37:10 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Feb 12 20:37:10 volumio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Feb 12 20:37:10 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Feb 12 20:37:10 volumio volumio-remote-updater[9507]: Volumio OTA Client: 1.7.0 Feb 12 20:37:10 volumio volumio-remote-updater[9507]: Error: No active session Feb 12 20:37:10 volumio volumio-remote-updater[9507]: [2026-02-12 20:37:10] [connect] Successful connection Feb 12 20:37:10 volumio volumio-remote-updater[9507]: [2026-02-12 20:37:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770921430 101 Feb 12 20:37:10 volumio volumio[1337]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Feb 12 20:37:12 volumio sudo[9503]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:12 volumio wireless.js[8692]: WIRELESS.JS - INFO: Regdomain already correct: GB Feb 12 20:37:12 volumio wireless.js[8692]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 12 20:37:12 volumio wireless.js[8692]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 12 20:37:12 volumio wireless.js[8692]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 12 20:37:12 volumio sudo[9515]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 20:37:12 volumio sudo[9515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 20:37:12 volumio sudo[9515]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:12 volumio sudo[9518]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 12 20:37:12 volumio sudo[9518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 20:37:12 volumio sudo[9518]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:12 volumio wpa_supplicant[9521]: Successfully initialized wpa_supplicant Feb 12 20:37:12 volumio wpa_supplicant[9521]: nl80211: kernel reports: Registration to specific type not supported Feb 12 20:37:12 volumio wpa_supplicant[9524]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 12 20:37:12 volumio wireless.js[8692]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 3404ms Feb 12 20:37:12 volumio wireless.js[8692]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Feb 12 20:37:12 volumio wireless.js[8692]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 12 20:37:12 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:12 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:12 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:12 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:12 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:12 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:12 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:12 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:12 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:12 volumio dhcpcd[1029]: eth0: using static address 192.168.1.165/24 Feb 12 20:37:12 volumio avahi-daemon[9279]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.165. Feb 12 20:37:12 volumio avahi-daemon[9279]: New relevant interface eth0.IPv4 for mDNS. Feb 12 20:37:12 volumio avahi-daemon[9279]: Registering new address record for 192.168.1.165 on eth0.IPv4. Feb 12 20:37:12 volumio dhcpcd[1029]: eth0: adding route to 192.168.1.0/24 Feb 12 20:37:12 volumio dhcpcd[1029]: eth0: adding default route via 192.168.1.1 Feb 12 20:37:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 20:37:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 12 20:37:13 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 12 20:37:13 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 20:37:13 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 20:37:13 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 20:37:13 volumio welcome[9557]: Resolved ip:[1] 192.168.1.165 Feb 12 20:37:13 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 20:37:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 20:37:13 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 12 20:37:13 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 12 20:37:13 volumio sudo[9564]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 20:37:13 volumio sudo[9564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:37:13 volumio sudo[9566]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 20:37:13 volumio sudo[9566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:37:13 volumio sudo[9564]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:13 volumio sudo[9566]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:13 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 12 20:37:13 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:13 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:13 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:13 volumio volumio5-onboarding[9302]: time=2026-02-12T20:37:13.507+02:00 level=INFO msg="disabling BLE discovery" Feb 12 20:37:13 volumio volumio5-onboarding[9302]: time=2026-02-12T20:37:13.510+02:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=2C:CF:67:22:61:1F%00 Feb 12 20:37:13 volumio sudo[9570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 20:37:13 volumio sudo[9570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:37:13 volumio sudo[9570]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:13 volumio sudo[9572]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 20:37:13 volumio sudo[9572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:37:13 volumio sudo[9572]: pam_unix(sudo:session): session closed for user root Feb 12 20:37:13 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:13 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:37:13 volumio volumio[1337]: info: Listing playlists Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 12 20:37:13 volumio volumio[1337]: info: Discovery: this is already registered, cd371ee5-22b4-4e9b-b606-2ea60a7e91a0 Feb 12 20:37:13 volumio volumio[1337]: info: Discovery: Found device Volumio Feb 12 20:37:13 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:13 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:14 volumio volumio[1337]: info: Volumio Network Manager: Network status updated: 1 Feb 12 20:37:14 volumio ntpd[1181]: IO: Listen normally on 11 eth0 192.168.1.165:123 Feb 12 20:37:14 volumio ntpd[1181]: IO: new interface(s) found: waking up resolver Feb 12 20:37:15 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:15 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:15 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:15 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:15 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:15 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:15 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:15 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 12 20:37:16 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:16 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:16 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:16 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:16 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:16 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:16 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:16 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:18 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:18 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 20:37:21 volumio volumio5-onboarding[9302]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Feb 12 20:37:21 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:37:21 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 20:37:21 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Feb 12 20:37:21 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 20:37:21 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 20:37:21 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:21.940+02:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 12 20:37:21 volumio volumio[1337]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Feb 12 20:37:21 volumio volumio[1337]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Feb 12 20:37:21 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:21 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:21 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:21 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:21.952+02:00 level=INFO msg="system info for 5b600903fae827b9079738e791d6f811" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 12 20:37:21 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:21 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:21 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:37:21 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:21.966+02:00 level=INFO msg="enabling local network discovery" Feb 12 20:37:21 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:21.982+02:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 12 20:37:21 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:21 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:21 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:21 volumio volumio-remote-updater[9507]: Test mode disabled Feb 12 20:37:21 volumio volumio-remote-updater[9507]: Alpha mode disabled Feb 12 20:37:21 volumio volumio-remote-updater[9507]: Alpha legacy test mode disabled Feb 12 20:37:23 volumio volumio[1337]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 12 20:37:23 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 12 20:37:23 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:23 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:23 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 20:37:23 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:23.883+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Feb 12 20:37:23 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:23.883+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Feb 12 20:37:23 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:23.883+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Feb 12 20:37:23 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:23 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:23 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 20:37:24 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:37:24 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 20:37:26 volumio volumio5-onboarding[9599]: time=2026-02-12T20:37:26.026+02:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=wbCYEsuLsANhKXeItjNvfMeK7S42 tokenExpiry=2026-02-12T21:37:26.026+02:00 Feb 12 20:37:29 volumio volumio[1337]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 20:37:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 12 20:37:31 volumio volumio[1337]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 12 20:37:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 12 20:37:31 volumio volumio[1337]: info: Received Get System Version Feb 12 20:37:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 20:37:31 volumio volumio[1337]: info: Received Get System Info Feb 12 20:37:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:37:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:37:31 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:37:31 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:37:31 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:37:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:37:53 volumio volumio[1337]: info: CALLMETHOD: system_controller mqtt_client saveConnectionSettings [object Object] Feb 12 20:37:53 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mqtt_client , saveConnectionSettings Feb 12 20:37:53 volumio volumio[1337]: info: [MQTT] Connecting to MQTT broker: mqtt://192.168.1.5:1883 Feb 12 20:37:53 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:37:53 volumio volumio[1337]: error: [MQTT] Reconnection failed: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:37:58 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:03 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:08 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:13 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:18 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:22 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: alarm-clock , getAlarms Feb 12 20:38:23 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:25 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:38:25 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 20:38:28 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:32 volumio volumio[1337]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 20:38:33 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:37 volumio volumio[1337]: info: Received OAUTH Data Feb 12 20:38:37 volumio volumio[1337]: info: Executing Spotify Oauth Login Feb 12 20:38:37 volumio volumio[1337]: info: Saving Spotify Refresh Token Feb 12 20:38:37 volumio sudo[9765]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 20:38:37 volumio sudo[9765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:38:37 volumio sudo[9767]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 20:38:37 volumio sudo[9765]: pam_unix(sudo:session): session closed for user root Feb 12 20:38:37 volumio sudo[9767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:38:37 volumio sudo[9767]: pam_unix(sudo:session): session closed for user root Feb 12 20:38:37 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 12 20:38:37 volumio volumio[1337]: info: New Spotify access tokenBQCZ31QtSw... Feb 12 20:38:37 volumio volumio[1337]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:38:37 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 12 20:38:37 volumio volumio[1337]: SPOTIFY: User informations: {"country":"IT","display_name":"darius.neciunas","email":"darius.neciunas@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/darius.neciunas"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/darius.neciunas","id":"darius.neciunas","images":[],"product":"free","type":"user","uri":"spotify:user:darius.neciunas"} Feb 12 20:38:37 volumio volumio[1337]: info: Creating Spotify config file Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:38:37 volumio volumio[1337]: info: Spotify config file written Feb 12 20:38:37 volumio sudo[9772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 20:38:37 volumio sudo[9772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:38:37 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 20:38:37 volumio systemd[1]: go-librespot-daemon.service: Killing process 1818 (go-librespot) with signal SIGKILL. Feb 12 20:38:37 volumio volumio[1337]: info: Connection to go-librespot Websocket closed Feb 12 20:38:37 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 20:38:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:38:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:38:37 volumio go-librespot[9774]: go-librespot daemon starting... Feb 12 20:38:37 volumio sudo[9772]: pam_unix(sudo:session): session closed for user root Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=info msg="running go-librespot 0.6.2" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="app state loaded" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="stored credentials not found" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=info msg="zeroconf server listening on port 39159" Feb 12 20:38:37 volumio volumio[1337]: info: New Spotify access tokenBQAKdWxLVb... Feb 12 20:38:37 volumio volumio[1337]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="obtained new client token: AADJ13dDM8A8wavVm10DFwf8F/vTrCWVH4MjaH8Nl+lTTj1y7+6VcEB/UE50t/6WGKjt9DRu+rc/VRgqeUe3+Yz1N80quUanmsnIOGx500JD5mgHUCPxUTgui2SoGzyPTEz1IcbxL7RJ9tcgjhjm4twPjYwbH6psV6QmbEfJULmzEGhqkLsGeow0tcYM2vE0VVn6N+on+zsgQv2KMrzJgT4sD4KuNGdc0TSZCNoCGiJLL6HA/NeGhuA=" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 12 20:38:37 volumio volumio[1337]: SPOTIFY: User informations: {"country":"IT","display_name":"darius.neciunas","email":"darius.neciunas@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/darius.neciunas"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/darius.neciunas","id":"darius.neciunas","images":[],"product":"free","type":"user","uri":"spotify:user:darius.neciunas"} Feb 12 20:38:37 volumio volumio[1337]: info: Spotify Successfully logged in Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 20:38:37 volumio volumio[1337]: info: [1770921517741] CoreMusicLibrary::Adding element Spotify Feb 12 20:38:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:38:37 volumio volumio[1337]: Cannot find translation for source Personal Radio Feb 12 20:38:37 volumio volumio[1337]: Cannot find translation for source Spotify Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="completed keyexchange" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=debug msg="completed challenge" Feb 12 20:38:37 volumio go-librespot[9775]: time="2026-02-12T20:38:37+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 12 20:38:38 volumio go-librespot[9775]: time="2026-02-12T20:38:38+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Feb 12 20:38:38 volumio go-librespot[9775]: time="2026-02-12T20:38:38+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 12 20:38:38 volumio go-librespot[9775]: time="2026-02-12T20:38:38+02:00" level=debug msg="completed keyexchange" Feb 12 20:38:38 volumio go-librespot[9775]: time="2026-02-12T20:38:38+02:00" level=debug msg="completed challenge" Feb 12 20:38:38 volumio go-librespot[9775]: time="2026-02-12T20:38:38+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 12 20:38:38 volumio volumio[1337]: error: [MQTT] MQTT connection error: Error: connect ECONNREFUSED 192.168.1.5:1883 Feb 12 20:38:39 volumio go-librespot[9775]: time="2026-02-12T20:38:39+02:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 12 20:38:39 volumio go-librespot[9775]: time="2026-02-12T20:38:39+02:00" level=debug msg="completed keyexchange" Feb 12 20:38:39 volumio go-librespot[9775]: time="2026-02-12T20:38:39+02:00" level=debug msg="completed challenge" Feb 12 20:38:39 volumio go-librespot[9775]: time="2026-02-12T20:38:39+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:38:39 volumio volumio[1337]: info: Received Get System Info Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:38:39 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:38:39 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 20:38:39 volumio volumio[1337]: info: Received Get System Info Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:38:39 volumio volumio[1337]: info: Discovery: Getting this device information Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Feb 12 20:38:39 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Feb 12 20:38:39 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:38:39 volumio go-librespot[9775]: time="2026-02-12T20:38:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 12 20:38:39 volumio go-librespot[9775]: time="2026-02-12T20:38:39+02:00" level=debug msg="completed keyexchange" Feb 12 20:38:39 volumio go-librespot[9775]: time="2026-02-12T20:38:39+02:00" level=debug msg="completed challenge" Feb 12 20:38:40 volumio go-librespot[9775]: time="2026-02-12T20:38:40+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 12 20:38:40 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Feb 12 20:38:40 volumio go-librespot[9775]: time="2026-02-12T20:38:40+02:00" level=debug msg="new websocket client" Feb 12 20:38:40 volumio volumio[1337]: info: Connection to go-librespot Websocket established Feb 12 20:38:40 volumio volumio[1337]: info: go-librespot daemon successfully initialized Feb 12 20:38:40 volumio go-librespot[9775]: time="2026-02-12T20:38:40+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 12 20:38:41 volumio go-librespot[9775]: time="2026-02-12T20:38:41+02:00" level=debug msg="completed keyexchange" Feb 12 20:38:41 volumio go-librespot[9775]: time="2026-02-12T20:38:41+02:00" level=debug msg="completed challenge" Feb 12 20:38:41 volumio go-librespot[9775]: time="2026-02-12T20:38:41+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 12 20:38:41 volumio go-librespot[9775]: time="2026-02-12T20:38:41+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 12 20:38:41 volumio go-librespot[9775]: time="2026-02-12T20:38:41+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 20:38:41 volumio go-librespot[9775]: time="2026-02-12T20:38:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 20:38:41 volumio volumio[1337]: info: Connection to go-librespot Websocket closed Feb 12 20:38:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:38:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:38:43 volumio volumio[1337]: info: Getting Spotify volume Feb 12 20:38:43 volumio volumio[1337]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:38:43 volumio volumio[1337]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 20:38:43 volumio volumio[1337]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 20:38:43 volumio volumio[1337]: errno: -111, Feb 12 20:38:43 volumio volumio[1337]: code: 'ECONNREFUSED', Feb 12 20:38:43 volumio volumio[1337]: syscall: 'connect', Feb 12 20:38:43 volumio volumio[1337]: address: '127.0.0.1', Feb 12 20:38:43 volumio volumio[1337]: port: 9879, Feb 12 20:38:43 volumio volumio[1337]: response: undefined Feb 12 20:38:43 volumio volumio[1337]: } Feb 12 20:38:43 volumio volumio[1337]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:38:43 volumio sudo[9804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 20:37' Feb 12 20:38:43 volumio sudo[9804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"