Feb 12 20:57:04 volumio go-librespot[6152]: time="2026-02-12T20:57:04+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\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Feb 12 20:57:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:57:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:57:05 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:05.239+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%07 @ 0x20cf500" latency=-893.571374ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Feb 12 20:57:05 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:05.338+02:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="00:00:00:00:00:00%07 @ 0x20cf500" latency=-845.637926ms timeout=1m0s Feb 12 20:57:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 12 20:57:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:57:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:57:07 volumio go-librespot[6277]: go-librespot daemon starting... Feb 12 20:57:07 volumio go-librespot[6278]: time="2026-02-12T20:57:07+02:00" level=info msg="running go-librespot 0.6.2" Feb 12 20:57:07 volumio go-librespot[6278]: time="2026-02-12T20:57:07+02:00" level=debug msg="app state loaded" Feb 12 20:57:07 volumio go-librespot[6278]: time="2026-02-12T20:57:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:57:09 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:09.001+02:00 level=INFO msg="emitting wifi scan event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" networks=3 Feb 12 20:57:09 volumio volumio[5411]: info: Received Get System Info Feb 12 20:57:09 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:57:09 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:57:09 volumio volumio[5411]: info: Discovery: Getting this device information Feb 12 20:57:09 volumio volumio[5411]: info: CoreCommandRouter::volumioGetState Feb 12 20:57:09 volumio volumio[5411]: info: CorePlayQueue::getTrack 0 Feb 12 20:57:09 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:57:09 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:09.508+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" available=true connected=true macAddress=2c:cf:67:b8:99:78 ip4Address=192.168.0.75/24 ip6Address= ssid=UPC27762C4 Feb 12 20:57:09 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:57:09 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:57:14 volumio volumio[5411]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Feb 12 20:57:37 volumio go-librespot[6278]: time="2026-02-12T20:57:37+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\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Feb 12 20:57:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:57:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:57:38 volumio volumio[5411]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=4967436427be55415e5d17bd8d490271" http://updates.volumio.org/downloader-v1/track-device Feb 12 20:57:38 volumio volumio[5411]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 12 20:57:38 volumio volumio[5411]: Dload Upload Total Spent Left Speed Feb 12 20:57:38 volumio volumio[5411]: [4.4K blob data] Feb 12 20:57:38 volumio volumio[5411]: retrying in 5 seconds, trial 1 Feb 12 20:57:38 volumio volumio[5411]: info: Volumio Calling Home Feb 12 20:57:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 12 20:57:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:57:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:57:40 volumio go-librespot[6422]: go-librespot daemon starting... Feb 12 20:57:40 volumio go-librespot[6423]: time="2026-02-12T20:57:40+02:00" level=info msg="running go-librespot 0.6.2" Feb 12 20:57:40 volumio go-librespot[6423]: time="2026-02-12T20:57:40+02:00" level=debug msg="app state loaded" Feb 12 20:57:40 volumio go-librespot[6423]: time="2026-02-12T20:57:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.320+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="00:00:00:00:00:00%07 @ 0x20cf500" latency=-890.937591ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.379+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%07 @ 0x20cf500" latency=-890.405689ms timeout=10s Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.379+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" Feb 12 20:57:58 volumio volumio[5411]: info: Received Get System Info Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:57:58 volumio volumio[5411]: info: Discovery: Getting this device information Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::volumioGetState Feb 12 20:57:58 volumio volumio[5411]: info: CorePlayQueue::getTrack 0 Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.471+02:00 level=INFO msg="emitting device name changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" name=Volumio Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.529+02:00 level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" language=en Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.620+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" timezone=Europe/Bucharest Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.679+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" available=true connected=false macAddress= ip4Address= ip6Address= Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.744+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" available=true connected=true macAddress=2c:cf:67:b8:99:78 ip4Address=192.168.0.75/24 ip6Address= ssid=UPC27762C4 Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.829+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" setupComplete=true Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:57:58 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 12 20:57:58 volumio volumio[5411]: amixer -c 0 info | grep "vc4-hdmi-0" Feb 12 20:57:58 volumio volumio[5411]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0' Feb 12 20:57:58 volumio volumio[5411]: amixer -c 1 info | grep "vc4-hdmi-1" Feb 12 20:57:58 volumio volumio[5411]: Card sysdefault:1 'vc4hdmi1'/'vc4-hdmi-1' Feb 12 20:57:58 volumio volumio[5411]: amixer -c 2 info | grep "IQaudIODAC" Feb 12 20:57:58 volumio volumio[5411]: Card sysdefault:2 'IQaudIODAC'/'IQaudIODAC' Feb 12 20:57:58 volumio volumio[5411]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 12 20:57:58 volumio volumio[5411]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 12 20:57:58 volumio volumio[5411]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 12 20:57:58 volumio volumio[5411]: amixer -c 2 info | grep "IQaudIO DAC Plus" Feb 12 20:57:58 volumio volumio5-onboarding[1794]: time=2026-02-12T20:57:58.997+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" selectedOutputId=2 Feb 12 20:58:02 volumio volumio[5411]: info: CoreCommandRouter::volumioGetState Feb 12 20:58:02 volumio volumio[5411]: info: CorePlayQueue::getTrack 0 Feb 12 20:58:02 volumio volumio5-onboarding[1794]: time=2026-02-12T20:58:02.072+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" state=STATUS_STOPPED positionMs=120638 volume=35 Feb 12 20:58:02 volumio volumio5-onboarding[1794]: time=2026-02-12T20:58:02.129+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" id="mnt/USB/Muzica/NOU/REVEDERE 2025/Muzica/02 Drive.mp3" title="2 - Drive" Feb 12 20:58:07 volumio volumio5-onboarding[1794]: time=2026-02-12T20:58:07.400+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%07 @ 0x20cf500" latency=-806.421061ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Feb 12 20:58:07 volumio volumio5-onboarding[1794]: time=2026-02-12T20:58:07.529+02:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="00:00:00:00:00:00%07 @ 0x20cf500" latency=-817.23734ms timeout=1m0s Feb 12 20:58:09 volumio volumio5-onboarding[1794]: time=2026-02-12T20:58:09.080+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%07 @ 0x20cf500" latency=-887.191404ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Feb 12 20:58:10 volumio volumio[5411]: error: FusionDsp - Reload WebSocket error: [object Object] Feb 12 20:58:10 volumio go-librespot[6423]: time="2026-02-12T20:58:10+02:00" level=debug msg="new websocket client" Feb 12 20:58:10 volumio volumio[5411]: info: Connection to go-librespot Websocket established Feb 12 20:58:10 volumio go-librespot[6423]: time="2026-02-12T20:58:10+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\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Feb 12 20:58:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:58:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:58:10 volumio volumio[5411]: info: Connection to go-librespot Websocket closed Feb 12 20:58:11 volumio volumio5-onboarding[1794]: time=2026-02-12T20:58:11.178+02:00 level=INFO msg="emitting wifi scan event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" networks=3 Feb 12 20:58:11 volumio volumio[5411]: info: Received Get System Info Feb 12 20:58:11 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 20:58:11 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 20:58:11 volumio volumio[5411]: info: Discovery: Getting this device information Feb 12 20:58:11 volumio volumio[5411]: info: CoreCommandRouter::volumioGetState Feb 12 20:58:11 volumio volumio[5411]: info: CorePlayQueue::getTrack 0 Feb 12 20:58:11 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 20:58:11 volumio volumio5-onboarding[1794]: time=2026-02-12T20:58:11.686+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x20cf500" available=true connected=true macAddress=2c:cf:67:b8:99:78 ip4Address=192.168.0.75/24 ip6Address= ssid=UPC27762C4 Feb 12 20:58:11 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 20:58:11 volumio volumio[5411]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 20:58:13 volumio volumio[5411]: info: Getting Spotify volume Feb 12 20:58:13 volumio volumio[5411]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:58:13 volumio volumio[5411]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 20:58:13 volumio volumio[5411]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 20:58:13 volumio volumio[5411]: errno: -111, Feb 12 20:58:13 volumio volumio[5411]: code: 'ECONNREFUSED', Feb 12 20:58:13 volumio volumio[5411]: syscall: 'connect', Feb 12 20:58:13 volumio volumio[5411]: address: '127.0.0.1', Feb 12 20:58:13 volumio volumio[5411]: port: 9879, Feb 12 20:58:13 volumio volumio[5411]: response: undefined Feb 12 20:58:13 volumio volumio[5411]: } Feb 12 20:58:13 volumio volumio[5411]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:58:13 volumio sudo[6576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 20:57' Feb 12 20:58:13 volumio sudo[6576]: 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"