Feb 24 11:20:00 volumio-home volumiossh-tunnel[29423]: ssh: Could not resolve hostname eu10.myvolumio.org: Device or resource busy
Feb 24 11:20:00 volumio-home autossh[29419]: ssh exited prematurely with status 255; autossh exiting
Feb 24 11:20:00 volumio-home systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:20:00 volumio-home systemd[1]: sshtunnel.service: Failed with result 'exit-code'.
Feb 24 11:20:00 volumio-home systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2526.
Feb 24 11:20:00 volumio-home systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:20:00 volumio-home systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:20:00 volumio-home autossh[29569]: port set to 0, monitoring disabled
Feb 24 11:20:00 volumio-home autossh[29569]: starting ssh (count 1)
Feb 24 11:20:00 volumio-home autossh[29569]: ssh child pid is 29572
Feb 24 11:20:02 volumio-home go-librespot[29480]: time="2026-02-24T11:20:02+02: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"
Feb 24 11:20:02 volumio-home systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:20:02 volumio-home systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 24 11:20:04 volumio-home volumio[29285]: error: MyVolumio Plugin failed to start in a timely fashion
Feb 24 11:20:04 volumio-home volumio[29285]: [Metrics] CommandRouter: 31s 737.23ms
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 11:20:04 volumio-home volumio[29285]: info: VolumeController:: Setting startup Volume 30
Feb 24 11:20:04 volumio-home volumio[29285]: info: VolumeController::SetAlsaVolume30
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreCommandRouter::Close All Modals sent
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreCommandRouter::Close All Modals sent
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreStateMachine::pushState
Feb 24 11:20:04 volumio-home volumio[29285]: info: CorePlayQueue::getTrack 0
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 24 11:20:04 volumio-home volumio[29285]: info: CoreCommandRouter::volumioPushState
Feb 24 11:20:04 volumio-home kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error!
Feb 24 11:20:04 volumio-home kernel: hwmon hwmon1: Voltage normalised
Feb 24 11:20:05 volumio-home systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Feb 24 11:20:05 volumio-home systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 24 11:20:05 volumio-home systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 24 11:20:05 volumio-home go-librespot[29577]: go-librespot daemon starting...
Feb 24 11:20:05 volumio-home go-librespot[29578]: time="2026-02-24T11:20:05+02:00" level=info msg="running go-librespot 0.6.2"
Feb 24 11:20:05 volumio-home go-librespot[29578]: time="2026-02-24T11:20:05+02:00" level=debug msg="app state loaded"
Feb 24 11:20:05 volumio-home go-librespot[29578]: time="2026-02-24T11:20:05+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 24 11:20:05 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 24 11:20:05 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 24 11:20:05 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 24 11:20:09 volumio-home kernel: hwmon hwmon1: Undervoltage detected!
Feb 24 11:20:11 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 24 11:20:11 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 11:20:11 volumio-home volumio[29285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 24 11:20:11 volumio-home volumio[29285]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=9f700ba08f1b03667e843bf66e72c48e" http://updates.volumio.org/downloader-v1/track-device
Feb 24 11:20:11 volumio-home volumio[29285]: % Total % Received % Xferd Average Speed Time Time Time Current
Feb 24 11:20:11 volumio-home volumio[29285]: Dload Upload Total Spent Left Speed
Feb 24 11:20:11 volumio-home volumio[29285]: [1.6K blob data]
Feb 24 11:20:11 volumio-home volumio[29285]: retrying in 5 seconds, trial 0
Feb 24 11:20:11 volumio-home volumio[29285]: info: Volumio Calling Home
Feb 24 11:20:11 volumio-home volumio[29285]: info: BOOT COMPLETED
Feb 24 11:20:13 volumio-home kernel: hwmon hwmon1: Voltage normalised
Feb 24 11:20:19 volumio-home volumio[29285]: info: Discovery: adding bcd263bf-67c8-4c99-89bf-514e1b1730d2
Feb 24 11:20:19 volumio-home volumio[29285]: info: Discovery: Found device Volumio Home
Feb 24 11:20:19 volumio-home volumio[29285]: info: CoreCommandRouter::volumioGetState
Feb 24 11:20:19 volumio-home volumio[29285]: info: CorePlayQueue::getTrack 0
Feb 24 11:20:19 volumio-home volumio[29285]: info: Discovery: this is already registered, bcd263bf-67c8-4c99-89bf-514e1b1730d2
Feb 24 11:20:19 volumio-home volumio[29285]: info: Discovery: Found device Volumio Home
Feb 24 11:20:19 volumio-home volumio[29285]: info: CoreCommandRouter::volumioGetState
Feb 24 11:20:19 volumio-home volumio[29285]: info: CorePlayQueue::getTrack 0
Feb 24 11:20:19 volumio-home volumio[29285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Feb 24 11:20:20 volumio-home volumiossh-tunnel[29572]: ssh: Could not resolve hostname eu10.myvolumio.org: Device or resource busy
Feb 24 11:20:20 volumio-home autossh[29569]: ssh exited prematurely with status 255; autossh exiting
Feb 24 11:20:20 volumio-home systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:20:20 volumio-home systemd[1]: sshtunnel.service: Failed with result 'exit-code'.
Feb 24 11:20:20 volumio-home systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2527.
Feb 24 11:20:20 volumio-home systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:20:20 volumio-home systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:20:20 volumio-home autossh[29624]: port set to 0, monitoring disabled
Feb 24 11:20:20 volumio-home autossh[29624]: starting ssh (count 1)
Feb 24 11:20:20 volumio-home autossh[29624]: ssh child pid is 29627
Feb 24 11:20:25 volumio-home go-librespot[29578]: time="2026-02-24T11:20:25+02: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"
Feb 24 11:20:25 volumio-home systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:20:25 volumio-home systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 24 11:20:28 volumio-home systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Feb 24 11:20:28 volumio-home systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 24 11:20:28 volumio-home systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 24 11:20:28 volumio-home go-librespot[29642]: go-librespot daemon starting...
Feb 24 11:20:28 volumio-home go-librespot[29643]: time="2026-02-24T11:20:28+02:00" level=info msg="running go-librespot 0.6.2"
Feb 24 11:20:28 volumio-home go-librespot[29643]: time="2026-02-24T11:20:28+02:00" level=debug msg="app state loaded"
Feb 24 11:20:28 volumio-home go-librespot[29643]: time="2026-02-24T11:20:28+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 24 11:20:39 volumio-home volumio[29285]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io
Feb 24 11:20:40 volumio-home volumiossh-tunnel[29627]: ssh: Could not resolve hostname eu10.myvolumio.org: Device or resource busy
Feb 24 11:20:40 volumio-home autossh[29624]: ssh exited prematurely with status 255; autossh exiting
Feb 24 11:20:40 volumio-home systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:20:40 volumio-home systemd[1]: sshtunnel.service: Failed with result 'exit-code'.
Feb 24 11:20:41 volumio-home systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2528.
Feb 24 11:20:41 volumio-home systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:20:41 volumio-home systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:20:41 volumio-home autossh[29667]: port set to 0, monitoring disabled
Feb 24 11:20:41 volumio-home autossh[29667]: starting ssh (count 1)
Feb 24 11:20:41 volumio-home autossh[29667]: ssh child pid is 29670
Feb 24 11:20:41 volumio-home volumio[29285]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=9f700ba08f1b03667e843bf66e72c48e" http://updates.volumio.org/downloader-v1/track-device
Feb 24 11:20:41 volumio-home volumio[29285]: % Total % Received % Xferd Average Speed Time Time Time Current
Feb 24 11:20:41 volumio-home volumio[29285]: Dload Upload Total Spent Left Speed
Feb 24 11:20:41 volumio-home volumio[29285]: [1.6K blob data]
Feb 24 11:20:41 volumio-home volumio[29285]: retrying in 5 seconds, trial 1
Feb 24 11:20:41 volumio-home volumio[29285]: info: Volumio Calling Home
Feb 24 11:20:48 volumio-home go-librespot[29643]: time="2026-02-24T11:20:48+02: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"
Feb 24 11:20:48 volumio-home systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:20:48 volumio-home systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 24 11:20:51 volumio-home systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Feb 24 11:20:51 volumio-home systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 24 11:20:51 volumio-home systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 24 11:20:51 volumio-home go-librespot[29688]: go-librespot daemon starting...
Feb 24 11:20:51 volumio-home go-librespot[29689]: time="2026-02-24T11:20:51+02:00" level=info msg="running go-librespot 0.6.2"
Feb 24 11:20:51 volumio-home go-librespot[29689]: time="2026-02-24T11:20:51+02:00" level=debug msg="app state loaded"
Feb 24 11:20:51 volumio-home go-librespot[29689]: time="2026-02-24T11:20:51+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 24 11:20:59 volumio-home dhcpcd[825]: eth0: offered 192.168.3.62 from 192.168.3.1
Feb 24 11:20:59 volumio-home go-librespot[29689]: time="2026-02-24T11:20:59+02:00" level=debug msg="new websocket client"
Feb 24 11:20:59 volumio-home volumio[29285]: info: Connection to go-librespot Websocket established
Feb 24 11:21:01 volumio-home volumiossh-tunnel[29670]: ssh: Could not resolve hostname eu10.myvolumio.org: Device or resource busy
Feb 24 11:21:01 volumio-home autossh[29667]: ssh exited prematurely with status 255; autossh exiting
Feb 24 11:21:01 volumio-home systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:21:01 volumio-home systemd[1]: sshtunnel.service: Failed with result 'exit-code'.
Feb 24 11:21:01 volumio-home systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2529.
Feb 24 11:21:01 volumio-home systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:21:01 volumio-home systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Feb 24 11:21:01 volumio-home autossh[29714]: port set to 0, monitoring disabled
Feb 24 11:21:01 volumio-home autossh[29714]: starting ssh (count 1)
Feb 24 11:21:01 volumio-home autossh[29714]: ssh child pid is 29717
Feb 24 11:21:02 volumio-home volumio[29285]: info: Getting Spotify volume
Feb 24 11:21:11 volumio-home volumio[29285]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=9f700ba08f1b03667e843bf66e72c48e" http://updates.volumio.org/downloader-v1/track-device
Feb 24 11:21:11 volumio-home volumio[29285]: % Total % Received % Xferd Average Speed Time Time Time Current
Feb 24 11:21:11 volumio-home volumio[29285]: Dload Upload Total Spent Left Speed
Feb 24 11:21:11 volumio-home volumio[29285]: [1.6K blob data]
Feb 24 11:21:11 volumio-home volumio[29285]: retrying in 5 seconds, trial 2
Feb 24 11:21:11 volumio-home volumio[29285]: info: Volumio Calling Home
Feb 24 11:21:11 volumio-home dhcpcd[825]: eth0: probing address 192.168.3.62/24
Feb 24 11:21:11 volumio-home go-librespot[29689]: time="2026-02-24T11:21:11+02: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"
Feb 24 11:21:11 volumio-home systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 24 11:21:11 volumio-home systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 24 11:21:11 volumio-home volumio[29285]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 24 11:21:11 volumio-home volumio[29285]: Error: socket hang up
Feb 24 11:21:11 volumio-home volumio[29285]: at connResetException (node:internal/errors:720:14)
Feb 24 11:21:11 volumio-home volumio[29285]: at Socket.socketOnEnd (node:_http_client:519:23)
Feb 24 11:21:11 volumio-home volumio[29285]: at Socket.emit (node:events:526:35)
Feb 24 11:21:11 volumio-home volumio[29285]: at endReadableNT (node:internal/streams/readable:1376:12)
Feb 24 11:21:11 volumio-home volumio[29285]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Feb 24 11:21:11 volumio-home volumio[29285]: code: 'ECONNRESET',
Feb 24 11:21:11 volumio-home volumio[29285]: response: undefined
Feb 24 11:21:11 volumio-home volumio[29285]: }
Feb 24 11:21:11 volumio-home volumio[29285]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 24 11:21:12 volumio-home sudo[29750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 11:20'
Feb 24 11:21:12 volumio-home sudo[29750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026"
VOLUMIO_VERSION="4.096"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"