Nov 17 19:31:03 volumio2 volumio[6403]: info: Discovery: adding 7a10725a-67eb-4736-8113-a8efcff3aacc Nov 17 19:31:03 volumio2 volumio[6403]: info: Discovery: Found device volumio2 Nov 17 19:31:03 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:03 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:03 volumio2 volumio[6403]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.071&uuid=d5d63b5bf39c4cddedd15a998509fe2b" http://updates.volumio.org/downloader-v1/track-device Nov 17 19:31:03 volumio2 volumio[6403]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 17 19:31:03 volumio2 volumio[6403]: Dload Upload Total Spent Left Speed Nov 17 19:31:03 volumio2 volumio[6403]: [1001B blob data] Nov 17 19:31:03 volumio2 volumio[6403]: retrying in 5 seconds, trial 0 Nov 17 19:31:03 volumio2 volumio[6403]: info: Volumio Calling Home Nov 17 19:31:05 volumio2 volumio[6403]: error: MyVolumio Plugin failed to start in a timely fashion Nov 17 19:31:05 volumio2 volumio[6403]: [Metrics] CommandRouter: 29s 786.32ms Nov 17 19:31:05 volumio2 volumio[6403]: info: CoreCommandRouter::volumiosetStartupVolume Nov 17 19:31:05 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 17 19:31:05 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 17 19:31:05 volumio2 volumio[6403]: info: CoreCommandRouter::Close All Modals sent Nov 17 19:31:05 volumio2 volumio[6403]: info: CoreCommandRouter::Close All Modals sent Nov 17 19:31:06 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 17 19:31:06 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 17 19:31:06 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 17 19:31:09 volumio2 go-librespot[6680]: time="2025-11-17T19:31:09-03: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" Nov 17 19:31:09 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:31:09 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:31:11 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 17 19:31:11 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 17 19:31:11 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 17 19:31:12 volumio2 volumio[6403]: info: BOOT COMPLETED Nov 17 19:31:12 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Nov 17 19:31:12 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:12 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:12 volumio2 go-librespot[6726]: go-librespot daemon starting... Nov 17 19:31:12 volumio2 go-librespot[6727]: time="2025-11-17T19:31:12-03:00" level=info msg="running go-librespot 0.4.0" Nov 17 19:31:12 volumio2 go-librespot[6727]: time="2025-11-17T19:31:12-03:00" level=debug msg="app state loaded" Nov 17 19:31:12 volumio2 go-librespot[6727]: time="2025-11-17T19:31:12-03:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 17 19:31:12 volumio2 volumio[6403]: info: CALLMETHOD: system_controller system savePrivacySettings [object Object] Nov 17 19:31:12 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , savePrivacySettings Nov 17 19:31:12 volumio2 volumio[6403]: info: CoreCommandRouter::Reload Ui Nov 17 19:31:13 volumio2 sudo[6734]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 17 19:31:13 volumio2 sudo[6734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:13 volumio2 sudo[6736]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:13 volumio2 sudo[6734]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:13 volumio2 sudo[6736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:13 volumio2 sudo[6736]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:13 volumio2 volumio[6403]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.144 UA: Mozilla/5.0 (Linux; Android 15; 24090RA29G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetVisibleSources Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:13 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 17 19:31:13 volumio2 volumio[6403]: info: Received Get System Info Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 17 19:31:13 volumio2 volumio[6403]: info: Discovery: Getting this device information Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:13 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:13 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:13 volumio2 volumio[6403]: info: Listing playlists Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Nov 17 19:31:13 volumio2 volumio[6403]: info: Received Get System Version Nov 17 19:31:13 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 17 19:31:15 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 17 19:31:15 volumio2 volumio[6403]: info: Received Get System Info Nov 17 19:31:15 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 17 19:31:15 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 17 19:31:15 volumio2 volumio[6403]: info: Discovery: Getting this device information Nov 17 19:31:15 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:15 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:15 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 17 19:31:15 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 17 19:31:23 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 17 19:31:25 volumio2 volumio[6403]: info: Discovery: this is already registered, 7a10725a-67eb-4736-8113-a8efcff3aacc Nov 17 19:31:25 volumio2 go-librespot[6727]: time="2025-11-17T19:31:25-03: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" Nov 17 19:31:25 volumio2 volumio[6403]: info: Discovery: Found device volumio2 Nov 17 19:31:25 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:25 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:25 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:31:25 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:31:25 volumio2 volumio[6403]: info: Error connecting to go-librespot Websocket: Error: socket hang up Nov 17 19:31:25 volumio2 volumio[6403]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.071&uuid=d5d63b5bf39c4cddedd15a998509fe2b" http://updates.volumio.org/downloader-v1/track-device Nov 17 19:31:25 volumio2 volumio[6403]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 17 19:31:25 volumio2 volumio[6403]: Dload Upload Total Spent Left Speed Nov 17 19:31:25 volumio2 volumio[6403]: [1.0K blob data] Nov 17 19:31:25 volumio2 volumio[6403]: retrying in 5 seconds, trial 1 Nov 17 19:31:25 volumio2 volumio[6403]: info: Volumio Calling Home Nov 17 19:31:26 volumio2 volumio[6403]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 17 19:31:26 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 17 19:31:27 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Nov 17 19:31:27 volumio2 sudo[6793]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Nov 17 19:31:27 volumio2 sudo[6793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:27 volumio2 sudo[6793]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:27 volumio2 sudo[6798]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 17 19:31:27 volumio2 sudo[6798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:27 volumio2 sudo[6798]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:27 volumio2 sudo[6804]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 17 19:31:27 volumio2 sudo[6804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:27 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 17 19:31:27 volumio2 sudo[6804]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:27 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 17 19:31:27 volumio2 sudo[6808]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 17 19:31:27 volumio2 sudo[6808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:27 volumio2 sudo[6808]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:27 volumio2 sudo[6818]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:27 volumio2 sudo[6818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:27 volumio2 sudo[6815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 17 19:31:27 volumio2 sudo[6815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:27 volumio2 sudo[6818]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:27 volumio2 sudo[6815]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:27 volumio2 sudo[6823]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 17 19:31:27 volumio2 sudo[6823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:28 volumio2 volumio[6403]: info: Initializing connection to go-librespot Websocket Nov 17 19:31:28 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Nov 17 19:31:28 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:28 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:28 volumio2 go-librespot[6827]: go-librespot daemon starting... Nov 17 19:31:28 volumio2 go-librespot[6828]: time="2025-11-17T19:31:28-03:00" level=info msg="running go-librespot 0.4.0" Nov 17 19:31:28 volumio2 go-librespot[6828]: time="2025-11-17T19:31:28-03:00" level=debug msg="app state loaded" Nov 17 19:31:28 volumio2 go-librespot[6828]: time="2025-11-17T19:31:28-03:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 17 19:31:31 volumio2 sudo[6823]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:37 volumio2 sudo[6838]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 17 19:31:37 volumio2 sudo[6838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:37 volumio2 sudo[6838]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:37 volumio2 sudo[6840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:37 volumio2 sudo[6840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:37 volumio2 sudo[6840]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:39 volumio2 volumio[6403]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.144 UA: Mozilla/5.0 (Linux; Android 15; 24090RA29G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetVisibleSources Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:39 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 17 19:31:39 volumio2 volumio[6403]: info: Received Get System Info Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 17 19:31:39 volumio2 volumio[6403]: info: Discovery: Getting this device information Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:39 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:39 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:39 volumio2 volumio[6403]: info: Listing playlists Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 17 19:31:39 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Nov 17 19:31:40 volumio2 sudo[6861]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Nov 17 19:31:40 volumio2 sudo[6861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:40 volumio2 sudo[6866]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 17 19:31:40 volumio2 sudo[6866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:40 volumio2 sudo[6866]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:40 volumio2 sudo[6861]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:40 volumio2 sudo[6872]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 17 19:31:40 volumio2 sudo[6872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:40 volumio2 sudo[6872]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 17 19:31:40 volumio2 sudo[6878]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 17 19:31:40 volumio2 sudo[6878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:40 volumio2 sudo[6878]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:40 volumio2 sudo[6885]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 17 19:31:40 volumio2 sudo[6885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:40 volumio2 go-librespot[6828]: time="2025-11-17T19:31:40-03: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" Nov 17 19:31:40 volumio2 sudo[6885]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:40 volumio2 sudo[6888]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:40 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:31:40 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:31:40 volumio2 sudo[6888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:40 volumio2 sudo[6888]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:40 volumio2 sudo[6891]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 17 19:31:40 volumio2 sudo[6891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 17 19:31:40 volumio2 volumio[6403]: info: Received Get System Info Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 17 19:31:40 volumio2 volumio[6403]: info: Discovery: Getting this device information Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:40 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:40 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 17 19:31:42 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 17 19:31:43 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Nov 17 19:31:43 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:44 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:44 volumio2 go-librespot[6896]: go-librespot daemon starting... Nov 17 19:31:44 volumio2 go-librespot[6897]: time="2025-11-17T19:31:44-03:00" level=info msg="running go-librespot 0.4.0" Nov 17 19:31:44 volumio2 go-librespot[6897]: time="2025-11-17T19:31:44-03:00" level=debug msg="app state loaded" Nov 17 19:31:44 volumio2 go-librespot[6897]: time="2025-11-17T19:31:44-03:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 17 19:31:44 volumio2 sudo[6891]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:44 volumio2 volumio[6403]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.071&uuid=d5d63b5bf39c4cddedd15a998509fe2b" http://updates.volumio.org/downloader-v1/track-device Nov 17 19:31:44 volumio2 volumio[6403]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 17 19:31:44 volumio2 volumio[6403]: Dload Upload Total Spent Left Speed Nov 17 19:31:44 volumio2 volumio[6403]: [843B blob data] Nov 17 19:31:44 volumio2 volumio[6403]: retrying in 5 seconds, trial 2 Nov 17 19:31:44 volumio2 volumio[6403]: info: Volumio Calling Home Nov 17 19:31:49 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 17 19:31:51 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Nov 17 19:31:51 volumio2 volumio[6403]: info: Saving new wireless network Nov 17 19:31:51 volumio2 sudo[6926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Nov 17 19:31:51 volumio2 sudo[6926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:51 volumio2 sudo[6926]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:51 volumio2 volumio[6403]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 17 19:31:51 volumio2 volumio[6403]: info: Discovery: Restarting Advertising due to device name change Nov 17 19:31:51 volumio2 sudo[6929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 17 19:31:51 volumio2 sudo[6929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:51 volumio2 systemd[1]: Stopping wireless.service - Wireless Services... Nov 17 19:31:51 volumio2 systemd[1]: wireless.service: Deactivated successfully. Nov 17 19:31:51 volumio2 systemd[1]: Stopped wireless.service - Wireless Services. Nov 17 19:31:51 volumio2 systemd[1]: wireless.service: Consumed 9min 30.644s CPU time. Nov 17 19:31:52 volumio2 systemd[1]: Starting wireless.service - Wireless Services... Nov 17 19:31:52 volumio2 wireless.js[6931]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Nov 17 19:31:52 volumio2 wireless.js[6931]: WIRELESS.JS: Wireless.js initializing wireless flow Nov 17 19:31:52 volumio2 wireless.js[6931]: WIRELESS.JS: Cleaning previous... Nov 17 19:31:52 volumio2 systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Nov 17 19:31:52 volumio2 dnsmasq[1344]: exiting on receipt of SIGTERM Nov 17 19:31:52 volumio2 systemd[1]: dnsmasq.service: Deactivated successfully. Nov 17 19:31:52 volumio2 systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Nov 17 19:31:52 volumio2 systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Nov 17 19:31:52 volumio2 volumio[6403]: info: Discovery: A device disappeared from network Nov 17 19:31:52 volumio2 volumio[6403]: info: Discovery: A device disappeared from network Nov 17 19:31:52 volumio2 sudo[6950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:52 volumio2 sudo[6950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:52 volumio2 sudo[6950]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:53 volumio2 dhcpcd[755]: wlan0: carrier lost Nov 17 19:31:53 volumio2 systemd[1]: hostapd.service: Deactivated successfully. Nov 17 19:31:53 volumio2 systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Nov 17 19:31:53 volumio2 avahi-daemon[653]: Withdrawing address record for 169.254.155.170 on wlan0. Nov 17 19:31:53 volumio2 dhcpcd[755]: wlan0: deleting route to 169.254.0.0/16 Nov 17 19:31:53 volumio2 dhcpcd[755]: wlan0: deleting default route Nov 17 19:31:53 volumio2 sudo[6964]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Nov 17 19:31:53 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Nov 17 19:31:53 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Nov 17 19:31:53 volumio2 systemd[1]: welcome.service: Deactivated successfully. Nov 17 19:31:53 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Nov 17 19:31:53 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Nov 17 19:31:53 volumio2 sudo[6964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 17 19:31:53 volumio2 avahi-daemon[653]: Withdrawing address record for 192.168.211.1 on wlan0. Nov 17 19:31:53 volumio2 avahi-daemon[653]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Nov 17 19:31:53 volumio2 avahi-daemon[653]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 17 19:31:53 volumio2 sudo[6964]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:53 volumio2 sudo[6977]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Nov 17 19:31:53 volumio2 sudo[6977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 17 19:31:53 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Nov 17 19:31:53 volumio2 welcome[6971]: Resolved ip:[0] Nov 17 19:31:53 volumio2 sudo[6977]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:53 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Nov 17 19:31:53 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Nov 17 19:31:53 volumio2 sudo[6987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:53 volumio2 sudo[6987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:53 volumio2 sudo[6987]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:53 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Nov 17 19:31:53 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Nov 17 19:31:53 volumio2 systemd[1]: welcome.service: Deactivated successfully. Nov 17 19:31:53 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Nov 17 19:31:53 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Nov 17 19:31:54 volumio2 wireless.js[6931]: WIRELESS.JS: Stopped aP Nov 17 19:31:54 volumio2 sudo[6996]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 17 19:31:54 volumio2 sudo[6996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:54 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Nov 17 19:31:54 volumio2 welcome[6991]: Resolved ip:[0] Nov 17 19:31:54 volumio2 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 17 19:31:54 volumio2 sudo[6996]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:54 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Nov 17 19:31:54 volumio2 sudo[7000]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 17 19:31:54 volumio2 sudo[7000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:54 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Nov 17 19:31:54 volumio2 sudo[7000]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:54 volumio2 sudo[7010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 17 19:31:54 volumio2 sudo[7010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:54 volumio2 sudo[7010]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:54 volumio2 sudo[7012]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 17 19:31:54 volumio2 sudo[7012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:54 volumio2 sudo[7017]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:54 volumio2 sudo[7017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:54 volumio2 sudo[7017]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:55 volumio2 ntpd[861]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=1, sent=1, dropped=0, active_time=21565 secs Nov 17 19:31:55 volumio2 ntpd[861]: IO: Deleting interface #4 wlan0, 169.254.155.170#123, interface stats: received=0, sent=0, dropped=0, active_time=21554 secs Nov 17 19:31:55 volumio2 go-librespot[6897]: time="2025-11-17T19:31:55-03: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" Nov 17 19:31:55 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:31:55 volumio2 volumio[6403]: info: Error connecting to go-librespot Websocket: Error: read ECONNRESET Nov 17 19:31:55 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:31:55 volumio2 sudo[7020]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:55 volumio2 sudo[7020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:55 volumio2 sudo[7020]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:56 volumio2 sudo[7012]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:56 volumio2 wireless.js[6931]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: AR Nov 17 19:31:56 volumio2 sudo[7024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 17 19:31:56 volumio2 sudo[7024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:56 volumio2 sudo[7024]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:56 volumio2 sudo[7026]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set AR Nov 17 19:31:56 volumio2 sudo[7026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:56 volumio2 sudo[7026]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:56 volumio2 wireless.js[6931]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: AR Nov 17 19:31:56 volumio2 wireless.js[6931]: WIRELESS.JS: Start wireless flow Nov 17 19:31:56 volumio2 wireless.js[6931]: WIRELESS.JS: Stopped hotspot (if there).. Nov 17 19:31:56 volumio2 sudo[7034]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Nov 17 19:31:56 volumio2 sudo[7034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 17 19:31:56 volumio2 sudo[7034]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:56 volumio2 sudo[7036]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Nov 17 19:31:56 volumio2 sudo[7036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 17 19:31:56 volumio2 volumio[6403]: info: Discovery: Started advertising with name: volumio2 Nov 17 19:31:57 volumio2 sudo[7036]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:57 volumio2 sudo[7040]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:57 volumio2 sudo[7040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:57 volumio2 sudo[7040]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:57 volumio2 wireless.js[6931]: WIRELESS.JS: DHCP IP fallback Nov 17 19:31:57 volumio2 wireless.js[6931]: WIRELESS.JS: Start ap Nov 17 19:31:57 volumio2 wpa_supplicant[7045]: Successfully initialized wpa_supplicant Nov 17 19:31:57 volumio2 sudo[7046]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 17 19:31:57 volumio2 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 17 19:31:57 volumio2 sudo[7046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 17 19:31:57 volumio2 dhcpcd[755]: ps_ctl_dispatch: cannot handle another client Nov 17 19:31:57 volumio2 dhcpcd[755]: control_free: No such file or directory Nov 17 19:31:57 volumio2 sudo[7046]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:57 volumio2 wpa_supplicant[7045]: nl80211: kernel reports: Registration to specific type not supported Nov 17 19:31:57 volumio2 volumio[6403]: info: Discovery: adding 7a10725a-67eb-4736-8113-a8efcff3aacc Nov 17 19:31:57 volumio2 volumio[6403]: info: Discovery: Found device volumio2 Nov 17 19:31:57 volumio2 volumio[6403]: info: CoreCommandRouter::volumioGetState Nov 17 19:31:57 volumio2 volumio[6403]: info: CorePlayQueue::getTrack 0 Nov 17 19:31:57 volumio2 sudo[7067]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:57 volumio2 sudo[7067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:57 volumio2 sudo[7067]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:58 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:31:58 volumio2 sudo[7071]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:31:58 volumio2 sudo[7071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:58 volumio2 sudo[7071]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:58 volumio2 volumio[6403]: info: Initializing connection to go-librespot Websocket Nov 17 19:31:58 volumio2 volumio[6403]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 17 19:31:58 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Nov 17 19:31:58 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:58 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:31:58 volumio2 go-librespot[7073]: go-librespot daemon starting... Nov 17 19:31:58 volumio2 go-librespot[7074]: time="2025-11-17T19:31:58-03:00" level=info msg="running go-librespot 0.4.0" Nov 17 19:31:58 volumio2 go-librespot[7074]: time="2025-11-17T19:31:58-03:00" level=debug msg="app state loaded" Nov 17 19:31:58 volumio2 go-librespot[7074]: time="2025-11-17T19:31:58-03:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 17 19:31:58 volumio2 go-librespot[7074]: time="2025-11-17T19:31:58-03: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" Nov 17 19:31:58 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:31:58 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:31:58 volumio2 sudo[7082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:58 volumio2 sudo[7082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:58 volumio2 sudo[7082]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:59 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:31:59 volumio2 sudo[7086]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:31:59 volumio2 sudo[7086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:59 volumio2 sudo[7086]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:59 volumio2 wpa_supplicant[7050]: wlan0: Trying to associate with 82:54:d9:bf:bc:d8 (SSID='Fibertel WiFi490 2.4GHz' freq=5260 MHz) Nov 17 19:31:59 volumio2 sudo[7089]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:31:59 volumio2 sudo[7089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:31:59 volumio2 sudo[7089]: pam_unix(sudo:session): session closed for user root Nov 17 19:31:59 volumio2 wpa_supplicant[7050]: wlan0: Associated with 82:54:d9:bf:bc:d8 Nov 17 19:31:59 volumio2 wpa_supplicant[7050]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 17 19:31:59 volumio2 wpa_supplicant[7050]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AR Nov 17 19:31:59 volumio2 wpa_supplicant[7050]: wlan0: WPA: Key negotiation completed with 82:54:d9:bf:bc:d8 [PTK=CCMP GTK=TKIP] Nov 17 19:31:59 volumio2 wpa_supplicant[7050]: wlan0: CTRL-EVENT-CONNECTED - Connection to 82:54:d9:bf:bc:d8 completed [id=0 id_str=] Nov 17 19:31:59 volumio2 dhcpcd[755]: wlan0: carrier acquired Nov 17 19:31:59 volumio2 dhcpcd[755]: wlan0: connected to Access Point: Fibertel WiFi490 2.4GHz Nov 17 19:31:59 volumio2 dhcpcd[755]: wlan0: IAID eb:e6:ac:79 Nov 17 19:32:00 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:00 volumio2 sudo[7094]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:00 volumio2 sudo[7094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:00 volumio2 sudo[7094]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:00 volumio2 dhcpcd[755]: wlan0: soliciting an IPv6 router Nov 17 19:32:00 volumio2 dhcpcd[755]: wlan0: soliciting a DHCP lease Nov 17 19:32:00 volumio2 sudo[7098]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:00 volumio2 sudo[7098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:00 volumio2 sudo[7098]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:01 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:01 volumio2 sudo[7102]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:01 volumio2 sudo[7102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:01 volumio2 sudo[7102]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:01 volumio2 volumio[6403]: info: Initializing connection to go-librespot Websocket Nov 17 19:32:01 volumio2 volumio[6403]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 17 19:32:01 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Nov 17 19:32:01 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:32:01 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:32:01 volumio2 go-librespot[7104]: go-librespot daemon starting... Nov 17 19:32:01 volumio2 go-librespot[7105]: time="2025-11-17T19:32:01-03:00" level=info msg="running go-librespot 0.4.0" Nov 17 19:32:01 volumio2 go-librespot[7105]: time="2025-11-17T19:32:01-03:00" level=debug msg="app state loaded" Nov 17 19:32:01 volumio2 go-librespot[7105]: time="2025-11-17T19:32:01-03:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 17 19:32:01 volumio2 go-librespot[7105]: time="2025-11-17T19:32:01-03: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" Nov 17 19:32:01 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:32:01 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:32:01 volumio2 sudo[7112]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:01 volumio2 sudo[7112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:01 volumio2 sudo[7112]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:02 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:02 volumio2 sudo[7116]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:02 volumio2 sudo[7116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:02 volumio2 sudo[7116]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:02 volumio2 dhcpcd[755]: wlan0: offered 192.168.1.25 from 192.168.1.1 Nov 17 19:32:02 volumio2 sudo[7120]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:02 volumio2 sudo[7120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:02 volumio2 sudo[7120]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:02 volumio2 dhcpcd[755]: wlan0: probing address 192.168.1.25/24 Nov 17 19:32:03 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:03 volumio2 sudo[7125]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:03 volumio2 sudo[7125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:03 volumio2 sudo[7125]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:03 volumio2 sudo[7128]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:03 volumio2 sudo[7128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:03 volumio2 sudo[7128]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:04 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:04 volumio2 sudo[7132]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:04 volumio2 sudo[7132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:04 volumio2 sudo[7132]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:04 volumio2 volumio[6403]: info: Initializing connection to go-librespot Websocket Nov 17 19:32:04 volumio2 volumio[6403]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 17 19:32:04 volumio2 sudo[7135]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:04 volumio2 sudo[7135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:04 volumio2 sudo[7135]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:04 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Nov 17 19:32:04 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:32:05 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:32:05 volumio2 go-librespot[7137]: go-librespot daemon starting... Nov 17 19:32:05 volumio2 go-librespot[7138]: time="2025-11-17T19:32:05-03:00" level=info msg="running go-librespot 0.4.0" Nov 17 19:32:05 volumio2 go-librespot[7138]: time="2025-11-17T19:32:05-03:00" level=debug msg="app state loaded" Nov 17 19:32:05 volumio2 go-librespot[7138]: time="2025-11-17T19:32:05-03:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 17 19:32:05 volumio2 go-librespot[7138]: time="2025-11-17T19:32:05-03: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" Nov 17 19:32:05 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:32:05 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:32:05 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:05 volumio2 sudo[7146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:05 volumio2 sudo[7146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:05 volumio2 sudo[7146]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:05 volumio2 sudo[7149]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:05 volumio2 sudo[7149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:05 volumio2 sudo[7149]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:06 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:06 volumio2 sudo[7153]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:06 volumio2 sudo[7153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:06 volumio2 sudo[7153]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:06 volumio2 sudo[7156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:06 volumio2 sudo[7156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:06 volumio2 sudo[7156]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:07 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:07 volumio2 sudo[7160]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:07 volumio2 sudo[7160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:07 volumio2 sudo[7160]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:07 volumio2 volumio[6403]: info: Initializing connection to go-librespot Websocket Nov 17 19:32:07 volumio2 volumio[6403]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 17 19:32:07 volumio2 sudo[7177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:07 volumio2 sudo[7177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:07 volumio2 sudo[7177]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:08 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:08 volumio2 sudo[7181]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:08 volumio2 sudo[7181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:08 volumio2 sudo[7181]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:08 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Nov 17 19:32:08 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:32:08 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 17 19:32:08 volumio2 go-librespot[7183]: go-librespot daemon starting... Nov 17 19:32:08 volumio2 go-librespot[7184]: time="2025-11-17T19:32:08-03:00" level=info msg="running go-librespot 0.4.0" Nov 17 19:32:08 volumio2 go-librespot[7184]: time="2025-11-17T19:32:08-03:00" level=debug msg="app state loaded" Nov 17 19:32:08 volumio2 go-librespot[7184]: time="2025-11-17T19:32:08-03:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 17 19:32:08 volumio2 go-librespot[7184]: time="2025-11-17T19:32:08-03: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" Nov 17 19:32:08 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 17 19:32:08 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 17 19:32:08 volumio2 dhcpcd[755]: wlan0: leased 192.168.1.25 for 86400 seconds Nov 17 19:32:08 volumio2 avahi-daemon[653]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.25. Nov 17 19:32:08 volumio2 avahi-daemon[653]: New relevant interface wlan0.IPv4 for mDNS. Nov 17 19:32:08 volumio2 avahi-daemon[653]: Registering new address record for 192.168.1.25 on wlan0.IPv4. Nov 17 19:32:08 volumio2 dhcpcd[755]: wlan0: adding route to 192.168.1.0/24 Nov 17 19:32:08 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Nov 17 19:32:08 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Nov 17 19:32:08 volumio2 systemd[1]: welcome.service: Deactivated successfully. Nov 17 19:32:08 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Nov 17 19:32:08 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Nov 17 19:32:08 volumio2 dhcpcd[755]: wlan0: adding default route via 192.168.1.1 Nov 17 19:32:08 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Nov 17 19:32:08 volumio2 welcome[7207]: Resolved ip:[1] 192.168.1.25 Nov 17 19:32:08 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Nov 17 19:32:08 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Nov 17 19:32:08 volumio2 volumio[6403]: compat.c: read() failed: Resource temporarily unavailable Nov 17 19:32:08 volumio2 volumio[6403]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 17 19:32:08 volumio2 volumio[6403]: Error: dns service error: unknown Nov 17 19:32:08 volumio2 volumio[6403]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Nov 17 19:32:08 volumio2 volumio[6403]: errorCode: -65537 Nov 17 19:32:08 volumio2 volumio[6403]: } Nov 17 19:32:08 volumio2 volumio[6403]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 17 19:32:09 volumio2 wireless.js[6931]: WIRELESS.JS: trying... Nov 17 19:32:09 volumio2 sudo[7232]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 17 19:32:09 volumio2 sudo[7232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:09 volumio2 sudo[7232]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:09 volumio2 wireless.js[6931]: WIRELESS.JS: Connected to: ----Fibertel WiFi490 2.4GHz---- Nov 17 19:32:09 volumio2 sudo[7235]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 17 19:32:09 volumio2 sudo[7235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 17 19:32:09 volumio2 sudo[7235]: pam_unix(sudo:session): session closed for user root Nov 17 19:32:09 volumio2 wireless.js[6931]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.1.25, ipV6 is undefined Nov 17 19:32:09 volumio2 wireless.js[6931]: WIRELESS.JS: It's done! AP Nov 17 19:32:09 volumio2 sudo[7238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-17 19:31' Nov 17 19:32:09 volumio2 sudo[7238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 17 19:32:09 volumio2 wireless.js[6931]: WIRELESS.JS: Restarting avahi-daemon... 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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"