Dec 20 21:50:29 volumio ntpd[1304]: CLOCK: time stepped by 73292.559199 Dec 20 21:50:29 volumio ntpd[1304]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 20 21:50:29 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 20 21:50:29 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 20 21:50:29 volumio volumio[1571]: info: Discovery: A device disappeared from network Dec 20 21:50:29 volumio volumio[1571]: info: Discovery: Device volumio disappeared from network Dec 20 21:50:29 volumio volumio[1571]: info: Discovery: A device disappeared from network Dec 20 21:50:29 volumio volumio-remote-updater[1092]: [2025-12-20 21:50:29] [connect] Successful connection Dec 20 21:50:29 volumio volumio-remote-updater[1092]: [2025-12-20 21:50:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766260229 101 Dec 20 21:50:29 volumio volumio[1571]: 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: 4 Dec 20 21:50:29 volumio volumio[1571]: info: Discovery: adding ee63f2fc-13d1-4972-889c-8cfc8b299775 Dec 20 21:50:29 volumio volumio[1571]: info: Discovery: Found device Volumio Dec 20 21:50:29 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 20 21:50:29 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 20 21:50:29 volumio volumio[1571]: info: Discovery: this is already registered, ee63f2fc-13d1-4972-889c-8cfc8b299775 Dec 20 21:50:29 volumio volumio[1571]: info: Discovery: Found device Volumio Dec 20 21:50:29 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 20 21:50:29 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 20 21:50:30 volumio volumio[1571]: info: go-librespot daemon successfully initialized Dec 20 21:50:30 volumio ntpd[1304]: IO: Deleting interface #3 wlan0, 192.168.2.112#123, interface stats: received=32, sent=38, dropped=0, active_time=7 secs Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 91.236.251.31 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 193.34.155.4 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 176.120.61.236 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 162.159.200.1 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 91.236.251.24 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 91.236.251.13 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 217.156.67.138 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 194.54.80.29 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 31.28.161.68 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 62.149.0.30 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 91.236.251.234 unlink local addr 192.168.2.112 -> Dec 20 21:50:30 volumio ntpd[1304]: PROTO: 193.106.144.6 unlink local addr 192.168.2.112 -> Dec 20 21:50:31 volumio sudo[2145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 21:50:31 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 21:50:31 volumio sudo[2148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 21:50:31 volumio sudo[2148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 21:50:31 volumio sudo[2150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 21:50:31 volumio sudo[2150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 21:50:31 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 21:50:31 volumio mpd_monitor.sh[2152]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 21:50:31 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Dec 20 21:50:31 volumio volumio[1571]: info: Successfully started MPD Monitor Dec 20 21:50:31 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 20 21:50:31 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 20 21:50:31 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 20 21:50:31 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 21:50:31 volumio sudo[2150]: pam_unix(sudo:session): session closed for user root Dec 20 21:50:31 volumio mpd_monitor.sh[2158]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 21:50:31 volumio sudo[2148]: pam_unix(sudo:session): session closed for user root Dec 20 21:50:31 volumio volumio[1571]: info: Successfully started MPD Monitor Dec 20 21:50:31 volumio volumio[1571]: info: Successfully started MPD Monitor Dec 20 21:50:32 volumio volumio[1571]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 20 21:50:33 volumio volumio[1571]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 20 21:50:33 volumio volumio[1571]: info: Initializing connection to go-librespot Websocket Dec 20 21:50:33 volumio volumio[1571]: info: Connection to go-librespot Websocket established Dec 20 21:50:33 volumio go-librespot[1901]: time="2025-12-20T21:50:33+02:00" level=debug msg="new websocket client" Dec 20 21:50:33 volumio volumio[1571]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 20 21:50:33 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 21:50:33 volumio volumio[1571]: info: Received Get System Info Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:50:33 volumio volumio[1571]: info: Discovery: Getting this device information Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 20 21:50:33 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 20 21:50:33 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 20 21:50:33 volumio volumio[1571]: info: Listing playlists Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 21:50:33 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 21:50:34 volumio sudo[2200]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 21:50:34 volumio sudo[2200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 21:50:34 volumio sudo[2202]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 21:50:34 volumio sudo[2202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 21:50:34 volumio sudo[2202]: pam_unix(sudo:session): session closed for user root Dec 20 21:50:34 volumio sudo[2200]: pam_unix(sudo:session): session closed for user root Dec 20 21:50:34 volumio sudo[2205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 20 21:50:34 volumio sudo[2205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 21:50:35 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 20 21:50:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 20 21:50:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 21:50:35 volumio volumio[1571]: info: Received Get System Info Dec 20 21:50:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:50:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:50:35 volumio volumio[1571]: info: Discovery: Getting this device information Dec 20 21:50:35 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 20 21:50:35 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 20 21:50:35 volumio volumio[1571]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:50:35 volumio dhcpcd[945]: timed out Dec 20 21:50:35 volumio sh[934]: timed out Dec 20 21:50:35 volumio sh[887]: ifup: failed to bring up eth0 Dec 20 21:50:35 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 20 21:50:35 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 20 21:50:36 volumio volumio[1571]: info: Getting Spotify volume Dec 20 21:50:36 volumio volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 20 21:50:36 volumio volumio[1571]: info: CoreCommandRouter::volumioGetState Dec 20 21:50:36 volumio volumio[1571]: info: CorePlayQueue::getTrack 0 Dec 20 21:50:37 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 20 21:50:37 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 20 21:50:37 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 21:50:37 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 21:50:37 volumio sudo[2205]: pam_unix(sudo:session): session closed for user root Dec 20 21:50:37 volumio volumio[1571]: info: Upmpdcli Daemon Started Dec 20 21:50:38 volumio wpa_supplicant[1457]: wlan0: Authentication with 50:ff:20:34:87:81 timed out. Dec 20 21:50:38 volumio wpa_supplicant[1457]: wlan0: CTRL-EVENT-DISCONNECTED bssid=50:ff:20:34:87:81 reason=3 locally_generated=1 Dec 20 21:50:38 volumio wpa_supplicant[1457]: BSSID 50:ff:20:34:87:81 ignore list count incremented to 2, ignoring for 10 seconds Dec 20 21:50:38 volumio wpa_supplicant[1457]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Dec 20 21:50:38 volumio wpa_supplicant[1457]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 20 21:50:38 volumio wpa_supplicant[1457]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=UA Dec 20 21:50:38 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Dec 20 21:50:41 volumio wpa_supplicant[1457]: wlan0: Trying to associate with 50:ff:20:34:87:83 (SSID='Keenetic-9973' freq=5180 MHz) Dec 20 21:50:41 volumio wpa_supplicant[1457]: FT: Invalid key management type (2) Dec 20 21:50:41 volumio wpa_supplicant[1457]: wlan0: Associated with 50:ff:20:34:87:83 Dec 20 21:50:41 volumio wpa_supplicant[1457]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 20 21:50:41 volumio wpa_supplicant[1457]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=UA Dec 20 21:50:41 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Dec 20 21:50:41 volumio wpa_supplicant[1457]: wlan0: WPA: Key negotiation completed with 50:ff:20:34:87:83 [PTK=CCMP GTK=CCMP] Dec 20 21:50:41 volumio wpa_supplicant[1457]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:ff:20:34:87:83 completed [id=0 id_str=] Dec 20 21:50:41 volumio dhcpcd[1148]: wlan0: carrier acquired Dec 20 21:50:41 volumio dhcpcd[1148]: wlan0: IAID 67:45:83:37 Dec 20 21:50:42 volumio dhcpcd[1148]: wlan0: soliciting a DHCP lease Dec 20 21:50:42 volumio dhcpcd[1148]: wlan0: offered 192.168.2.112 from 192.168.2.1 Dec 20 21:50:42 volumio dhcpcd[1148]: wlan0: soliciting an IPv6 router Dec 20 21:50:42 volumio dhcpcd[1148]: wlan0: leased 192.168.2.112 for 25200 seconds Dec 20 21:50:42 volumio dhcpcd[1148]: wlan0: changing route to 192.168.2.0/24 Dec 20 21:50:42 volumio dhcpcd[1148]: wlan0: changing default route via 192.168.2.1 Dec 20 21:50:42 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 20 21:50:42 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 20 21:50:42 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 20 21:50:42 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 20 21:50:42 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 20 21:50:42 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 20 21:50:42 volumio welcome[2255]: Resolved ip:[1] 192.168.2.112 Dec 20 21:50:42 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 20 21:50:42 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 20 21:50:42 volumio go-librespot[1901]: time="2025-12-20T21:50:42+02:00" level=debug msg="obtained new client token: AAAaV0ObEbosDnvVrtjquf6T7376DLMgoOqahBQ6OzGvmz+Ujpch5jJ8Pcsq3L4hFCFBzQG6lG63yZwBOuCF8/TBlNtcpQ+BfqdUPBWoqH4CyseESDHJIlg+++lieq2ICCqaoELL+SPsi49noJnw5KY71HER8mcOOUx6jJxm9xt0nyJj2cCKlmEAhX3LknNgdq09EEKhrkADKo1eA12rLML4AOlt/lO4jMtaWlIuYR1ZDXRFIAKPoBgheTqa" Dec 20 21:50:43 volumio go-librespot[1901]: time="2025-12-20T21:50:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 21:50:43 volumio go-librespot[1901]: time="2025-12-20T21:50:43+02:00" level=debug msg="completed keyexchange" Dec 20 21:50:43 volumio go-librespot[1901]: time="2025-12-20T21:50:43+02:00" level=debug msg="completed challenge" Dec 20 21:50:43 volumio go-librespot[1901]: time="2025-12-20T21:50:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 20 21:50:43 volumio volumio[1571]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 21:50:43 volumio volumio[1571]: Error: socket hang up Dec 20 21:50:43 volumio volumio[1571]: at connResetException (node:internal/errors:720:14) Dec 20 21:50:43 volumio volumio[1571]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 20 21:50:43 volumio volumio[1571]: at Socket.emit (node:events:526:35) Dec 20 21:50:43 volumio volumio[1571]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 20 21:50:43 volumio volumio[1571]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 20 21:50:43 volumio volumio[1571]: code: 'ECONNRESET', Dec 20 21:50:43 volumio volumio[1571]: response: undefined Dec 20 21:50:43 volumio volumio[1571]: } Dec 20 21:50:43 volumio volumio[1571]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 21:50:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 21:50:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 21:50:43 volumio sudo[2274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 21:49' Dec 20 21:50:43 volumio sudo[2274]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"