Jan 28 06:12:04 volumiosalon volumio[13292]: info: BOOT COMPLETED Jan 28 06:12:05 volumiosalon volumio[13292]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Jan 28 06:12:05 volumiosalon volumio[13292]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Jan 28 06:12:05 volumiosalon volumio[13292]: info: CoreCommandRouter::volumioGetState Jan 28 06:12:05 volumiosalon volumio[13292]: info: CorePlayQueue::getTrack 0 Jan 28 06:12:05 volumiosalon volumio[13292]: info: CoreCommandRouter::volumioPause Jan 28 06:12:05 volumiosalon volumio[13292]: info: CoreStateMachine::pause Jan 28 06:12:05 volumiosalon volumio[13292]: info: mpdhttpout --- Volumio set on pause Jan 28 06:12:05 volumiosalon go-librespot[13620]: time="2026-01-28T06:12:05+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" Jan 28 06:12:05 volumiosalon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 06:12:05 volumiosalon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 06:12:06 volumiosalon volumio[13292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 06:12:06 volumiosalon volumio[13292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 06:12:06 volumiosalon volumio[13292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 06:12:07 volumiosalon volumio[13292]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jan 28 06:12:07 volumiosalon sudo[13781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 06:12:07 volumiosalon sudo[13781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 06:12:07 volumiosalon systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 06:12:07 volumiosalon systemd[1]: mpd.service: Deactivated successfully. Jan 28 06:12:07 volumiosalon systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 06:12:07 volumiosalon systemd[1]: mpd.socket: Deactivated successfully. Jan 28 06:12:07 volumiosalon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 06:12:07 volumiosalon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 06:12:07 volumiosalon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 06:12:07 volumiosalon systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 06:12:07 volumiosalon sudo[13784]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 06:12:07 volumiosalon sudo[13784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 06:12:07 volumiosalon sudo[13784]: pam_unix(sudo:session): session closed for user root Jan 28 06:12:08 volumiosalon mpd[13786]: 2026-01-28T06:12:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 06:12:08 volumiosalon systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 06:12:08 volumiosalon sudo[13781]: pam_unix(sudo:session): session closed for user root Jan 28 06:12:08 volumiosalon volumio[13292]: error: updateQueue error: null Jan 28 06:12:09 volumiosalon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 28 06:12:09 volumiosalon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 06:12:09 volumiosalon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 06:12:09 volumiosalon go-librespot[13789]: go-librespot daemon starting... Jan 28 06:12:09 volumiosalon go-librespot[13790]: time="2026-01-28T06:12:09+01:00" level=info msg="running go-librespot 0.6.2" Jan 28 06:12:09 volumiosalon go-librespot[13790]: time="2026-01-28T06:12:09+01:00" level=debug msg="app state loaded" Jan 28 06:12:09 volumiosalon go-librespot[13790]: time="2026-01-28T06:12:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 06:12:14 volumiosalon volumio[13292]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=4539e7df76c63c5359cab5979f4d5025" http://updates.volumio.org/downloader-v1/track-device Jan 28 06:12:14 volumiosalon volumio[13292]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 28 06:12:14 volumiosalon volumio[13292]: Dload Upload Total Spent Left Speed Jan 28 06:12:14 volumiosalon volumio[13292]: [2.2K blob data] Jan 28 06:12:14 volumiosalon volumio[13292]: retrying in 5 seconds, trial 0 Jan 28 06:12:14 volumiosalon volumio[13292]: info: Volumio Calling Home Jan 28 06:12:31 volumiosalon volumio[13292]: info: Discovery: adding e0627878-d626-4ded-a107-e50d5d4b6d3b Jan 28 06:12:31 volumiosalon volumio[13292]: info: Discovery: Found device VolumioSalon Jan 28 06:12:31 volumiosalon volumio[13292]: info: CoreCommandRouter::volumioGetState Jan 28 06:12:31 volumiosalon volumio[13292]: info: CorePlayQueue::getTrack 0 Jan 28 06:12:31 volumiosalon volumio[13292]: info: Discovery: this is already registered, e0627878-d626-4ded-a107-e50d5d4b6d3b Jan 28 06:12:31 volumiosalon volumio[13292]: info: Discovery: Found device VolumioSalon Jan 28 06:12:31 volumiosalon volumio[13292]: info: CoreCommandRouter::volumioGetState Jan 28 06:12:31 volumiosalon volumio[13292]: info: CorePlayQueue::getTrack 0 Jan 28 06:12:31 volumiosalon volumio[13292]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 28 06:12:31 volumiosalon volumio[13292]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 28 06:12:31 volumiosalon volumio[13292]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 28 06:12:33 volumiosalon systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 28 06:12:33 volumiosalon systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 28 06:12:33 volumiosalon systemd[1]: welcome.service: Deactivated successfully. Jan 28 06:12:33 volumiosalon systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 28 06:12:33 volumiosalon systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 28 06:12:33 volumiosalon systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 28 06:12:33 volumiosalon welcome[13862]: Resolved ip:[1] 192.168.2.109 Jan 28 06:12:33 volumiosalon systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 28 06:12:33 volumiosalon systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 28 06:12:37 volumiosalon go-librespot[13790]: time="2026-01-28T06:12:37+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" Jan 28 06:12:37 volumiosalon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 06:12:37 volumiosalon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 06:12:40 volumiosalon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 28 06:12:40 volumiosalon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 06:12:40 volumiosalon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 06:12:40 volumiosalon go-librespot[13882]: go-librespot daemon starting... Jan 28 06:12:40 volumiosalon go-librespot[13883]: time="2026-01-28T06:12:40+01:00" level=info msg="running go-librespot 0.6.2" Jan 28 06:12:40 volumiosalon go-librespot[13883]: time="2026-01-28T06:12:40+01:00" level=debug msg="app state loaded" Jan 28 06:12:40 volumiosalon go-librespot[13883]: time="2026-01-28T06:12:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 06:12:52 volumiosalon volumio[13292]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=4539e7df76c63c5359cab5979f4d5025" http://updates.volumio.org/downloader-v1/track-device Jan 28 06:12:52 volumiosalon volumio[13292]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 28 06:12:52 volumiosalon volumio[13292]: Dload Upload Total Spent Left Speed Jan 28 06:12:52 volumiosalon volumio[13292]: [2.2K blob data] Jan 28 06:12:52 volumiosalon volumio[13292]: retrying in 5 seconds, trial 1 Jan 28 06:12:52 volumiosalon volumio[13292]: info: Volumio Calling Home Jan 28 06:12:59 volumiosalon volumio[13292]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jan 28 06:13:08 volumiosalon go-librespot[13883]: time="2026-01-28T06:13:08+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" Jan 28 06:13:08 volumiosalon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 06:13:08 volumiosalon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 06:13:11 volumiosalon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 28 06:13:11 volumiosalon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 06:13:11 volumiosalon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 06:13:11 volumiosalon go-librespot[13950]: go-librespot daemon starting... Jan 28 06:13:11 volumiosalon go-librespot[13951]: time="2026-01-28T06:13:11+01:00" level=info msg="running go-librespot 0.6.2" Jan 28 06:13:11 volumiosalon go-librespot[13951]: time="2026-01-28T06:13:11+01:00" level=debug msg="app state loaded" Jan 28 06:13:11 volumiosalon go-librespot[13951]: time="2026-01-28T06:13:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 06:13:27 volumiosalon volumio[13292]: error: FusionDsp - Reload WebSocket error: [object Object] Jan 28 06:13:27 volumiosalon go-librespot[13951]: time="2026-01-28T06:13:27+01:00" level=debug msg="new websocket client" Jan 28 06:13:27 volumiosalon volumio[13292]: info: Connection to go-librespot Websocket established Jan 28 06:13:30 volumiosalon volumio[13292]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=4539e7df76c63c5359cab5979f4d5025" http://updates.volumio.org/downloader-v1/track-device Jan 28 06:13:30 volumiosalon volumio[13292]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 28 06:13:30 volumiosalon volumio[13292]: Dload Upload Total Spent Left Speed Jan 28 06:13:30 volumiosalon volumio[13292]: [2.2K blob data] Jan 28 06:13:30 volumiosalon volumio[13292]: retrying in 5 seconds, trial 2 Jan 28 06:13:30 volumiosalon volumio[13292]: info: Volumio Calling Home Jan 28 06:13:30 volumiosalon volumio[13292]: info: Getting Spotify volume Jan 28 06:13:39 volumiosalon go-librespot[13951]: time="2026-01-28T06:13:39+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" Jan 28 06:13:39 volumiosalon volumio[13292]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 06:13:39 volumiosalon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 06:13:39 volumiosalon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 06:13:39 volumiosalon volumio[13292]: Error: socket hang up Jan 28 06:13:39 volumiosalon volumio[13292]: at connResetException (node:internal/errors:720:14) Jan 28 06:13:39 volumiosalon volumio[13292]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 28 06:13:39 volumiosalon volumio[13292]: at Socket.emit (node:events:526:35) Jan 28 06:13:39 volumiosalon volumio[13292]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 28 06:13:39 volumiosalon volumio[13292]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 28 06:13:39 volumiosalon volumio[13292]: code: 'ECONNRESET', Jan 28 06:13:39 volumiosalon volumio[13292]: response: undefined Jan 28 06:13:39 volumiosalon volumio[13292]: } Jan 28 06:13:39 volumiosalon volumio[13292]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 06:13:39 volumiosalon sudo[14033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 06:12' Jan 28 06:13:39 volumiosalon sudo[14033]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"