Jan 10 18:14:02 spottari systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 10 18:14:02 spottari systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:02 spottari systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:02 spottari go-librespot[1755]: go-librespot daemon starting... Jan 10 18:14:02 spottari go-librespot[1756]: time="2026-01-10T18:14:02+02:00" level=info msg="running go-librespot 0.4.0" Jan 10 18:14:02 spottari go-librespot[1756]: time="2026-01-10T18:14:02+02:00" level=debug msg="app state loaded" Jan 10 18:14:02 spottari go-librespot[1756]: time="2026-01-10T18:14:02+02:00" level=debug msg="stored credentials not found" Jan 10 18:14:02 spottari go-librespot[1756]: time="2026-01-10T18:14:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 18:14:02 spottari go-librespot[1756]: time="2026-01-10T18:14:02+02:00" level=fatal msg="failed running zeroconf" 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" Jan 10 18:14:02 spottari systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 18:14:02 spottari systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 18:14:03 spottari wpa_supplicant[932]: wlan0: Failed to initiate sched scan Jan 10 18:14:06 spottari systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 10 18:14:06 spottari systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:06 spottari systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:06 spottari go-librespot[1781]: go-librespot daemon starting... Jan 10 18:14:20 spottari wpa_supplicant[932]: wlan0: Trying to associate with 6c:5a:b0:88:db:16 (SSID='TP-Link_DB16' freq=2462 MHz) Jan 10 18:14:20 spottari wpa_supplicant[932]: wlan0: Associated with 6c:5a:b0:88:db:16 Jan 10 18:14:20 spottari wpa_supplicant[932]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 10 18:14:21 spottari wpa_supplicant[932]: wlan0: WPA: Key negotiation completed with 6c:5a:b0:88:db:16 [PTK=CCMP GTK=CCMP] Jan 10 18:14:21 spottari wpa_supplicant[932]: wlan0: CTRL-EVENT-CONNECTED - Connection to 6c:5a:b0:88:db:16 completed [id=0 id_str=] Jan 10 18:14:21 spottari dhcpcd[691]: wlan0: carrier acquired Jan 10 18:14:21 spottari go-librespot[1787]: time="2026-01-10T18:14:21+02:00" level=info msg="running go-librespot 0.4.0" Jan 10 18:14:22 spottari go-librespot[1787]: time="2026-01-10T18:14:22+02:00" level=debug msg="app state loaded" Jan 10 18:14:22 spottari go-librespot[1787]: time="2026-01-10T18:14:22+02:00" level=debug msg="stored credentials not found" Jan 10 18:14:22 spottari go-librespot[1787]: time="2026-01-10T18:14:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 18:14:22 spottari go-librespot[1787]: time="2026-01-10T18:14:22+02:00" level=fatal msg="failed running zeroconf" 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" Jan 10 18:14:22 spottari systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 18:14:22 spottari systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 18:14:23 spottari dhcpcd[691]: wlan0: IAID 67:f8:37:ef Jan 10 18:14:24 spottari dhcpcd[691]: wlan0: soliciting an IPv6 router Jan 10 18:14:25 spottari dhcpcd[691]: wlan0: soliciting a DHCP lease Jan 10 18:14:25 spottari dhcpcd[691]: wlan0: offered 192.168.1.101 from 192.168.1.1 Jan 10 18:14:25 spottari systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jan 10 18:14:25 spottari systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:25 spottari systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:26 spottari dhcpcd[691]: wlan0: probing address 192.168.1.101/24 Jan 10 18:14:26 spottari go-librespot[1840]: go-librespot daemon starting... Jan 10 18:14:26 spottari go-librespot[1843]: time="2026-01-10T18:14:26+02:00" level=info msg="running go-librespot 0.4.0" Jan 10 18:14:26 spottari go-librespot[1843]: time="2026-01-10T18:14:26+02:00" level=debug msg="app state loaded" Jan 10 18:14:26 spottari go-librespot[1843]: time="2026-01-10T18:14:26+02:00" level=debug msg="stored credentials not found" Jan 10 18:14:26 spottari go-librespot[1843]: time="2026-01-10T18:14:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 18:14:26 spottari go-librespot[1843]: time="2026-01-10T18:14:26+02:00" level=fatal msg="failed running zeroconf" 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" Jan 10 18:14:26 spottari systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 18:14:26 spottari systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 18:14:29 spottari systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jan 10 18:14:29 spottari systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:29 spottari systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 18:14:37 spottari dhcpcd[691]: wlan0: leased 192.168.1.101 for 86400 seconds Jan 10 18:14:37 spottari dhcpcd[691]: wlan0: adding route to 192.168.1.0/24 Jan 10 18:14:37 spottari dhcpcd[691]: wlan0: adding default route via 192.168.1.1 Jan 10 18:14:37 spottari go-librespot[1851]: go-librespot daemon starting... Jan 10 18:14:38 spottari go-librespot[1854]: time="2026-01-10T18:14:38+02:00" level=info msg="running go-librespot 0.4.0" Jan 10 18:14:38 spottari go-librespot[1854]: time="2026-01-10T18:14:38+02:00" level=debug msg="app state loaded" Jan 10 18:14:38 spottari go-librespot[1854]: time="2026-01-10T18:14:38+02:00" level=debug msg="stored credentials not found" Jan 10 18:14:38 spottari go-librespot[1854]: time="2026-01-10T18:14:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 18:14:38 spottari avahi-daemon[1008]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.101. Jan 10 18:14:38 spottari avahi-daemon[1008]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 18:14:38 spottari avahi-daemon[1008]: Registering new address record for 192.168.1.101 on wlan0.IPv4. Jan 10 18:14:38 spottari ntpd[778]: IO: Listen normally on 4 wlan0 192.168.1.101:123 Jan 10 18:14:38 spottari ntpd[778]: IO: new interface(s) found: waking up resolver Jan 10 18:14:42 spottari go-librespot[1854]: time="2026-01-10T18:14:42+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 10 18:14:42 spottari go-librespot[1854]: time="2026-01-10T18:14:42+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 10 18:14:42 spottari go-librespot[1854]: time="2026-01-10T18:14:42+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 10 18:14:42 spottari go-librespot[1854]: time="2026-01-10T18:14:42+02:00" level=info msg="zeroconf server listening on port 45701" Jan 10 18:14:45 spottari systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 10 18:14:45 spottari systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 10 18:14:45 spottari systemd[1]: welcome.service: Deactivated successfully. Jan 10 18:14:45 spottari systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 10 18:14:45 spottari systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 10 18:14:45 spottari systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 10 18:14:46 spottari welcome[1897]: Resolved ip:[1] 192.168.1.101 Jan 10 18:14:46 spottari volumio-remote-updater[548]: [2026-01-10 18:14:46] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jan 10 18:14:46 spottari systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 10 18:14:46 spottari systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 10 18:14:52 spottari volumio-remote-updater[548]: [2026-01-10 18:14:51] [connect] Successful connection Jan 10 18:15:04 spottari volumio-remote-updater[548]: [2026-01-10 18:15:04] [connect] Successful connection Jan 10 18:15:16 spottari nmbd[825]: [2026/01/10 18:15:14.846210, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jan 10 18:15:28 spottari volumio-remote-updater[548]: [2026-01-10 18:15:19] [connect] Successful connection Jan 10 18:15:16 spottari nmbd[825]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Jan 10 18:15:31 spottari sudo[2001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 18:14' Jan 10 18:15:31 spottari sudo[2001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"