-- Logs begin at Fri 2026-04-10 10:00:39 -03, end at Mon 2026-04-13 14:16:58 -03. -- Apr 13 14:15:09 volumio go-librespot[4984]: time="2026-04-13T14:15:09-03:00" level=debug msg="fetched chunk 12/21, size: 524288" uri="spotify:track:6h0s5FUtc6HV3smc8naDPI" Apr 13 14:15:22 volumio go-librespot[4984]: time="2026-04-13T14:15:22-03:00" level=trace msg="sent dealer ping" Apr 13 14:15:22 volumio go-librespot[4984]: time="2026-04-13T14:15:22-03:00" level=trace msg="received dealer pong" Apr 13 14:15:22 volumio go-librespot[4984]: time="2026-04-13T14:15:22-03:00" level=debug msg="fetched chunk 13/21, size: 524288" uri="spotify:track:6h0s5FUtc6HV3smc8naDPI" Apr 13 14:15:36 volumio go-librespot[4984]: time="2026-04-13T14:15:36-03:00" level=debug msg="fetched chunk 14/21, size: 524288" uri="spotify:track:6h0s5FUtc6HV3smc8naDPI" Apr 13 14:15:49 volumio go-librespot[4984]: time="2026-04-13T14:15:49-03:00" level=debug msg="fetched chunk 15/21, size: 524288" uri="spotify:track:6h0s5FUtc6HV3smc8naDPI" Apr 13 14:15:52 volumio go-librespot[4984]: time="2026-04-13T14:15:52-03:00" level=trace msg="sent dealer ping" Apr 13 14:15:52 volumio go-librespot[4984]: time="2026-04-13T14:15:52-03:00" level=trace msg="received dealer pong" Apr 13 14:16:03 volumio go-librespot[4984]: time="2026-04-13T14:16:03-03:00" level=debug msg="fetched chunk 16/21, size: 524288" uri="spotify:track:6h0s5FUtc6HV3smc8naDPI" Apr 13 14:16:17 volumio go-librespot[4984]: time="2026-04-13T14:16:17-03:00" level=debug msg="fetched chunk 17/21, size: 524288" uri="spotify:track:6h0s5FUtc6HV3smc8naDPI" Apr 13 14:16:21 volumio go-librespot[4984]: time="2026-04-13T14:16:21-03:00" level=trace msg="received accesspoint ping" Apr 13 14:16:21 volumio go-librespot[4984]: time="2026-04-13T14:16:21-03:00" level=trace msg="received accesspoint pong ack" Apr 13 14:16:22 volumio go-librespot[4984]: time="2026-04-13T14:16:22-03:00" level=trace msg="sent dealer ping" Apr 13 14:16:22 volumio go-librespot[4984]: time="2026-04-13T14:16:22-03:00" level=trace msg="received dealer pong" Apr 13 14:16:31 volumio go-librespot[4984]: time="2026-04-13T14:16:31-03:00" level=debug msg="fetched chunk 18/21, size: 524288" uri="spotify:track:6h0s5FUtc6HV3smc8naDPI" Apr 13 14:16:46 volumio wpa_supplicant[780]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 13 14:16:46 volumio dhcpcd[797]: wlan0: carrier lost Apr 13 14:16:46 volumio avahi-daemon[474]: Withdrawing address record for 192.168.0.5 on wlan0. Apr 13 14:16:46 volumio avahi-daemon[474]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.5. Apr 13 14:16:47 volumio wpa_supplicant[780]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f8:2d:c0:24:80:a5 reason=4 locally_generated=1 Apr 13 14:16:47 volumio avahi-daemon[474]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 13 14:16:47 volumio dhcpcd[797]: wlan0: deleting route to 192.168.0.0/24 Apr 13 14:16:47 volumio dhcpcd[797]: wlan0: deleting default route via 192.168.0.1 Apr 13 14:16:47 volumio wpa_supplicant[780]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 13 14:16:47 volumio volumio[880]: info: Discovery: A device disappeared from network Apr 13 14:16:47 volumio volumio[880]: info: Received Get System Info Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:16:47 volumio volumio[880]: info: Discovery: Getting this device information Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:16:47 volumio volumio[880]: info: Discovery: A device disappeared from network Apr 13 14:16:47 volumio volumio[880]: info: Discovery: A device disappeared from network Apr 13 14:16:47 volumio volumio[880]: info: Discovery: A device disappeared from network Apr 13 14:16:47 volumio volumio[880]: info: Discovery: A device disappeared from network Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 13 14:16:47 volumio volumio5-onboarding[1169]: time=2026-04-13T14:16:47.774-03:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 13 14:16:47 volumio volumio[880]: info: Received Get System Info Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:16:47 volumio volumio[880]: info: Discovery: Getting this device information Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 13 14:16:47 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 13 14:16:48 volumio ntpd[694]: Deleting interface #24 wlan0, 192.168.0.5#123, interface stats: received=645, sent=651, dropped=0, active_time=57552 secs Apr 13 14:16:48 volumio ntpd[694]: 216.238.112.29 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio ntpd[694]: 200.160.7.186 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio ntpd[694]: 200.160.7.197 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio ntpd[694]: 200.160.7.196 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio ntpd[694]: 200.160.7.193 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio ntpd[694]: 172.233.29.160 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio ntpd[694]: 200.160.0.8 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio ntpd[694]: 186.192.158.146 local addr 192.168.0.5 -> Apr 13 14:16:48 volumio volumio5-onboarding[1169]: time=2026-04-13T14:16:48.774-03:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 13 14:16:50 volumio wpa_supplicant[780]: wlan0: SME: Trying to authenticate with f8:2d:c0:24:80:a5 (SSID='2G Grejo ' freq=2457 MHz) Apr 13 14:16:50 volumio kernel: wlan0: authenticate with f8:2d:c0:24:80:a5 Apr 13 14:16:50 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 13 14:16:51 volumio nmbd[689]: [2026/04/13 14:16:51.376798, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Apr 13 14:16:51 volumio nmbd[689]: reload_interfaces: No subnets to listen to. Waiting.. Apr 13 14:16:51 volumio kernel: wlan0: send auth to f8:2d:c0:24:80:a5 (try 1/3) Apr 13 14:16:51 volumio volumio[880]: info: Received Get System Info Apr 13 14:16:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 14:16:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:16:51 volumio volumio[880]: info: Discovery: Getting this device information Apr 13 14:16:51 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Apr 13 14:16:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:16:51 volumio wpa_supplicant[780]: wlan0: Trying to associate with f8:2d:c0:24:80:a5 (SSID='2G Grejo ' freq=2457 MHz) Apr 13 14:16:51 volumio kernel: wlan0: authenticated Apr 13 14:16:51 volumio kernel: wlan0: associate with f8:2d:c0:24:80:a5 (try 1/3) Apr 13 14:16:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 13 14:16:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 13 14:16:51 volumio kernel: wlan0: RX AssocResp from f8:2d:c0:24:80:a5 (capab=0x431 status=0 aid=4) Apr 13 14:16:51 volumio kernel: wlan0: associated Apr 13 14:16:51 volumio wpa_supplicant[780]: wlan0: Associated with f8:2d:c0:24:80:a5 Apr 13 14:16:51 volumio wpa_supplicant[780]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 13 14:16:51 volumio volumio5-onboarding[1169]: time=2026-04-13T14:16:51.840-03:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 13 14:16:51 volumio systemd-udevd[10797]: Process '/sbin/crda' failed with exit code 255. Apr 13 14:16:52 volumio wpa_supplicant[780]: wlan0: WPA: Key negotiation completed with f8:2d:c0:24:80:a5 [PTK=CCMP GTK=CCMP] Apr 13 14:16:52 volumio wpa_supplicant[780]: wlan0: CTRL-EVENT-CONNECTED - Connection to f8:2d:c0:24:80:a5 completed [id=0 id_str=] Apr 13 14:16:52 volumio dhcpcd[797]: wlan0: carrier acquired Apr 13 14:16:52 volumio dhcpcd[797]: wlan0: IAID 00:18:8a:a6 Apr 13 14:16:52 volumio go-librespot[4984]: time="2026-04-13T14:16:52-03:00" level=trace msg="sent dealer ping" Apr 13 14:16:52 volumio volumio[880]: info: Received Get System Info Apr 13 14:16:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 14:16:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:16:52 volumio volumio[880]: info: Discovery: Getting this device information Apr 13 14:16:52 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Apr 13 14:16:52 volumio dhcpcd[797]: wlan0: rebinding lease of 192.168.0.5 Apr 13 14:16:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:16:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 13 14:16:52 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 13 14:16:52 volumio dhcpcd[797]: wlan0: probing address 192.168.0.5/24 Apr 13 14:16:52 volumio dhcpcd[797]: wlan0: soliciting an IPv6 router Apr 13 14:16:52 volumio volumio5-onboarding[1169]: time=2026-04-13T14:16:52.838-03:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 13 14:16:55 volumio wpa_supplicant[780]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 13 14:16:56 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 14:16:56 volumio volumio[880]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Apr 13 14:16:56 volumio volumio[880]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Apr 13 14:16:56 volumio volumio[880]: errno: -16, Apr 13 14:16:56 volumio volumio[880]: code: 'EBUSY', Apr 13 14:16:56 volumio volumio[880]: syscall: 'getaddrinfo', Apr 13 14:16:56 volumio volumio[880]: hostname: 'ws.audioscrobbler.com' Apr 13 14:16:56 volumio volumio[880]: } Apr 13 14:16:56 volumio volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 14:16:57 volumio dhcpcd[797]: wlan0: leased 192.168.0.5 for 3600 seconds Apr 13 14:16:57 volumio avahi-daemon[474]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.5. Apr 13 14:16:57 volumio dhcpcd[797]: wlan0: adding route to 192.168.0.0/24 Apr 13 14:16:57 volumio dhcpcd[797]: wlan0: adding default route via 192.168.0.1 Apr 13 14:16:57 volumio avahi-daemon[474]: New relevant interface wlan0.IPv4 for mDNS. Apr 13 14:16:57 volumio avahi-daemon[474]: Registering new address record for 192.168.0.5 on wlan0.IPv4. Apr 13 14:16:58 volumio sudo[10882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-13 14:15 Apr 13 14:16:58 volumio sudo[10882]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"