Jun 01 19:11:01 volumioamplified volumiossh-tunnel[28141]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:11:01 volumioamplified autossh[28138]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:11:01 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:01 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:11:01 volumioamplified volumio[27652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 01 19:11:01 volumioamplified go-librespot[28160]: time="2026-06-01T19:11:01+01:00" level=debug msg="new websocket client" Jun 01 19:11:01 volumioamplified volumio[27652]: info: Connection to go-librespot Websocket established Jun 01 19:11:01 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 89. Jun 01 19:11:01 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:01 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:01 volumioamplified autossh[28184]: port set to 0, monitoring disabled Jun 01 19:11:01 volumioamplified autossh[28184]: starting ssh (count 1) Jun 01 19:11:01 volumioamplified autossh[28184]: ssh child pid is 28187 Jun 01 19:11:04 volumioamplified volumio[27652]: info: Getting Spotify volume Jun 01 19:11:10 volumioamplified go-librespot[28160]: time="2026-06-01T19:11:10+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" Jun 01 19:11:11 volumioamplified systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:11 volumioamplified systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 19:11:11 volumioamplified volumio[27652]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 19:11:11 volumioamplified volumio[27652]: Error: socket hang up Jun 01 19:11:11 volumioamplified volumio[27652]: at connResetException (node:internal/errors:720:14) Jun 01 19:11:11 volumioamplified volumio[27652]: at Socket.socketOnEnd (node:_http_client:519:23) Jun 01 19:11:11 volumioamplified volumio[27652]: at Socket.emit (node:events:526:35) Jun 01 19:11:11 volumioamplified volumio[27652]: at endReadableNT (node:internal/streams/readable:1376:12) Jun 01 19:11:11 volumioamplified volumio[27652]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jun 01 19:11:11 volumioamplified volumio[27652]: code: 'ECONNRESET', Jun 01 19:11:11 volumioamplified volumio[27652]: response: undefined Jun 01 19:11:11 volumioamplified volumio[27652]: } Jun 01 19:11:11 volumioamplified volumio[27652]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 19:11:14 volumioamplified systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jun 01 19:11:14 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:14 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:14 volumioamplified go-librespot[28215]: go-librespot daemon starting... Jun 01 19:11:14 volumioamplified volumiossh-tunnel[28187]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:11:14 volumioamplified autossh[28184]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:11:14 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:14 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:11:14 volumioamplified go-librespot[28216]: time="2026-06-01T19:11:14+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:11:14 volumioamplified go-librespot[28216]: time="2026-06-01T19:11:14+01:00" level=debug msg="app state loaded" Jun 01 19:11:14 volumioamplified go-librespot[28216]: time="2026-06-01T19:11:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 19:11:14 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 90. Jun 01 19:11:14 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:14 volumioamplified sudo[28219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 19:10' Jun 01 19:11:14 volumioamplified sudo[28219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:14 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:14 volumioamplified autossh[28229]: port set to 0, monitoring disabled Jun 01 19:11:14 volumioamplified autossh[28229]: starting ssh (count 1) Jun 01 19:11:14 volumioamplified autossh[28229]: ssh child pid is 28232 Jun 01 19:11:14 volumioamplified sudo[28219]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:26 volumioamplified volumiossh-tunnel[28232]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:11:26 volumioamplified autossh[28229]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:11:26 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:26 volumioamplified go-librespot[28216]: time="2026-06-01T19:11:26+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" Jun 01 19:11:26 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:11:26 volumioamplified systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:26 volumioamplified systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 19:11:26 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 91. Jun 01 19:11:26 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:26 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:26 volumioamplified autossh[28258]: port set to 0, monitoring disabled Jun 01 19:11:26 volumioamplified autossh[28258]: starting ssh (count 1) Jun 01 19:11:26 volumioamplified autossh[28258]: ssh child pid is 28261 Jun 01 19:11:29 volumioamplified systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jun 01 19:11:29 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:29 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:29 volumioamplified go-librespot[28262]: go-librespot daemon starting... Jun 01 19:11:29 volumioamplified go-librespot[28263]: time="2026-06-01T19:11:29+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:11:29 volumioamplified go-librespot[28263]: time="2026-06-01T19:11:29+01:00" level=debug msg="app state loaded" Jun 01 19:11:29 volumioamplified go-librespot[28263]: time="2026-06-01T19:11:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 19:11:39 volumioamplified volumiossh-tunnel[28261]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:11:39 volumioamplified autossh[28258]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:11:39 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:39 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:11:39 volumioamplified systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:39 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 92. Jun 01 19:11:39 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:39 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:39 volumioamplified systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 01 19:11:39 volumioamplified systemd[1]: volumio.service: Consumed 34.643s CPU time. Jun 01 19:11:39 volumioamplified systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 01 19:11:39 volumioamplified autossh[28290]: port set to 0, monitoring disabled Jun 01 19:11:39 volumioamplified autossh[28290]: starting ssh (count 1) Jun 01 19:11:39 volumioamplified autossh[28290]: ssh child pid is 28294 Jun 01 19:11:39 volumioamplified volumio-remote-updater[592]: [2026-06-01 19:11:39] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Jun 01 19:11:39 volumioamplified volumio-remote-updater[592]: [2026-06-01 19:11:39] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Jun 01 19:11:39 volumioamplified systemd[1]: dynamicswap.service: Deactivated successfully. Jun 01 19:11:39 volumioamplified systemd[1]: volumio.service: Scheduled restart job, restart counter is at 404. Jun 01 19:11:39 volumioamplified systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 01 19:11:39 volumioamplified systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 01 19:11:39 volumioamplified systemd[1]: volumio.service: Consumed 34.643s CPU time. Jun 01 19:11:39 volumioamplified systemd[1]: Started volumio.service - Volumio Backend Module. Jun 01 19:11:39 volumioamplified systemd[1]: dynamicswap.service: Deactivated successfully. Jun 01 19:11:42 volumioamplified go-librespot[28263]: time="2026-06-01T19:11:42+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" Jun 01 19:11:42 volumioamplified systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:42 volumioamplified systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 19:11:42 volumioamplified volumio[28301]: info: ------------------------------------------- Jun 01 19:11:42 volumioamplified volumio[28301]: info: ----- Volumio3 ---- Jun 01 19:11:42 volumioamplified volumio[28301]: info: ------------------------------------------- Jun 01 19:11:42 volumioamplified volumio[28301]: info: ----- System startup ---- Jun 01 19:11:42 volumioamplified volumio[28301]: info: ------------------------------------------- Jun 01 19:11:44 volumioamplified volumio[28301]: info: MYVOLUMIO Environment detected Jun 01 19:11:44 volumioamplified volumio[28301]: info: Plugin folders cleanup Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning into folder /volumio/app/plugins/ Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning category audio_interface Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning category miscellanea Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning category music_service Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning category plugins.json Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning category system_controller Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning category user_interface Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning into folder /data/plugins/ Jun 01 19:11:44 volumioamplified volumio[28301]: info: Scanning category music_service Jun 01 19:11:44 volumioamplified volumio[28301]: info: Plugin folders cleanup completed Jun 01 19:11:44 volumioamplified volumio[28301]: info: ------------------------------------------- Jun 01 19:11:44 volumioamplified volumio[28301]: info: ----- Core plugins startup ---- Jun 01 19:11:44 volumioamplified volumio[28301]: info: ------------------------------------------- Jun 01 19:11:44 volumioamplified volumio[28301]: info: Loading plugins from folder /volumio/app/plugins/ Jun 01 19:11:44 volumioamplified volumio[28301]: info: Adding plugin upnp to MyMusic Plugins Jun 01 19:11:44 volumioamplified volumio-remote-updater[592]: [2026-06-01 19:11:44] [connect] Successful connection Jun 01 19:11:44 volumioamplified volumio[28301]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 01 19:11:44 volumioamplified volumio[28301]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 01 19:11:44 volumioamplified volumio[28301]: info: Loading plugins from folder /data/plugins/ Jun 01 19:11:44 volumioamplified volumio[28301]: info: Loading plugin "system"... Jun 01 19:11:44 volumioamplified volumio[28301]: info: Loading plugin "appearance"... Jun 01 19:11:45 volumioamplified systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jun 01 19:11:45 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:45 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:45 volumioamplified go-librespot[28329]: go-librespot daemon starting... Jun 01 19:11:45 volumioamplified go-librespot[28330]: time="2026-06-01T19:11:45+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:11:45 volumioamplified go-librespot[28330]: time="2026-06-01T19:11:45+01:00" level=debug msg="app state loaded" Jun 01 19:11:45 volumioamplified go-librespot[28330]: time="2026-06-01T19:11:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "network"... Jun 01 19:11:46 volumioamplified volumio[28301]: info: Refreshing Cached IP Addresses Jun 01 19:11:46 volumioamplified sudo[28339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 19:11:46 volumioamplified sudo[28341]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 19:11:46 volumioamplified sudo[28339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:46 volumioamplified sudo[28341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:46 volumioamplified sudo[28341]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "services"... Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "alsa_controller"... Jun 01 19:11:46 volumioamplified sudo[28339]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:46 volumioamplified sudo[28350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 01 19:11:46 volumioamplified sudo[28350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:46 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "wizard"... Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "networkfs"... Jun 01 19:11:46 volumioamplified volumio[28301]: info: Starting Udev Watcher for removable devices Jun 01 19:11:46 volumioamplified volumio[28301]: info: Ignoring mount for partition: boot Jun 01 19:11:46 volumioamplified volumio[28301]: info: Ignoring mount for partition: volumio Jun 01 19:11:46 volumioamplified volumio[28301]: info: Ignoring mount for partition: volumio_data Jun 01 19:11:46 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "volumio_command_line_client"... Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "upnp"... Jun 01 19:11:46 volumioamplified volumio[28301]: info: [1780337506931] Starting Upmpd Daemon Jun 01 19:11:46 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "my_music"... Jun 01 19:11:46 volumioamplified volumio[28301]: info: Loading plugin "mpd"... Jun 01 19:11:47 volumioamplified volumio[28301]: info: Loading plugin "upnp_browser"... Jun 01 19:11:47 volumioamplified sudo[28350]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:49 volumioamplified volumio[28301]: info: Starting UPNP Browser Jun 01 19:11:49 volumioamplified volumio[28301]: info: Loading plugin "alarm-clock"... Jun 01 19:11:50 volumioamplified volumio[28301]: info: Loading plugin "airplay_emulation"... Jun 01 19:11:50 volumioamplified volumio[28301]: info: Starting Shairport Sync Jun 01 19:11:50 volumioamplified volumio[28301]: info: Loading plugin "last_100"... Jun 01 19:11:50 volumioamplified volumio[28301]: info: Loading plugin "webradio"... Jun 01 19:11:50 volumioamplified volumio[28301]: info: Loading plugin "i2s_dacs"... Jun 01 19:11:50 volumioamplified volumio[28301]: info: Loading plugin "volumiodiscovery"... Jun 01 19:11:50 volumioamplified volumio[28301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 01 19:11:50 volumioamplified volumio[28301]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 19:11:50 volumioamplified node[28301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 01 19:11:50 volumioamplified volumio[28301]: *** WARNING *** For more information see Jun 01 19:11:50 volumioamplified volumio[28301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 01 19:11:50 volumioamplified volumio[28301]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 19:11:50 volumioamplified volumio[28301]: *** WARNING *** For more information see Jun 01 19:11:50 volumioamplified node[28301]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 19:11:50 volumioamplified node[28301]: *** WARNING *** For more information see Jun 01 19:11:50 volumioamplified node[28301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 01 19:11:50 volumioamplified node[28301]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 19:11:50 volumioamplified node[28301]: *** WARNING *** For more information see Jun 01 19:11:50 volumioamplified volumio[28301]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 01 19:11:50 volumioamplified volumio[28301]: info: Discovery: Started advertising with name: VolumioAmplified Jun 01 19:11:50 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 19:11:50 volumioamplified volumio[28301]: info: Loading plugin "spop"... Jun 01 19:11:51 volumioamplified volumiossh-tunnel[28294]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:11:51 volumioamplified autossh[28290]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:11:51 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:11:51 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:11:51 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 93. Jun 01 19:11:51 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:51 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:11:51 volumioamplified autossh[28378]: port set to 0, monitoring disabled Jun 01 19:11:51 volumioamplified autossh[28378]: starting ssh (count 1) Jun 01 19:11:51 volumioamplified autossh[28378]: ssh child pid is 28381 Jun 01 19:11:52 volumioamplified volumio[28301]: info: Loading plugin "outputs"... Jun 01 19:11:52 volumioamplified volumio[28301]: info: Loading plugin "albumart"... Jun 01 19:11:52 volumioamplified volumio[28301]: info: Plugin example_plugin is not enabled Jun 01 19:11:52 volumioamplified volumio[28301]: info: Loading plugin "inputs"... Jun 01 19:11:52 volumioamplified volumio[28301]: info: Loading plugin "updater_comm"... Jun 01 19:11:52 volumioamplified volumio[28301]: info: Plugin mpdemulation is not enabled Jun 01 19:11:52 volumioamplified volumio[28301]: info: Loading plugin "rest_api"... Jun 01 19:11:52 volumioamplified volumio[28301]: info: Loading plugin "websocket"... Jun 01 19:11:52 volumioamplified volumio[28301]: info: Starting Socket.io Server version 1.7.4 Jun 01 19:11:52 volumioamplified volumio[28301]: info: Loading i18n strings for locale en Jun 01 19:11:52 volumioamplified volumio[28301]: Updating browse sources language Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::initPlayerControls Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 19:11:52 volumioamplified volumio[28301]: Express server listening on port 3000 Jun 01 19:11:52 volumioamplified volumio[28301]: [Metrics] WebUI: 11s 99.57ms Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::resetVolumioState Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::getcurrentVolume Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 19:11:53 volumioamplified volumio[28382]: Forking 3 albumart workers Jun 01 19:11:53 volumioamplified volumio[28301]: info: Cannot read play queue from file Jun 01 19:11:53 volumioamplified volumio[28301]: info: Volumio Network Manager: Network status updated: 2 Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:53 volumioamplified volumio[28301]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ Jun 01 19:11:53 volumioamplified volumio[28301]: info: VolumeController:: Volume=20 Mute =false Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::pushState Jun 01 19:11:53 volumioamplified volumio[28301]: info: CorePlayQueue::getTrack 0 Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioPushState Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::updateTrackBlock Jun 01 19:11:53 volumioamplified volumio[28301]: info: CorePlayQueue::getTrackBlock Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::setRepeat null single undefined Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::pushState Jun 01 19:11:53 volumioamplified volumio[28301]: info: CorePlayQueue::getTrack 0 Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioPushState Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::setRandom null Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreStateMachine::pushState Jun 01 19:11:53 volumioamplified volumio[28301]: info: CorePlayQueue::getTrack 0 Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioPushState Jun 01 19:11:53 volumioamplified volumio[28301]: info: Setting Device type: Raspberry PI Jun 01 19:11:53 volumioamplified volumio[28301]: info: DAC script executed Jun 01 19:11:53 volumioamplified volumio[28301]: info: Completed loading Core Plugins Jun 01 19:11:53 volumioamplified volumio[28301]: info: Preparing to generate the ALSA configuration file Jun 01 19:11:53 volumioamplified volumio[28301]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 01 19:11:53 volumioamplified volumio[28301]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jun 01 19:11:53 volumioamplified volumio[28301]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 01 19:11:53 volumioamplified volumio[28301]: info: Asound.conf file unchanged, so no further update is needed Jun 01 19:11:53 volumioamplified volumio[28301]: info: Output device has changed, restarting MPD Jun 01 19:11:53 volumioamplified volumio[28301]: info: Output device has changed, restarting Shairport Sync Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:53 volumioamplified volumio[28301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 19:11:53 volumioamplified volumio[28301]: info: ___________ START PLUGINS ___________ Jun 01 19:11:53 volumioamplified sudo[28443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 19:11:53 volumioamplified sudo[28443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:53 volumioamplified sudo[28445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 19:11:53 volumioamplified sudo[28445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:53 volumioamplified sudo[28443]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:53 volumioamplified volumio[28301]: info: ControllerMpd::onStart: Initializing MPD Jun 01 19:11:53 volumioamplified volumio[28301]: info: Creating MPD Configuration file Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 19:11:53 volumioamplified volumio[28301]: info: [1780337513909] CoreMusicLibrary::Adding element Media Servers Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 19:11:53 volumioamplified sudo[28453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 19:11:53 volumioamplified sudo[28453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:53 volumioamplified volumio[28301]: info: UPNP Browser: Client initialized successfully Jun 01 19:11:53 volumioamplified systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 19:11:53 volumioamplified sudo[28453]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:53 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:54 volumioamplified sudo[28455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 19:11:54 volumioamplified sudo[28455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:54 volumioamplified volumio[28301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:54 volumioamplified volumio[28301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 19:11:54 volumioamplified volumio[28301]: info: [1780337514128] CoreMusicLibrary::Adding element Last_100 Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 19:11:54 volumioamplified volumio[28301]: info: [1780337514134] CoreMusicLibrary::Adding element Webradio Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 19:11:54 volumioamplified volumio[28301]: info: Initializing BBC Radios Jun 01 19:11:54 volumioamplified volumio[28301]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:54 volumioamplified volumio[28301]: info: Creating Spotify config file Jun 01 19:11:54 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: Volumio Calling Home Jun 01 19:11:55 volumioamplified volumio[28301]: info: MPD Permissions set Jun 01 19:11:55 volumioamplified volumio[28301]: info: MPD Permissions set Jun 01 19:11:55 volumioamplified volumio[28301]: info: VolumeController:: Volume=20 Mute =false Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreStateMachine::pushState Jun 01 19:11:55 volumioamplified volumio[28301]: info: CorePlayQueue::getTrack 0 Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioPushState Jun 01 19:11:55 volumioamplified volumio[28301]: info: Spotify config file written Jun 01 19:11:55 volumioamplified volumio[28301]: info: No need to fix Spotify hosts Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:55 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:56 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:11:56 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:11:56 volumioamplified volumio[28301]: info: Starting Shairport Sync Jun 01 19:11:56 volumioamplified volumio[28301]: info: Starting Shairport Sync Jun 01 19:11:56 volumioamplified volumio[28301]: info: Starting Shairport Sync Jun 01 19:11:56 volumioamplified sudo[28491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 01 19:11:56 volumioamplified sudo[28508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 19:11:56 volumioamplified sudo[28506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 19:11:56 volumioamplified sudo[28504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 19:11:56 volumioamplified sudo[28504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:56 volumioamplified sudo[28506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:56 volumioamplified sudo[28508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:56 volumioamplified sudo[28491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:56 volumioamplified systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 01 19:11:56 volumioamplified systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 19:11:56 volumioamplified systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 19:11:56 volumioamplified systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 19:11:56 volumioamplified systemd[1]: shairport-sync.service: Consumed 2.732s CPU time. Jun 01 19:11:56 volumioamplified systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 01 19:11:56 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:56 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:11:56 volumioamplified go-librespot[28521]: go-librespot daemon starting... Jun 01 19:11:56 volumioamplified systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 19:11:56 volumioamplified sudo[28508]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:56 volumioamplified sudo[28504]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:56 volumioamplified sudo[28506]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:56 volumioamplified volumio-remote-updater[592]: [2026-06-01 19:11:56] [connect] Successful connection Jun 01 19:11:56 volumioamplified sudo[28491]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:56 volumioamplified volumio-remote-updater[592]: [2026-06-01 19:11:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1780337516 101 Jun 01 19:11:56 volumioamplified go-librespot[28527]: time="2026-06-01T19:11:56+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:11:56 volumioamplified go-librespot[28527]: time="2026-06-01T19:11:56+01:00" level=debug msg="app state loaded" Jun 01 19:11:56 volumioamplified volumio[28301]: 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: 1 Jun 01 19:11:56 volumioamplified volumio[28301]: info: Shairport-Sync Started Jun 01 19:11:56 volumioamplified volumio[28301]: Error adding Membership: Error: addMembership EINVAL Jun 01 19:11:56 volumioamplified volumio[28301]: info: Shairport-Sync Started Jun 01 19:11:56 volumioamplified volumio[28301]: info: Shairport-Sync Started Jun 01 19:11:56 volumioamplified sudo[28537]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 19:11:57 volumioamplified sudo[28537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:57 volumioamplified sudo[28539]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 19:11:57 volumioamplified sudo[28539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:57 volumioamplified sudo[28539]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:57 volumioamplified sudo[28544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 01 19:11:57 volumioamplified sudo[28544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:11:57 volumioamplified go-librespot[28527]: time="2026-06-01T19:11:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 19:11:57 volumioamplified sudo[28537]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:57 volumioamplified sudo[28544]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:57 volumioamplified volumio[28301]: info: Upmpdcli Daemon Started Jun 01 19:11:58 volumioamplified volumio[28397]: Starting albumart workers Jun 01 19:11:58 volumioamplified volumio[28398]: Starting albumart workers Jun 01 19:11:58 volumioamplified volumio[28396]: Starting albumart workers Jun 01 19:11:58 volumioamplified systemd[1]: mpd.service: Deactivated successfully. Jun 01 19:11:58 volumioamplified systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 19:11:58 volumioamplified systemd[1]: mpd.service: Consumed 8.186s CPU time. Jun 01 19:11:58 volumioamplified systemd[1]: mpd.socket: Deactivated successfully. Jun 01 19:11:58 volumioamplified mpd_monitor.sh[28496]: MPD error: Connection reset by peer Jun 01 19:11:58 volumioamplified systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 19:11:58 volumioamplified systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 19:11:58 volumioamplified mpd_monitor.sh[27965]: MPD Monitor Service: MPD Appears to be inactive, restarting Jun 01 19:11:58 volumioamplified mpd_monitor.sh[28563]: mpd: no process found Jun 01 19:11:59 volumioamplified systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 19:11:59 volumioamplified systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 19:11:59 volumioamplified systemd[1]: mpd.service: Deactivated successfully. Jun 01 19:11:59 volumioamplified systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 19:11:59 volumioamplified systemd[1]: mpd.socket: Deactivated successfully. Jun 01 19:11:59 volumioamplified systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 19:11:59 volumioamplified systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 19:11:59 volumioamplified systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 19:11:59 volumioamplified systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 19:11:59 volumioamplified sudo[28569]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 19:11:59 volumioamplified sudo[28569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 19:11:59 volumioamplified sudo[28569]: pam_unix(sudo:session): session closed for user root Jun 01 19:11:59 volumioamplified volumio[28301]: info: go-librespot daemon successfully initialized Jun 01 19:12:02 volumioamplified volumio[28301]: info: Initializing connection to go-librespot Websocket Jun 01 19:12:04 volumioamplified volumiossh-tunnel[28381]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:12:04 volumioamplified autossh[28378]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:12:04 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:04 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:12:04 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 94. Jun 01 19:12:04 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:04 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:04 volumioamplified autossh[28592]: port set to 0, monitoring disabled Jun 01 19:12:04 volumioamplified autossh[28592]: starting ssh (count 1) Jun 01 19:12:04 volumioamplified autossh[28592]: ssh child pid is 28595 Jun 01 19:12:07 volumioamplified go-librespot[28527]: time="2026-06-01T19:12:07+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" Jun 01 19:12:07 volumioamplified systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:07 volumioamplified systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 19:12:07 volumioamplified volumio[28301]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 01 19:12:08 volumioamplified volumio[28301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 01 19:12:09 volumioamplified mpd[28577]: 2026-06-01T19:12:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 19:12:09 volumioamplified systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 19:12:09 volumioamplified mpd_monitor.sh[27965]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 01 19:12:09 volumioamplified sudo[28455]: pam_unix(sudo:session): session closed for user root Jun 01 19:12:09 volumioamplified sudo[28445]: pam_unix(sudo:session): session closed for user root Jun 01 19:12:09 volumioamplified volumio[28301]: info: Completed starting Core Plugins Jun 01 19:12:09 volumioamplified volumio[28301]: info: ------------------------------------------- Jun 01 19:12:09 volumioamplified volumio[28301]: info: ----- MyVolumio plugins startup ---- Jun 01 19:12:09 volumioamplified volumio[28301]: info: ------------------------------------------- Jun 01 19:12:09 volumioamplified volumio[28301]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 01 19:12:10 volumioamplified volumio[28301]: error: MPD error: The expression evaluated to a falsy value: Jun 01 19:12:10 volumioamplified volumio[28301]: assert.ok(self.idling) Jun 01 19:12:10 volumioamplified volumio[28301]: error: The expression evaluated to a falsy value: Jun 01 19:12:10 volumioamplified volumio[28301]: assert.ok(self.idling) Jun 01 19:12:10 volumioamplified volumio[28301]: info: MPD running with PID28577 Jun 01 19:12:10 volumioamplified volumio[28301]: ,establishing connection Jun 01 19:12:10 volumioamplified volumio[28301]: error: MPD error: The expression evaluated to a falsy value: Jun 01 19:12:10 volumioamplified volumio[28301]: assert.ok(self.idling) Jun 01 19:12:10 volumioamplified volumio[28301]: error: The expression evaluated to a falsy value: Jun 01 19:12:10 volumioamplified volumio[28301]: assert.ok(self.idling) Jun 01 19:12:10 volumioamplified volumio[28301]: error: updateQueue error: null Jun 01 19:12:10 volumioamplified systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jun 01 19:12:10 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:10 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:10 volumioamplified go-librespot[28615]: go-librespot daemon starting... Jun 01 19:12:10 volumioamplified go-librespot[28616]: time="2026-06-01T19:12:10+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:12:10 volumioamplified go-librespot[28616]: time="2026-06-01T19:12:10+01:00" level=debug msg="app state loaded" Jun 01 19:12:10 volumioamplified go-librespot[28616]: time="2026-06-01T19:12:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 19:12:12 volumioamplified sudo[28625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jun 01 19:12:12 volumioamplified sudo[28625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:12:12 volumioamplified sudo[28628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jun 01 19:12:12 volumioamplified sudo[28628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:12:12 volumioamplified systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jun 01 19:12:12 volumioamplified systemd[1]: mpd_monitor.service: Deactivated successfully. Jun 01 19:12:12 volumioamplified systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jun 01 19:12:13 volumioamplified systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jun 01 19:12:13 volumioamplified sudo[28625]: pam_unix(sudo:session): session closed for user root Jun 01 19:12:13 volumioamplified sudo[28628]: pam_unix(sudo:session): session closed for user root Jun 01 19:12:13 volumioamplified volumio[28301]: info: Successfully started MPD Monitor Jun 01 19:12:13 volumioamplified volumio[28301]: info: Successfully started MPD Monitor Jun 01 19:12:13 volumioamplified mpd_monitor.sh[28630]: MPD Monitor Service: Starting MPD Monitor Service Jun 01 19:12:14 volumioamplified volumio[28301]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 01 19:12:16 volumioamplified volumiossh-tunnel[28595]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:12:16 volumioamplified autossh[28592]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:12:16 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:16 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:12:16 volumioamplified volumio[28301]: info: Discovery: adding d923af33-ad10-4aac-afd7-5a3509df35bc Jun 01 19:12:16 volumioamplified volumio[28301]: info: Discovery: Found device VolumioPC Jun 01 19:12:16 volumioamplified volumio[28301]: info: Discovery: Connecting to remote: 192.168.2.195 Jun 01 19:12:16 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 95. Jun 01 19:12:16 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:16 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:16 volumioamplified autossh[28636]: port set to 0, monitoring disabled Jun 01 19:12:16 volumioamplified autossh[28636]: starting ssh (count 1) Jun 01 19:12:16 volumioamplified autossh[28636]: ssh child pid is 28639 Jun 01 19:12:18 volumioamplified volumio[28301]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=0c85fab5088086a494ee9733bdfd44c8" http://updates.volumio.org/downloader-v1/track-device Jun 01 19:12:18 volumioamplified volumio[28301]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 19:12:18 volumioamplified volumio[28301]: Dload Upload Total Spent Left Speed Jun 01 19:12:18 volumioamplified volumio[28301]: [1001B blob data] Jun 01 19:12:18 volumioamplified volumio[28301]: retrying in 5 seconds, trial 0 Jun 01 19:12:18 volumioamplified volumio[28301]: info: Volumio Calling Home Jun 01 19:12:22 volumioamplified go-librespot[28616]: time="2026-06-01T19:12:22+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" Jun 01 19:12:22 volumioamplified systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:22 volumioamplified systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 19:12:25 volumioamplified volumio[28301]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 01 19:12:25 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:12:25 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 19:12:25 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:12:25 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 19:12:25 volumioamplified systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jun 01 19:12:25 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:25 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:25 volumioamplified go-librespot[28661]: go-librespot daemon starting... Jun 01 19:12:26 volumioamplified go-librespot[28662]: time="2026-06-01T19:12:26+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:12:26 volumioamplified go-librespot[28662]: time="2026-06-01T19:12:26+01:00" level=debug msg="app state loaded" Jun 01 19:12:26 volumioamplified go-librespot[28662]: time="2026-06-01T19:12:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 19:12:29 volumioamplified volumiossh-tunnel[28639]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:12:28 volumioamplified autossh[28636]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:12:29 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:29 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:12:29 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 96. Jun 01 19:12:29 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:29 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:29 volumioamplified autossh[28685]: port set to 0, monitoring disabled Jun 01 19:12:29 volumioamplified autossh[28685]: starting ssh (count 1) Jun 01 19:12:29 volumioamplified autossh[28685]: ssh child pid is 28688 Jun 01 19:12:29 volumioamplified volumio[28301]: error: MyVolumio Plugin failed to start in a timely fashion Jun 01 19:12:29 volumioamplified volumio[28301]: [Metrics] CommandRouter: 47s 85.93ms Jun 01 19:12:29 volumioamplified volumio[28301]: info: CoreCommandRouter::volumiosetStartupVolume Jun 01 19:12:29 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 19:12:29 volumioamplified volumio[28301]: info: VolumeController:: Setting startup Volume 20 Jun 01 19:12:29 volumioamplified volumio[28301]: info: VolumeController::SetAlsaVolume20 Jun 01 19:12:29 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 19:12:29 volumioamplified volumio[28301]: info: CoreCommandRouter::Close All Modals sent Jun 01 19:12:29 volumioamplified volumio[28301]: info: CoreCommandRouter::Close All Modals sent Jun 01 19:12:29 volumioamplified volumio[28301]: info: CoreStateMachine::pushState Jun 01 19:12:29 volumioamplified volumio[28301]: info: CorePlayQueue::getTrack 0 Jun 01 19:12:29 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioPushState Jun 01 19:12:30 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 01 19:12:30 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 19:12:30 volumioamplified volumio[28301]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 01 19:12:36 volumioamplified volumio[28301]: info: BOOT COMPLETED Jun 01 19:12:38 volumioamplified go-librespot[28662]: time="2026-06-01T19:12:38+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" Jun 01 19:12:38 volumioamplified systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:38 volumioamplified systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 19:12:39 volumioamplified volumio[28301]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=0c85fab5088086a494ee9733bdfd44c8" http://updates.volumio.org/downloader-v1/track-device Jun 01 19:12:39 volumioamplified volumio[28301]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 19:12:39 volumioamplified volumio[28301]: Dload Upload Total Spent Left Speed Jun 01 19:12:39 volumioamplified volumio[28301]: [922B blob data] Jun 01 19:12:39 volumioamplified volumio[28301]: retrying in 5 seconds, trial 1 Jun 01 19:12:39 volumioamplified volumio[28301]: info: Volumio Calling Home Jun 01 19:12:41 volumioamplified volumiossh-tunnel[28688]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:12:41 volumioamplified volumio[28301]: info: Discovery: adding 5af7ed16-152e-4f53-b1b6-e0b9cd9ed5c4 Jun 01 19:12:41 volumioamplified volumio[28301]: info: Discovery: Found device VolumioAmplified Jun 01 19:12:41 volumioamplified volumio[28301]: info: CoreCommandRouter::volumioGetState Jun 01 19:12:41 volumioamplified volumio[28301]: info: CorePlayQueue::getTrack 0 Jun 01 19:12:41 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:41 volumioamplified autossh[28685]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:12:41 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:12:41 volumioamplified systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jun 01 19:12:41 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:41 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:41 volumioamplified go-librespot[28712]: go-librespot daemon starting... Jun 01 19:12:41 volumioamplified go-librespot[28713]: time="2026-06-01T19:12:41+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:12:41 volumioamplified go-librespot[28713]: time="2026-06-01T19:12:41+01:00" level=debug msg="app state loaded" Jun 01 19:12:41 volumioamplified go-librespot[28713]: time="2026-06-01T19:12:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 19:12:41 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 97. Jun 01 19:12:41 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:41 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:41 volumioamplified autossh[28722]: port set to 0, monitoring disabled Jun 01 19:12:41 volumioamplified autossh[28722]: starting ssh (count 1) Jun 01 19:12:41 volumioamplified autossh[28722]: ssh child pid is 28725 Jun 01 19:12:44 volumioamplified go-librespot[28713]: time="2026-06-01T19:12:44+01:00" level=debug msg="new websocket client" Jun 01 19:12:44 volumioamplified volumio[28301]: info: Connection to go-librespot Websocket established Jun 01 19:12:47 volumioamplified volumio[28301]: info: Getting Spotify volume Jun 01 19:12:53 volumioamplified volumiossh-tunnel[28725]: ssh: Could not resolve hostname eu1.myvolumio.org: Device or resource busy Jun 01 19:12:53 volumioamplified autossh[28722]: ssh exited prematurely with status 255; autossh exiting Jun 01 19:12:53 volumioamplified systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:53 volumioamplified systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 01 19:12:53 volumioamplified go-librespot[28713]: time="2026-06-01T19:12:53+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" Jun 01 19:12:53 volumioamplified systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 19:12:53 volumioamplified systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 19:12:54 volumioamplified volumio[28301]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 19:12:54 volumioamplified volumio[28301]: Error: socket hang up Jun 01 19:12:54 volumioamplified volumio[28301]: at connResetException (node:internal/errors:720:14) Jun 01 19:12:54 volumioamplified volumio[28301]: at Socket.socketOnEnd (node:_http_client:519:23) Jun 01 19:12:54 volumioamplified volumio[28301]: at Socket.emit (node:events:526:35) Jun 01 19:12:54 volumioamplified volumio[28301]: at endReadableNT (node:internal/streams/readable:1376:12) Jun 01 19:12:54 volumioamplified volumio[28301]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jun 01 19:12:54 volumioamplified volumio[28301]: code: 'ECONNRESET', Jun 01 19:12:54 volumioamplified volumio[28301]: response: undefined Jun 01 19:12:54 volumioamplified volumio[28301]: } Jun 01 19:12:54 volumioamplified volumio[28301]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 19:12:54 volumioamplified systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 98. Jun 01 19:12:54 volumioamplified systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:54 volumioamplified systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 01 19:12:54 volumioamplified autossh[28749]: port set to 0, monitoring disabled Jun 01 19:12:54 volumioamplified autossh[28749]: starting ssh (count 1) Jun 01 19:12:54 volumioamplified autossh[28749]: ssh child pid is 28752 Jun 01 19:12:57 volumioamplified systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jun 01 19:12:57 volumioamplified systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:57 volumioamplified go-librespot[28767]: go-librespot daemon starting... Jun 01 19:12:57 volumioamplified systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 19:12:58 volumioamplified go-librespot[28768]: time="2026-06-01T19:12:58+01:00" level=info msg="running go-librespot 0.6.2" Jun 01 19:12:58 volumioamplified go-librespot[28768]: time="2026-06-01T19:12:58+01:00" level=debug msg="app state loaded" Jun 01 19:12:58 volumioamplified sudo[28790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 19:11' Jun 01 19:12:58 volumioamplified sudo[28790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 19:12:58 volumioamplified go-librespot[28768]: time="2026-06-01T19:12:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" 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"