-- Logs begin at Mon 2025-06-02 12:09:05 CEST, end at Mon 2025-06-02 18:53:36 CEST. -- Jun 02 18:52:02 garaz-volumio volumio[7982]: error: MyVolumio Plugin failed to start in a timely fashion Jun 02 18:52:02 garaz-volumio volumio[7982]: [Metrics] CommandRouter: 44s 269.44ms Jun 02 18:52:02 garaz-volumio volumio[7982]: info: CoreCommandRouter::volumiosetStartupVolume Jun 02 18:52:02 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:52:02 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 18:52:02 garaz-volumio volumio[7982]: info: CoreCommandRouter::Close All Modals sent Jun 02 18:52:02 garaz-volumio volumio[7982]: info: CoreCommandRouter::Close All Modals sent Jun 02 18:52:03 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 02 18:52:03 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 18:52:03 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 02 18:52:08 garaz-volumio go-librespot[8222]: time="2025-06-02T18:52:08+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" Jun 02 18:52:08 garaz-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:52:08 garaz-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 18:52:09 garaz-volumio volumio[7982]: info: BOOT COMPLETED Jun 02 18:52:09 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 18:52:09 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 18:52:09 garaz-volumio volumio[7982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 18:52:11 garaz-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 18:52:11 garaz-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jun 02 18:52:11 garaz-volumio systemd[1]: Stopped go-librespot Daemon. Jun 02 18:52:11 garaz-volumio systemd[1]: Started go-librespot Daemon. Jun 02 18:52:11 garaz-volumio go-librespot[8314]: Librespot-go daemon starting... Jun 02 18:52:11 garaz-volumio go-librespot[8314]: time="2025-06-02T18:52:11+02:00" level=info msg="generated new device id: fd2ca34f95c860bb980c41c2d65dc07bde6458ee" Jun 02 18:52:11 garaz-volumio go-librespot[8314]: time="2025-06-02T18:52:11+02:00" level=debug msg="stored credentials found for 31ykrfcoejvq3eri6uosxaghgvou" Jun 02 18:52:17 garaz-volumio volumio[7982]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.812&uuid=70c06b48de3d14bcf18d3b7865feb41e" http://updates.volumio.org/downloader-v1/track-device Jun 02 18:52:17 garaz-volumio volumio[7982]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 02 18:52:17 garaz-volumio volumio[7982]: Dload Upload Total Spent Left Speed Jun 02 18:52:17 garaz-volumio volumio[7982]: [2.2K blob data] Jun 02 18:52:17 garaz-volumio volumio[7982]: retrying in 5 seconds, trial 0 Jun 02 18:52:17 garaz-volumio volumio[7982]: info: Volumio Calling Home Jun 02 18:52:39 garaz-volumio volumio[7982]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Jun 02 18:52:39 garaz-volumio volumio[7982]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Jun 02 18:52:39 garaz-volumio go-librespot[8314]: time="2025-06-02T18:52:39+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" Jun 02 18:52:39 garaz-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:52:39 garaz-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 18:52:43 garaz-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 18:52:43 garaz-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jun 02 18:52:43 garaz-volumio systemd[1]: Stopped go-librespot Daemon. Jun 02 18:52:43 garaz-volumio systemd[1]: Started go-librespot Daemon. Jun 02 18:52:43 garaz-volumio go-librespot[8426]: Librespot-go daemon starting... Jun 02 18:52:43 garaz-volumio go-librespot[8426]: time="2025-06-02T18:52:43+02:00" level=info msg="generated new device id: 04459fc53f38bbb3f9cc95be17f80ac696b7588a" Jun 02 18:52:43 garaz-volumio go-librespot[8426]: time="2025-06-02T18:52:43+02:00" level=debug msg="stored credentials found for 31ykrfcoejvq3eri6uosxaghgvou" Jun 02 18:52:56 garaz-volumio volumio[7982]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.812&uuid=70c06b48de3d14bcf18d3b7865feb41e" http://updates.volumio.org/downloader-v1/track-device Jun 02 18:52:56 garaz-volumio volumio[7982]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 02 18:52:56 garaz-volumio volumio[7982]: Dload Upload Total Spent Left Speed Jun 02 18:52:56 garaz-volumio volumio[7982]: [2.2K blob data] Jun 02 18:52:56 garaz-volumio volumio[7982]: retrying in 5 seconds, trial 1 Jun 02 18:52:56 garaz-volumio volumio[7982]: info: Volumio Calling Home Jun 02 18:53:07 garaz-volumio volumio[7982]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 02 18:53:11 garaz-volumio go-librespot[8426]: time="2025-06-02T18:53: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" Jun 02 18:53:11 garaz-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:53:11 garaz-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 18:53:14 garaz-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 18:53:14 garaz-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jun 02 18:53:14 garaz-volumio systemd[1]: Stopped go-librespot Daemon. Jun 02 18:53:14 garaz-volumio systemd[1]: Started go-librespot Daemon. Jun 02 18:53:14 garaz-volumio go-librespot[8486]: Librespot-go daemon starting... Jun 02 18:53:14 garaz-volumio go-librespot[8486]: time="2025-06-02T18:53:14+02:00" level=info msg="generated new device id: 46baad4a55892bf7aecdaaf7226aa2d0eb13ca98" Jun 02 18:53:14 garaz-volumio go-librespot[8486]: time="2025-06-02T18:53:14+02:00" level=debug msg="stored credentials found for 31ykrfcoejvq3eri6uosxaghgvou" Jun 02 18:53:34 garaz-volumio volumio[7982]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.812&uuid=70c06b48de3d14bcf18d3b7865feb41e" http://updates.volumio.org/downloader-v1/track-device Jun 02 18:53:34 garaz-volumio volumio[7982]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 02 18:53:34 garaz-volumio volumio[7982]: Dload Upload Total Spent Left Speed Jun 02 18:53:34 garaz-volumio volumio[7982]: [2.2K blob data] Jun 02 18:53:34 garaz-volumio volumio[7982]: retrying in 5 seconds, trial 2 Jun 02 18:53:34 garaz-volumio volumio[7982]: info: Volumio Calling Home Jun 02 18:53:35 garaz-volumio volumio[7982]: info: Discovery: adding 61c94b53-f720-4c34-be67-0f6ba4676c48 Jun 02 18:53:35 garaz-volumio volumio[7982]: info: Discovery: Found device garaz-volumio Jun 02 18:53:35 garaz-volumio volumio[7982]: info: CoreCommandRouter::volumioGetState Jun 02 18:53:35 garaz-volumio volumio[7982]: info: CorePlayQueue::getTrack 0 Jun 02 18:53:35 garaz-volumio volumio[7982]: 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 02 18:53:35 garaz-volumio volumio[7982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 18:53:35 garaz-volumio volumio[7982]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jun 02 18:53:35 garaz-volumio volumio[7982]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 02 18:53:35 garaz-volumio volumio[7982]: errno: -3007, Jun 02 18:53:35 garaz-volumio volumio[7982]: code: 'ENOTFOUND', Jun 02 18:53:35 garaz-volumio volumio[7982]: syscall: 'getaddrinfo', Jun 02 18:53:35 garaz-volumio volumio[7982]: hostname: 'ws.audioscrobbler.com' Jun 02 18:53:35 garaz-volumio volumio[7982]: } Jun 02 18:53:35 garaz-volumio volumio[7982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 18:53:36 garaz-volumio sudo[8579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-02 18:52 Jun 02 18:53:36 garaz-volumio sudo[8579]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"