-- Logs begin at Fri 2025-07-04 19:00:02 -03, end at Sat 2025-07-05 01:02:22 -03. -- Jul 05 01:01:00 volumio-aiwa ntpd[812]: Listen normally on 4 wlan0 169.254.221.25:123 Jul 05 01:01:00 volumio-aiwa ntpd[812]: new interface(s) found: waking up resolver Jul 05 01:01:02 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:01:06 volumio-aiwa volumio[18623]: info: Volumio Network Manager: Network status updated: 2 Jul 05 01:01:08 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:01:14 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:14-03:00" level=error msg="did not receive last pong from dealer, 120s passed" Jul 05 01:01:15 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:01:22 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-DISCONNECTED bssid=8c:90:2d:b5:24:f6 reason=6 Jul 05 01:01:22 volumio-aiwa dhcpcd[931]: wlan0: carrier lost Jul 05 01:01:22 volumio-aiwa wpa_supplicant[919]: wlan0: Trying to associate with SSID 'Meli y Sergio' Jul 05 01:01:22 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 05 01:01:22 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Jul 05 01:01:22 volumio-aiwa avahi-daemon[650]: Withdrawing address record for 169.254.221.25 on wlan0. Jul 05 01:01:22 volumio-aiwa avahi-daemon[650]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.221.25. Jul 05 01:01:22 volumio-aiwa dhcpcd[931]: wlan0: deleting route to 169.254.0.0/16 Jul 05 01:01:22 volumio-aiwa dhcpcd[931]: wlan0: deleting default route Jul 05 01:01:22 volumio-aiwa avahi-daemon[650]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 05 01:01:22 volumio-aiwa volumio[18623]: info: Discovery: A device disappeared from network Jul 05 01:01:22 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 05 01:01:24 volumio-aiwa ntpd[812]: Deleting interface #4 wlan0, 169.254.221.25#123, interface stats: received=0, sent=6, dropped=0, active_time=24 secs Jul 05 01:01:24 volumio-aiwa ntpd[812]: 200.11.116.10 local addr 169.254.221.25 -> Jul 05 01:01:24 volumio-aiwa ntpd[812]: 156.244.54.121 local addr 169.254.221.25 -> Jul 05 01:01:24 volumio-aiwa ntpd[812]: 162.159.200.123 local addr 169.254.221.25 -> Jul 05 01:01:24 volumio-aiwa ntpd[812]: 162.159.200.1 local addr 169.254.221.25 -> Jul 05 01:01:24 volumio-aiwa ntpd[812]: 190.103.0.13 local addr 169.254.221.25 -> Jul 05 01:01:24 volumio-aiwa ntpd[812]: 170.210.222.10 local addr 169.254.221.25 -> Jul 05 01:01:26 volumio-aiwa volumio[18623]: info: Volumio Network Manager: Network status updated: 0 Jul 05 01:01:27 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 05 01:01:27 volumio-aiwa ntpd[812]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 05 01:01:27 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 05 01:01:27 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 05 01:01:27 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 05 01:01:29 volumio-aiwa volumio[18623]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Jul 05 01:01:30 volumio-aiwa wpa_supplicant[919]: wlan0: Trying to associate with SSID 'Meli y Sergio' Jul 05 01:01:30 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 05 01:01:31 volumio-aiwa ntpd[812]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 05 01:01:32 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:32-03:00" level=debug msg="renewing login5 access token" Jul 05 01:01:32 volumio-aiwa nmbd[789]: [2025/07/05 01:01:32.845115, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Jul 05 01:01:32 volumio-aiwa nmbd[789]: reload_interfaces: No subnets to listen to. Waiting.. Jul 05 01:01:34 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 05 01:01:34 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 05 01:01:34 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 05 01:01:35 volumio-aiwa kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 05 01:01:37 volumio-aiwa wpa_supplicant[919]: wlan0: Trying to associate with SSID 'Meli y Sergio' Jul 05 01:01:37 volumio-aiwa dhcpcd[931]: wlan0: carrier acquired Jul 05 01:01:37 volumio-aiwa wpa_supplicant[919]: wlan0: Associated with 40:3f:8c:fb:4c:46 Jul 05 01:01:37 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-CONNECTED - Connection to 40:3f:8c:fb:4c:46 completed [id=0 id_str=] Jul 05 01:01:37 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 05 01:01:37 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jul 05 01:01:37 volumio-aiwa dhcpcd[931]: wlan0: IAID 32:e7:c9:a9 Jul 05 01:01:37 volumio-aiwa dhcpcd[931]: wlan0: carrier lost Jul 05 01:01:37 volumio-aiwa dhcpcd[931]: wlan0: carrier acquired Jul 05 01:01:37 volumio-aiwa dhcpcd[931]: wlan0: IAID 32:e7:c9:a9 Jul 05 01:01:38 volumio-aiwa dhcpcd[931]: wlan0: soliciting an IPv6 router Jul 05 01:01:38 volumio-aiwa dhcpcd[931]: wlan0: soliciting a DHCP lease Jul 05 01:01:39 volumio-aiwa ntpd[812]: Soliciting pool server 2606:4700:f1::123 Jul 05 01:01:39 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:01:43 volumio-aiwa dhcpcd[931]: wlan0: probing for an IPv4LL address Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=error msg="did not receive last pong ack from accesspoint, 201s passed" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=error msg="did not receive last pong from dealer, 150s passed" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.68.87:58796->104.154.127.247:80: use of closed network connection" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:44 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:44-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:45 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:45-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:46 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:46-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:01:48-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:01:48 volumio-aiwa dhcpcd[931]: wlan0: using IPv4LL address 169.254.221.25 Jul 05 01:01:48 volumio-aiwa avahi-daemon[650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.221.25. Jul 05 01:01:48 volumio-aiwa dhcpcd[931]: wlan0: adding route to 169.254.0.0/16 Jul 05 01:01:48 volumio-aiwa dhcpcd[931]: wlan0: adding default route Jul 05 01:01:48 volumio-aiwa avahi-daemon[650]: New relevant interface wlan0.IPv4 for mDNS. Jul 05 01:01:48 volumio-aiwa avahi-daemon[650]: Registering new address record for 169.254.221.25 on wlan0.IPv4. Jul 05 01:01:50 volumio-aiwa ntpd[812]: Listen normally on 5 wlan0 169.254.221.25:123 Jul 05 01:01:50 volumio-aiwa ntpd[812]: new interface(s) found: waking up resolver Jul 05 01:01:51 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:01:56 volumio-aiwa volumio[18623]: info: Volumio Network Manager: Network status updated: 2 Jul 05 01:01:57 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:02:04 volumio-aiwa dhcpcd[931]: wlan0: carrier lost Jul 05 01:02:04 volumio-aiwa wpa_supplicant[919]: wlan0: Associated with 8c:90:2d:b5:24:f6 Jul 05 01:02:04 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-CONNECTED - Connection to 8c:90:2d:b5:24:f6 completed [id=0 id_str=] Jul 05 01:02:04 volumio-aiwa wpa_supplicant[919]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 05 01:02:04 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:04-03:00" level=debug msg="renewing login5 access token" Jul 05 01:02:04 volumio-aiwa avahi-daemon[650]: Withdrawing address record for 169.254.221.25 on wlan0. Jul 05 01:02:04 volumio-aiwa avahi-daemon[650]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.221.25. Jul 05 01:02:04 volumio-aiwa dhcpcd[931]: wlan0: deleting route to 169.254.0.0/16 Jul 05 01:02:04 volumio-aiwa dhcpcd[931]: wlan0: deleting default route Jul 05 01:02:04 volumio-aiwa avahi-daemon[650]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 05 01:02:04 volumio-aiwa volumio[18623]: info: Discovery: A device disappeared from network Jul 05 01:02:04 volumio-aiwa dhcpcd[931]: wlan0: carrier acquired Jul 05 01:02:04 volumio-aiwa dhcpcd[931]: wlan0: IAID 32:e7:c9:a9 Jul 05 01:02:04 volumio-aiwa dhcpcd[931]: wlan0: soliciting an IPv6 router Jul 05 01:02:05 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:02:05 volumio-aiwa dhcpcd[931]: wlan0: soliciting a DHCP lease Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:05 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:05-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:06 volumio-aiwa dhcpcd[931]: wlan0: offered 192.168.68.76 from 192.168.68.1 Jul 05 01:02:06 volumio-aiwa dhcpcd[931]: wlan0: probing address 192.168.68.76/22 Jul 05 01:02:06 volumio-aiwa volumio[18623]: info: Volumio Network Manager: Network status updated: 0 Jul 05 01:02:06 volumio-aiwa ntpd[812]: Deleting interface #5 wlan0, 169.254.221.25#123, interface stats: received=0, sent=6, dropped=0, active_time=16 secs Jul 05 01:02:06 volumio-aiwa ntpd[812]: 200.11.116.10 local addr 169.254.221.25 -> Jul 05 01:02:06 volumio-aiwa ntpd[812]: 156.244.54.121 local addr 169.254.221.25 -> Jul 05 01:02:06 volumio-aiwa ntpd[812]: 162.159.200.123 local addr 169.254.221.25 -> Jul 05 01:02:06 volumio-aiwa ntpd[812]: 162.159.200.1 local addr 169.254.221.25 -> Jul 05 01:02:06 volumio-aiwa ntpd[812]: 190.103.0.13 local addr 169.254.221.25 -> Jul 05 01:02:06 volumio-aiwa ntpd[812]: 170.210.222.10 local addr 169.254.221.25 -> Jul 05 01:02:07 volumio-aiwa ntpd[812]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:09-03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 05 01:02:09 volumio-aiwa volumio[18623]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Jul 05 01:02:10 volumio-aiwa dhcpcd[931]: wlan0: leased 192.168.68.76 for 7200 seconds Jul 05 01:02:10 volumio-aiwa avahi-daemon[650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.76. Jul 05 01:02:10 volumio-aiwa dhcpcd[931]: wlan0: adding route to 192.168.68.0/22 Jul 05 01:02:10 volumio-aiwa dhcpcd[931]: wlan0: adding default route via 192.168.68.1 Jul 05 01:02:10 volumio-aiwa avahi-daemon[650]: New relevant interface wlan0.IPv4 for mDNS. Jul 05 01:02:10 volumio-aiwa avahi-daemon[650]: Registering new address record for 192.168.68.76 on wlan0.IPv4. Jul 05 01:02:11 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:02:11 volumio-aiwa volumio[18623]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 01:02:12 volumio-aiwa ntpd[812]: Listen normally on 6 wlan0 192.168.68.76:123 Jul 05 01:02:12 volumio-aiwa ntpd[812]: new interface(s) found: waking up resolver Jul 05 01:02:14 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:14-03:00" level=error msg="did not receive last pong from dealer, 180s passed" Jul 05 01:02:16 volumio-aiwa volumio[18623]: info: Volumio Network Manager: Network status updated: 2 Jul 05 01:02:17 volumio-aiwa wpa_supplicant[919]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 01:02:20 volumio-aiwa go-librespot[19059]: time="2025-07-05T01:02:20-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 05 01:02:21 volumio-aiwa volumio[18623]: info: Discovery: adding ad4d1d14-6ae4-4ae1-9227-5e1cb5c4d51b Jul 05 01:02:21 volumio-aiwa volumio[18623]: info: Discovery: Found device Volumio-Aiwa Jul 05 01:02:21 volumio-aiwa volumio[18623]: info: CoreCommandRouter::volumioGetState Jul 05 01:02:21 volumio-aiwa volumio[18623]: info: CorePlayQueue::getTrack 0 Jul 05 01:02:21 volumio-aiwa volumio[18623]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 01:02:21 volumio-aiwa volumio[18623]: TypeError: Cannot read property 'name' of undefined Jul 05 01:02:21 volumio-aiwa volumio[18623]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:272:40) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at Browser.emit (events.js:315:20) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jul 05 01:02:21 volumio-aiwa volumio[18623]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jul 05 01:02:21 volumio-aiwa volumio[18623]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jul 05 01:02:21 volumio-aiwa volumio[18623]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 01:02:22 volumio-aiwa sudo[24261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 01:01 Jul 05 01:02:22 volumio-aiwa sudo[24261]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"