Mar 28 12:11:00 volumio volumio[2820644]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 28 12:11:00 volumio volumio[2820644]: info: Initializing connection to go-librespot Websocket Mar 28 12:11:04 volumio volumio[2820644]: info: Cannot mount NAS 1 at system boot, trial number 4 ,retrying in 5 seconds Mar 28 12:11:09 volumio volumio[2820644]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Mar 28 12:11:14 volumio volumio[2820644]: error: MyVolumio Plugin failed to start in a timely fashion Mar 28 12:11:14 volumio volumio[2820644]: [Metrics] CommandRouter: 26s 660.41ms Mar 28 12:11:14 volumio volumio[2820644]: info: CoreCommandRouter::volumiosetStartupVolume Mar 28 12:11:14 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 12:11:14 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 12:11:14 volumio volumio[2820644]: info: CoreCommandRouter::Close All Modals sent Mar 28 12:11:14 volumio volumio[2820644]: info: CoreCommandRouter::Close All Modals sent Mar 28 12:11:14 volumio volumio[2820644]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 28 12:11:14 volumio volumio[2820644]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for II Mar 28 12:11:14 volumio volumio[2820644]: aplay: main:831: audio open error: No such device Mar 28 12:11:15 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 28 12:11:15 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 12:11:16 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 28 12:11:16 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 28 12:11:21 volumio volumio[2820644]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Mar 28 12:11:21 volumio volumio[2820644]: info: BOOT COMPLETED Mar 28 12:11:22 volumio go-librespot[2820838]: time="2026-03-28T12:11:22+08: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" Mar 28 12:11:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:11:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:11:23 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 12:11:23 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 12:11:23 volumio volumio[2820644]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 12:11:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 28 12:11:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 12:11:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 12:11:25 volumio go-librespot[2820994]: go-librespot daemon starting... Mar 28 12:11:25 volumio go-librespot[2820995]: time="2026-03-28T12:11:25+08:00" level=info msg="running go-librespot 0.6.2" Mar 28 12:11:25 volumio go-librespot[2820995]: time="2026-03-28T12:11:25+08:00" level=debug msg="app state loaded" Mar 28 12:11:25 volumio go-librespot[2820995]: time="2026-03-28T12:11:25+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 12:11:31 volumio volumio[2820644]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.073&uuid=4b2e161e3770e8f64c919f483176efdf" http://updates.volumio.org/downloader-v1/track-device Mar 28 12:11:31 volumio volumio[2820644]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 28 12:11:31 volumio volumio[2820644]: Dload Upload Total Spent Left Speed Mar 28 12:11:31 volumio volumio[2820644]: [2.2K blob data] Mar 28 12:11:31 volumio volumio[2820644]: retrying in 5 seconds, trial 0 Mar 28 12:11:31 volumio volumio[2820644]: info: Volumio Calling Home Mar 28 12:11:53 volumio go-librespot[2820995]: time="2026-03-28T12:11:53+08: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" Mar 28 12:11:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:11:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:11:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 28 12:11:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 12:11:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 12:11:56 volumio go-librespot[2821085]: go-librespot daemon starting... Mar 28 12:11:56 volumio go-librespot[2821086]: time="2026-03-28T12:11:56+08:00" level=info msg="running go-librespot 0.6.2" Mar 28 12:11:56 volumio go-librespot[2821086]: time="2026-03-28T12:11:56+08:00" level=debug msg="app state loaded" Mar 28 12:11:56 volumio go-librespot[2821086]: time="2026-03-28T12:11:56+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 12:12:09 volumio volumio[2820644]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.073&uuid=4b2e161e3770e8f64c919f483176efdf" http://updates.volumio.org/downloader-v1/track-device Mar 28 12:12:09 volumio volumio[2820644]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 28 12:12:09 volumio volumio[2820644]: Dload Upload Total Spent Left Speed Mar 28 12:12:09 volumio volumio[2820644]: [2.2K blob data] Mar 28 12:12:09 volumio volumio[2820644]: retrying in 5 seconds, trial 1 Mar 28 12:12:09 volumio volumio[2820644]: info: Volumio Calling Home Mar 28 12:12:24 volumio go-librespot[2821086]: time="2026-03-28T12:12:24+08: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" Mar 28 12:12:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:12:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:12:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 28 12:12:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 12:12:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 12:12:27 volumio go-librespot[2821179]: go-librespot daemon starting... Mar 28 12:12:28 volumio go-librespot[2821180]: time="2026-03-28T12:12:28+08:00" level=info msg="running go-librespot 0.6.2" Mar 28 12:12:28 volumio go-librespot[2821180]: time="2026-03-28T12:12:28+08:00" level=debug msg="app state loaded" Mar 28 12:12:28 volumio go-librespot[2821180]: time="2026-03-28T12:12:28+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 12:12:40 volumio volumio[2820644]: info: Discovery: adding e6a18189-e241-4e69-86f9-e226835a4be3 Mar 28 12:12:40 volumio volumio[2820644]: info: Discovery: Found device Volumio Mar 28 12:12:40 volumio volumio[2820644]: info: CoreCommandRouter::volumioGetState Mar 28 12:12:40 volumio volumio[2820644]: info: CorePlayQueue::getTrack 0 Mar 28 12:12:40 volumio go-librespot[2821180]: time="2026-03-28T12:12:40+08:00" level=debug msg="new websocket client" Mar 28 12:12:40 volumio volumio[2820644]: info: Connection to go-librespot Websocket established Mar 28 12:12:43 volumio volumio[2820644]: info: Getting Spotify volume Mar 28 12:12:45 volumio volumio[2820644]: info: Discovery: this is already registered, e6a18189-e241-4e69-86f9-e226835a4be3 Mar 28 12:12:45 volumio volumio[2820644]: info: Discovery: Found device Volumio Mar 28 12:12:45 volumio volumio[2820644]: info: CoreCommandRouter::volumioGetState Mar 28 12:12:45 volumio volumio[2820644]: info: CorePlayQueue::getTrack 0 Mar 28 12:12:48 volumio volumio[2820644]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.073&uuid=4b2e161e3770e8f64c919f483176efdf" http://updates.volumio.org/downloader-v1/track-device Mar 28 12:12:48 volumio volumio[2820644]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 28 12:12:48 volumio volumio[2820644]: Dload Upload Total Spent Left Speed Mar 28 12:12:48 volumio volumio[2820644]: [2.2K blob data] Mar 28 12:12:48 volumio volumio[2820644]: retrying in 5 seconds, trial 2 Mar 28 12:12:48 volumio volumio[2820644]: info: Volumio Calling Home Mar 28 12:12:56 volumio go-librespot[2821180]: time="2026-03-28T12:12:56+08: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" Mar 28 12:12:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:12:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:12:56 volumio volumio[2820644]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 12:12:56 volumio volumio[2820644]: Error: socket hang up Mar 28 12:12:56 volumio volumio[2820644]: at connResetException (node:internal/errors:720:14) Mar 28 12:12:56 volumio volumio[2820644]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 28 12:12:56 volumio volumio[2820644]: at Socket.emit (node:events:526:35) Mar 28 12:12:56 volumio volumio[2820644]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 28 12:12:56 volumio volumio[2820644]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 28 12:12:56 volumio volumio[2820644]: code: 'ECONNRESET', Mar 28 12:12:56 volumio volumio[2820644]: response: undefined Mar 28 12:12:56 volumio volumio[2820644]: } Mar 28 12:12:56 volumio volumio[2820644]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 12:12:56 volumio sudo[2821281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 12:11' Mar 28 12:12:56 volumio sudo[2821281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"