Jun 01 11:44:03 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:44:04 dac volumio[12225]: error: [now-playing] Error fetching https://api.openweathermap.org/data/3.0/onecall?appid=c6805d37048db94b1c6df968049295d5&lat=51.34906670095337&lon=0.6601020254019067&units=metric: TypeError: fetch failed Jun 01 11:44:04 dac volumio[12225]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Jun 01 11:44:04 dac volumio[12225]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 11:44:04 dac volumio[12225]: at async fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:26:26) Jun 01 11:44:04 dac volumio[12225]: at async Promise.all (index 0) Jun 01 11:44:04 dac volumio[12225]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:91:24) Jun 01 11:44:04 dac volumio[12225]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:104:45) Jun 01 11:44:04 dac volumio[12225]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:301:25 Jun 01 11:44:04 dac volumio[12225]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): fetch failed Jun 01 11:44:04 dac volumio[12225]: error: [now-playing] Error fetching https://api.openweathermap.org/data/2.5/weather?appid=c6805d37048db94b1c6df968049295d5&lat=51.34906670095337&lon=0.6601020254019067&units=metric: TypeError: fetch failed Jun 01 11:44:04 dac volumio[12225]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Jun 01 11:44:04 dac volumio[12225]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 11:44:04 dac volumio[12225]: at async fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:26:26) Jun 01 11:44:04 dac volumio[12225]: at async Promise.all (index 1) Jun 01 11:44:07 dac volumio[12225]: info: Discovery: adding 8d39747c-6999-4851-9c27-976b2f160fae Jun 01 11:44:07 dac volumio[12225]: info: Discovery: Found device DAC Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::volumioGetState Jun 01 11:44:07 dac volumio[12225]: info: CorePlayQueue::getTrack 0 Jun 01 11:44:07 dac volumio[12225]: info: Discovery: this is already registered, 8d39747c-6999-4851-9c27-976b2f160fae Jun 01 11:44:07 dac volumio[12225]: info: Discovery: Found device DAC Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::volumioGetState Jun 01 11:44:07 dac volumio[12225]: info: CorePlayQueue::getTrack 0 Jun 01 11:44:07 dac volumio[12225]: error: MyVolumio Plugin failed to start in a timely fashion Jun 01 11:44:07 dac volumio[12225]: [Metrics] CommandRouter: 32s 358.88ms Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::volumiosetStartupVolume Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:44:07 dac volumio[12225]: info: VolumeController:: Setting startup Volume 55 Jun 01 11:44:07 dac volumio[12225]: info: VolumeController::SetAlsaVolume55 Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::Close All Modals sent Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::Close All Modals sent Jun 01 11:44:07 dac volumio[12225]: info: CoreStateMachine::pushState Jun 01 11:44:07 dac volumio[12225]: info: CorePlayQueue::getTrack 0 Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 11:44:07 dac volumio[12225]: info: CoreCommandRouter::volumioPushState Jun 01 11:44:07 dac volumio[12225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 01 11:44:08 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 01 11:44:08 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 11:44:08 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 01 11:44:10 dac go-librespot[12552]: time="2026-06-01T11:44: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 11:44:10 dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 11:44:10 dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 11:44:11 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:44:13 dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jun 01 11:44:13 dac systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:44:13 dac systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:44:13 dac go-librespot[12663]: go-librespot daemon starting... Jun 01 11:44:13 dac go-librespot[12665]: time="2026-06-01T11:44:13+01:00" level=info msg="running go-librespot 0.7.1" Jun 01 11:44:13 dac go-librespot[12665]: time="2026-06-01T11:44:13+01:00" level=debug msg="app state loaded" Jun 01 11:44:13 dac go-librespot[12665]: time="2026-06-01T11:44:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 11:44:13 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 11:44:13 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:44:13 dac volumio[12225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 11:44:14 dac volumio[12225]: info: BOOT COMPLETED Jun 01 11:44:17 dac volumio[12225]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=a6dc3a8a986337449d1fd1a88f3e1112" http://updates.volumio.org/downloader-v1/track-device Jun 01 11:44:17 dac volumio[12225]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 11:44:17 dac volumio[12225]: Dload Upload Total Spent Left Speed Jun 01 11:44:17 dac volumio[12225]: [1.9K blob data] Jun 01 11:44:17 dac volumio[12225]: retrying in 5 seconds, trial 0 Jun 01 11:44:17 dac volumio[12225]: info: Volumio Calling Home Jun 01 11:44:19 dac volumiossh-tunnel[12637]: ssh: Could not resolve hostname eu3.myvolumio.org: Device or resource busy Jun 01 11:44:19 dac autossh[14785]: ssh exited with error status 255; restarting ssh Jun 01 11:44:19 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:44:27 dac autossh[14785]: starting ssh (count 19) Jun 01 11:44:27 dac autossh[14785]: ssh child pid is 12710 Jun 01 11:44:28 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:44:32 dac volumio[12225]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 01 11:44:36 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:44:38 dac go-librespot[12665]: time="2026-06-01T11:44: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 11:44:38 dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 11:44:38 dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 11:44:41 dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jun 01 11:44:41 dac systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:44:41 dac systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:44:41 dac go-librespot[12729]: go-librespot daemon starting... Jun 01 11:44:41 dac go-librespot[12730]: time="2026-06-01T11:44:41+01:00" level=info msg="running go-librespot 0.7.1" Jun 01 11:44:41 dac go-librespot[12730]: time="2026-06-01T11:44:41+01:00" level=debug msg="app state loaded" Jun 01 11:44:41 dac go-librespot[12730]: time="2026-06-01T11:44:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 11:44:44 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:44:50 dac volumiossh-tunnel[12710]: ssh: Could not resolve hostname eu3.myvolumio.org: Device or resource busy Jun 01 11:44:50 dac autossh[14785]: ssh exited with error status 255; restarting ssh Jun 01 11:44:51 dac volumio[12225]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=a6dc3a8a986337449d1fd1a88f3e1112" http://updates.volumio.org/downloader-v1/track-device Jun 01 11:44:51 dac volumio[12225]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 11:44:51 dac volumio[12225]: Dload Upload Total Spent Left Speed Jun 01 11:44:51 dac volumio[12225]: [1.9K blob data] Jun 01 11:44:51 dac volumio[12225]: retrying in 5 seconds, trial 1 Jun 01 11:44:51 dac volumio[12225]: info: Volumio Calling Home Jun 01 11:44:52 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:45:00 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:45:06 dac go-librespot[12730]: time="2026-06-01T11:45:06+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 11:45:06 dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 11:45:06 dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 11:45:08 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:45:08 dac autossh[14785]: starting ssh (count 20) Jun 01 11:45:08 dac autossh[14785]: ssh child pid is 12784 Jun 01 11:45:09 dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jun 01 11:45:09 dac systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:45:09 dac systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:45:09 dac go-librespot[12785]: go-librespot daemon starting... Jun 01 11:45:09 dac go-librespot[12786]: time="2026-06-01T11:45:09+01:00" level=info msg="running go-librespot 0.7.1" Jun 01 11:45:09 dac go-librespot[12786]: time="2026-06-01T11:45:09+01:00" level=debug msg="app state loaded" Jun 01 11:45:09 dac go-librespot[12786]: time="2026-06-01T11:45:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 11:45:16 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:45:22 dac go-librespot[12786]: time="2026-06-01T11:45:22+01:00" level=debug msg="new websocket client" Jun 01 11:45:22 dac volumio[12225]: info: Connection to go-librespot Websocket established Jun 01 11:45:25 dac volumio[12225]: info: Getting Spotify volume Jun 01 11:45:25 dac volumio[12225]: error: [MQTT] MQTT connection error: Error: connect EHOSTUNREACH 192.168.1.11:1883 Jun 01 11:45:25 dac volumio[12225]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=a6dc3a8a986337449d1fd1a88f3e1112" http://updates.volumio.org/downloader-v1/track-device Jun 01 11:45:25 dac volumio[12225]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 11:45:25 dac volumio[12225]: Dload Upload Total Spent Left Speed Jun 01 11:45:25 dac volumio[12225]: [2.0K blob data] Jun 01 11:45:25 dac volumio[12225]: retrying in 5 seconds, trial 2 Jun 01 11:45:25 dac volumio[12225]: info: Volumio Calling Home Jun 01 11:45:31 dac volumiossh-tunnel[12784]: ssh: Could not resolve hostname eu3.myvolumio.org: Device or resource busy Jun 01 11:45:31 dac autossh[14785]: ssh exited with error status 255; restarting ssh Jun 01 11:45:32 dac go-librespot[12786]: time="2026-06-01T11:45:32+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 11:45:32 dac volumio[12225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 11:45:32 dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 11:45:32 dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 11:45:32 dac volumio[12225]: Error: socket hang up Jun 01 11:45:32 dac volumio[12225]: at connResetException (node:internal/errors:720:14) Jun 01 11:45:32 dac volumio[12225]: at Socket.socketOnEnd (node:_http_client:519:23) Jun 01 11:45:32 dac volumio[12225]: at Socket.emit (node:events:526:35) Jun 01 11:45:32 dac volumio[12225]: at endReadableNT (node:internal/streams/readable:1376:12) Jun 01 11:45:32 dac volumio[12225]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jun 01 11:45:32 dac volumio[12225]: code: 'ECONNRESET', Jun 01 11:45:32 dac volumio[12225]: response: undefined Jun 01 11:45:32 dac volumio[12225]: } Jun 01 11:45:32 dac volumio[12225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 11:45:32 dac sudo[12841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 11:44' Jun 01 11:45:32 dac sudo[12841]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"