Dec 29 13:08:00 music---petes-salon dhcpcd[759]: wlan0: soliciting a DHCP lease Dec 29 13:08:00 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:00 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:01 music---petes-salon ntpd[818]: IO: Listen normally on 3 wlan0 192.168.211.1:123 Dec 29 13:08:01 music---petes-salon ntpd[818]: IO: new interface(s) found: waking up resolver Dec 29 13:08:01 music---petes-salon ntpd[818]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:08:01 music---petes-salon ntpd[818]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 29 13:08:01 music---petes-salon ntpd[818]: DNS: dns_check: DNS error: -11, System error Dec 29 13:08:01 music---petes-salon ntpd[818]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 29 13:08:01 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Dec 29 13:08:01 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:01 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:01 music---petes-salon go-librespot[1913]: go-librespot daemon starting... Dec 29 13:08:01 music---petes-salon go-librespot[1914]: time="2025-12-29T13:08:01+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:01 music---petes-salon go-librespot[1914]: time="2025-12-29T13:08:01+01:00" level=debug msg="app state loaded" Dec 29 13:08:01 music---petes-salon go-librespot[1914]: time="2025-12-29T13:08:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:01 music---petes-salon go-librespot[1914]: time="2025-12-29T13:08:01+01: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" Dec 29 13:08:01 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:01 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:02 music---petes-salon ntpd[818]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:08:02 music---petes-salon ntpd[818]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 29 13:08:02 music---petes-salon ntpd[818]: DNS: dns_check: DNS error: -11, System error Dec 29 13:08:02 music---petes-salon ntpd[818]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 29 13:08:03 music---petes-salon ntpd[818]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:08:03 music---petes-salon ntpd[818]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 29 13:08:03 music---petes-salon ntpd[818]: DNS: dns_check: DNS error: -11, System error Dec 29 13:08:03 music---petes-salon ntpd[818]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 29 13:08:03 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:03 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:04 music---petes-salon ntpd[818]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:08:04 music---petes-salon ntpd[818]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 29 13:08:04 music---petes-salon ntpd[818]: DNS: dns_check: DNS error: -11, System error Dec 29 13:08:04 music---petes-salon ntpd[818]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 29 13:08:04 music---petes-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 29 13:08:04 music---petes-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 29 13:08:04 music---petes-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 29 13:08:04 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 29 13:08:04 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:04 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:04 music---petes-salon go-librespot[1955]: go-librespot daemon starting... Dec 29 13:08:04 music---petes-salon go-librespot[1959]: time="2025-12-29T13:08:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:04 music---petes-salon go-librespot[1959]: time="2025-12-29T13:08:04+01:00" level=debug msg="app state loaded" Dec 29 13:08:04 music---petes-salon go-librespot[1959]: time="2025-12-29T13:08:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:04 music---petes-salon go-librespot[1959]: time="2025-12-29T13:08:04+01: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" Dec 29 13:08:04 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:04 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:06 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:06 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:08 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 29 13:08:08 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:08 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:08 music---petes-salon go-librespot[1965]: go-librespot daemon starting... Dec 29 13:08:08 music---petes-salon go-librespot[1966]: time="2025-12-29T13:08:08+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:08 music---petes-salon go-librespot[1966]: time="2025-12-29T13:08:08+01:00" level=debug msg="app state loaded" Dec 29 13:08:08 music---petes-salon go-librespot[1966]: time="2025-12-29T13:08:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:08 music---petes-salon go-librespot[1966]: time="2025-12-29T13:08:08+01: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" Dec 29 13:08:08 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:08 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:09 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:09 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:11 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Dec 29 13:08:11 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:11 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:11 music---petes-salon go-librespot[1972]: go-librespot daemon starting... Dec 29 13:08:11 music---petes-salon go-librespot[1973]: time="2025-12-29T13:08:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:11 music---petes-salon go-librespot[1973]: time="2025-12-29T13:08:11+01:00" level=debug msg="app state loaded" Dec 29 13:08:11 music---petes-salon go-librespot[1973]: time="2025-12-29T13:08:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:11 music---petes-salon go-librespot[1973]: time="2025-12-29T13:08:11+01: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" Dec 29 13:08:11 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:11 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:12 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:12 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:14 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Dec 29 13:08:14 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:14 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:14 music---petes-salon go-librespot[1994]: go-librespot daemon starting... Dec 29 13:08:14 music---petes-salon go-librespot[1995]: time="2025-12-29T13:08:14+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:14 music---petes-salon go-librespot[1995]: time="2025-12-29T13:08:14+01:00" level=debug msg="app state loaded" Dec 29 13:08:14 music---petes-salon go-librespot[1995]: time="2025-12-29T13:08:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:14 music---petes-salon go-librespot[1995]: time="2025-12-29T13:08:14+01: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" Dec 29 13:08:14 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:14 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:15 music---petes-salon upmpdcli[2001]: writing RSA key Dec 29 13:08:15 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:15 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:17 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Dec 29 13:08:17 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:17 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:17 music---petes-salon go-librespot[2005]: go-librespot daemon starting... Dec 29 13:08:17 music---petes-salon go-librespot[2006]: time="2025-12-29T13:08:17+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:17 music---petes-salon go-librespot[2006]: time="2025-12-29T13:08:17+01:00" level=debug msg="app state loaded" Dec 29 13:08:17 music---petes-salon go-librespot[2006]: time="2025-12-29T13:08:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:17 music---petes-salon go-librespot[2006]: time="2025-12-29T13:08:17+01: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" Dec 29 13:08:17 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:17 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:18 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:18 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:21 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Dec 29 13:08:21 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:21 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:21 music---petes-salon go-librespot[2012]: go-librespot daemon starting... Dec 29 13:08:21 music---petes-salon go-librespot[2013]: time="2025-12-29T13:08:21+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:21 music---petes-salon go-librespot[2013]: time="2025-12-29T13:08:21+01:00" level=debug msg="app state loaded" Dec 29 13:08:21 music---petes-salon go-librespot[2013]: time="2025-12-29T13:08:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:21 music---petes-salon go-librespot[2013]: time="2025-12-29T13:08:21+01: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" Dec 29 13:08:21 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:21 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:21 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:21 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:24 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Dec 29 13:08:24 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:24 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:24 music---petes-salon go-librespot[2036]: go-librespot daemon starting... Dec 29 13:08:24 music---petes-salon go-librespot[2037]: time="2025-12-29T13:08:24+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:24 music---petes-salon go-librespot[2037]: time="2025-12-29T13:08:24+01:00" level=debug msg="app state loaded" Dec 29 13:08:24 music---petes-salon go-librespot[2037]: time="2025-12-29T13:08:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:24 music---petes-salon go-librespot[2037]: time="2025-12-29T13:08:24+01: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" Dec 29 13:08:24 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:24 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:24 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:24 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:27 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Dec 29 13:08:27 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:27 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:27 music---petes-salon go-librespot[2044]: go-librespot daemon starting... Dec 29 13:08:27 music---petes-salon go-librespot[2045]: time="2025-12-29T13:08:27+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:27 music---petes-salon go-librespot[2045]: time="2025-12-29T13:08:27+01:00" level=debug msg="app state loaded" Dec 29 13:08:27 music---petes-salon go-librespot[2045]: time="2025-12-29T13:08:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:27 music---petes-salon go-librespot[2045]: time="2025-12-29T13:08:27+01: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" Dec 29 13:08:27 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:27 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:27 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:27 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:28 music---petes-salon ifplugd(eth0)[853]: Link beat detected. Dec 29 13:08:28 music---petes-salon ifplugd(eth0)[853]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 29 13:08:28 music---petes-salon wireless.js[669]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 29 13:08:28 music---petes-salon wireless.js[669]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 29 13:08:28 music---petes-salon wireless.js[669]: WIRELESS.JS: Cleaning previous... Dec 29 13:08:28 music---petes-salon dhcpcd[759]: eth0: carrier acquired Dec 29 13:08:28 music---petes-salon kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control off Dec 29 13:08:28 music---petes-salon dhcpcd[759]: eth0: IAID eb:f9:a5:ca Dec 29 13:08:28 music---petes-salon dhcpcd[759]: ps_ctl_dispatch: cannot handle another client Dec 29 13:08:28 music---petes-salon ifplugd(eth0)[853]: client: sending commands to dhcpcd process Dec 29 13:08:28 music---petes-salon dhcpcd[759]: control_free: No such file or directory Dec 29 13:08:28 music---petes-salon systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 29 13:08:28 music---petes-salon dnsmasq[1908]: exiting on receipt of SIGTERM Dec 29 13:08:28 music---petes-salon systemd[1]: dnsmasq.service: Deactivated successfully. Dec 29 13:08:28 music---petes-salon systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 29 13:08:28 music---petes-salon systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 29 13:08:28 music---petes-salon dhcpcd[759]: eth0: soliciting an IPv6 router Dec 29 13:08:28 music---petes-salon ifplugd(eth0)[853]: Program executed successfully. Dec 29 13:08:28 music---petes-salon systemd[1]: hostapd.service: Deactivated successfully. Dec 29 13:08:28 music---petes-salon systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 29 13:08:28 music---petes-salon sudo[2103]: root : unable to resolve host music---petes-salon: System error Dec 29 13:08:28 music---petes-salon sudo[2103]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 29 13:08:28 music---petes-salon sudo[2103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 29 13:08:28 music---petes-salon dhcpcd[759]: wlan0: carrier lost Dec 29 13:08:28 music---petes-salon avahi-daemon[644]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 29 13:08:28 music---petes-salon avahi-daemon[644]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 29 13:08:28 music---petes-salon avahi-daemon[644]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 29 13:08:28 music---petes-salon sudo[2103]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:29 music---petes-salon volumio[1056]: info: Discovery: A device disappeared from network Dec 29 13:08:29 music---petes-salon sudo[2110]: root : unable to resolve host music---petes-salon: System error Dec 29 13:08:29 music---petes-salon sudo[2110]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 29 13:08:29 music---petes-salon sudo[2110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 29 13:08:29 music---petes-salon systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 29 13:08:29 music---petes-salon systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 29 13:08:29 music---petes-salon systemd[1]: welcome.service: Deactivated successfully. Dec 29 13:08:29 music---petes-salon systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 29 13:08:29 music---petes-salon systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 29 13:08:29 music---petes-salon systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 29 13:08:29 music---petes-salon welcome[2122]: Resolved ip:[0] Dec 29 13:08:29 music---petes-salon sudo[2110]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:29 music---petes-salon systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 29 13:08:29 music---petes-salon systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 29 13:08:29 music---petes-salon dhcpcd[759]: eth0: soliciting a DHCP lease Dec 29 13:08:29 music---petes-salon wireless.js[669]: WIRELESS.JS: Stopped aP Dec 29 13:08:29 music---petes-salon sudo[2134]: volumio : unable to resolve host music---petes-salon: System error Dec 29 13:08:29 music---petes-salon sudo[2134]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 29 13:08:29 music---petes-salon sudo[2134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 13:08:30 music---petes-salon sudo[2134]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:30 music---petes-salon kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 29 13:08:30 music---petes-salon sudo[2136]: volumio : unable to resolve host music---petes-salon: System error Dec 29 13:08:30 music---petes-salon sudo[2136]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 29 13:08:30 music---petes-salon sudo[2136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 13:08:30 music---petes-salon sudo[2136]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:30 music---petes-salon wireless.js[669]: sudo: unable to resolve host music---petes-salon: System error Dec 29 13:08:30 music---petes-salon wireless.js[669]: sudo: unable to resolve host music---petes-salon: System error Dec 29 13:08:30 music---petes-salon sudo[2144]: volumio : unable to resolve host music---petes-salon: System error Dec 29 13:08:30 music---petes-salon sudo[2144]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 29 13:08:30 music---petes-salon sudo[2144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 13:08:30 music---petes-salon sudo[2144]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:30 music---petes-salon sudo[2146]: volumio : unable to resolve host music---petes-salon: System error Dec 29 13:08:30 music---petes-salon sudo[2146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 29 13:08:30 music---petes-salon sudo[2146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 13:08:30 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Dec 29 13:08:30 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:30 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:30 music---petes-salon go-librespot[2150]: go-librespot daemon starting... Dec 29 13:08:30 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:30 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:30 music---petes-salon go-librespot[2151]: time="2025-12-29T13:08:30+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:30 music---petes-salon go-librespot[2151]: time="2025-12-29T13:08:30+01:00" level=debug msg="app state loaded" Dec 29 13:08:30 music---petes-salon go-librespot[2151]: time="2025-12-29T13:08:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:30 music---petes-salon go-librespot[2151]: time="2025-12-29T13:08:30+01: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" Dec 29 13:08:30 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:30 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:31 music---petes-salon ntpd[818]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=30 secs Dec 29 13:08:32 music---petes-salon sudo[2146]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:32 music---petes-salon wireless.js[669]: sudo: unable to resolve host music---petes-salon: System error Dec 29 13:08:32 music---petes-salon wireless.js[669]: sudo: unable to resolve host music---petes-salon: System error Dec 29 13:08:32 music---petes-salon wireless.js[669]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: ES Dec 29 13:08:32 music---petes-salon sudo[2159]: volumio : unable to resolve host music---petes-salon: System error Dec 29 13:08:33 music---petes-salon sudo[2159]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 29 13:08:33 music---petes-salon sudo[2159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 13:08:33 music---petes-salon sudo[2159]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:33 music---petes-salon sudo[2162]: volumio : unable to resolve host music---petes-salon: System error Dec 29 13:08:33 music---petes-salon sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set ES Dec 29 13:08:33 music---petes-salon sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 13:08:33 music---petes-salon sudo[2162]: pam_unix(sudo:session): session closed for user root Dec 29 13:08:33 music---petes-salon wireless.js[669]: sudo: unable to resolve host music---petes-salon: System error Dec 29 13:08:33 music---petes-salon wireless.js[669]: sudo: unable to resolve host music---petes-salon: System error Dec 29 13:08:33 music---petes-salon wireless.js[669]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: ES Dec 29 13:08:33 music---petes-salon wireless.js[669]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 29 13:08:33 music---petes-salon wireless.js[669]: WIRELESS.JS: Notified systemd about wireless ready Dec 29 13:08:33 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:33 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:34 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Dec 29 13:08:34 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:34 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:34 music---petes-salon go-librespot[2180]: go-librespot daemon starting... Dec 29 13:08:34 music---petes-salon go-librespot[2181]: time="2025-12-29T13:08:34+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:34 music---petes-salon go-librespot[2181]: time="2025-12-29T13:08:34+01:00" level=debug msg="app state loaded" Dec 29 13:08:34 music---petes-salon go-librespot[2181]: time="2025-12-29T13:08:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:34 music---petes-salon go-librespot[2181]: time="2025-12-29T13:08:34+01: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" Dec 29 13:08:34 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:34 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:36 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:36 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:37 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Dec 29 13:08:37 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:37 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:37 music---petes-salon go-librespot[2187]: go-librespot daemon starting... Dec 29 13:08:37 music---petes-salon dhcpcd[759]: eth0: carrier lost Dec 29 13:08:37 music---petes-salon kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Dec 29 13:08:37 music---petes-salon go-librespot[2188]: time="2025-12-29T13:08:37+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:37 music---petes-salon go-librespot[2188]: time="2025-12-29T13:08:37+01:00" level=debug msg="app state loaded" Dec 29 13:08:37 music---petes-salon go-librespot[2188]: time="2025-12-29T13:08:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:37 music---petes-salon go-librespot[2188]: time="2025-12-29T13:08:37+01: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" Dec 29 13:08:37 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:37 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:37 music---petes-salon ifplugd(eth0)[853]: Link beat lost. Dec 29 13:08:39 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:39 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:40 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Dec 29 13:08:40 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:40 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:40 music---petes-salon go-librespot[2208]: go-librespot daemon starting... Dec 29 13:08:40 music---petes-salon go-librespot[2209]: time="2025-12-29T13:08:40+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:40 music---petes-salon go-librespot[2209]: time="2025-12-29T13:08:40+01:00" level=debug msg="app state loaded" Dec 29 13:08:40 music---petes-salon go-librespot[2209]: time="2025-12-29T13:08:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:40 music---petes-salon go-librespot[2209]: time="2025-12-29T13:08:40+01: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" Dec 29 13:08:40 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:40 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:42 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:42 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:44 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Dec 29 13:08:44 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:44 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:44 music---petes-salon go-librespot[2232]: go-librespot daemon starting... Dec 29 13:08:44 music---petes-salon go-librespot[2233]: time="2025-12-29T13:08:44+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:44 music---petes-salon go-librespot[2233]: time="2025-12-29T13:08:44+01:00" level=debug msg="app state loaded" Dec 29 13:08:44 music---petes-salon go-librespot[2233]: time="2025-12-29T13:08:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:44 music---petes-salon go-librespot[2233]: time="2025-12-29T13:08:44+01: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" Dec 29 13:08:44 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:44 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:45 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:45 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:47 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Dec 29 13:08:47 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:47 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:47 music---petes-salon go-librespot[2239]: go-librespot daemon starting... Dec 29 13:08:47 music---petes-salon go-librespot[2240]: time="2025-12-29T13:08:47+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:47 music---petes-salon go-librespot[2240]: time="2025-12-29T13:08:47+01:00" level=debug msg="app state loaded" Dec 29 13:08:47 music---petes-salon go-librespot[2240]: time="2025-12-29T13:08:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:47 music---petes-salon go-librespot[2240]: time="2025-12-29T13:08:47+01: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" Dec 29 13:08:47 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:47 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:47 music---petes-salon ifplugd(eth0)[853]: Link beat detected. Dec 29 13:08:48 music---petes-salon dhcpcd[759]: eth0: carrier acquired Dec 29 13:08:48 music---petes-salon kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 29 13:08:48 music---petes-salon dhcpcd[759]: eth0: IAID eb:f9:a5:ca Dec 29 13:08:48 music---petes-salon dhcpcd[759]: eth0: soliciting an IPv6 router Dec 29 13:08:48 music---petes-salon dhcpcd[759]: eth0: soliciting a DHCP lease Dec 29 13:08:48 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:49 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:50 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Dec 29 13:08:50 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:50 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:50 music---petes-salon go-librespot[2248]: go-librespot daemon starting... Dec 29 13:08:50 music---petes-salon go-librespot[2249]: time="2025-12-29T13:08:50+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:50 music---petes-salon go-librespot[2249]: time="2025-12-29T13:08:50+01:00" level=debug msg="app state loaded" Dec 29 13:08:50 music---petes-salon go-librespot[2249]: time="2025-12-29T13:08:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:50 music---petes-salon go-librespot[2249]: time="2025-12-29T13:08:50+01: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" Dec 29 13:08:50 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:50 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:52 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:52 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:53 music---petes-salon dhcpcd[759]: eth0: offered 192.168.1.10 from 192.168.1.1 Dec 29 13:08:53 music---petes-salon dhcpcd[759]: eth0: probing address 192.168.1.10/24 Dec 29 13:08:53 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Dec 29 13:08:53 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:53 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:53 music---petes-salon go-librespot[2270]: go-librespot daemon starting... Dec 29 13:08:53 music---petes-salon go-librespot[2271]: time="2025-12-29T13:08:53+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:53 music---petes-salon go-librespot[2271]: time="2025-12-29T13:08:53+01:00" level=debug msg="app state loaded" Dec 29 13:08:53 music---petes-salon go-librespot[2271]: time="2025-12-29T13:08:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:53 music---petes-salon go-librespot[2271]: time="2025-12-29T13:08:53+01: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" Dec 29 13:08:53 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:53 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:55 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:55 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:57 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Dec 29 13:08:57 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:57 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:08:57 music---petes-salon go-librespot[2278]: go-librespot daemon starting... Dec 29 13:08:57 music---petes-salon go-librespot[2279]: time="2025-12-29T13:08:57+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:08:57 music---petes-salon go-librespot[2279]: time="2025-12-29T13:08:57+01:00" level=debug msg="app state loaded" Dec 29 13:08:57 music---petes-salon go-librespot[2279]: time="2025-12-29T13:08:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:08:57 music---petes-salon go-librespot[2279]: time="2025-12-29T13:08:57+01: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" Dec 29 13:08:57 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:08:57 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:08:58 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:08:58 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:08:58 music---petes-salon dhcpcd[759]: eth0: leased 192.168.1.10 for 86400 seconds Dec 29 13:08:58 music---petes-salon avahi-daemon[644]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.10. Dec 29 13:08:58 music---petes-salon avahi-daemon[644]: New relevant interface eth0.IPv4 for mDNS. Dec 29 13:08:58 music---petes-salon avahi-daemon[644]: Registering new address record for 192.168.1.10 on eth0.IPv4. Dec 29 13:08:58 music---petes-salon dhcpcd[759]: eth0: adding route to 192.168.1.0/24 Dec 29 13:08:58 music---petes-salon dhcpcd[759]: eth0: adding default route via 192.168.1.1 Dec 29 13:08:58 music---petes-salon systemd[1]: welcome.service: Deactivated successfully. Dec 29 13:08:58 music---petes-salon systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 29 13:08:58 music---petes-salon systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 29 13:08:58 music---petes-salon systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 29 13:08:58 music---petes-salon welcome[2300]: Resolved ip:[1] 192.168.1.10 Dec 29 13:08:58 music---petes-salon systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 29 13:08:58 music---petes-salon systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 29 13:08:59 music---petes-salon volumio[1056]: info: Discovery: adding 72e72c9d-c70f-485c-bb7c-d9a10c7238d9 Dec 29 13:08:59 music---petes-salon volumio[1056]: info: Discovery: Found device Music - Pete's Salon Dec 29 13:08:59 music---petes-salon volumio[1056]: info: CoreCommandRouter::volumioGetState Dec 29 13:08:59 music---petes-salon volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 29 13:09:00 music---petes-salon ntpd[818]: IO: Listen normally on 4 eth0 192.168.1.10:123 Dec 29 13:09:00 music---petes-salon ntpd[818]: IO: new interface(s) found: waking up resolver Dec 29 13:09:00 music---petes-salon ntpd[818]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:09:00 music---petes-salon ntpd[818]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 29 13:09:00 music---petes-salon ntpd[818]: DNS: Pool taking: 10.0.0.1 Dec 29 13:09:00 music---petes-salon ntpd[818]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 29 13:09:00 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Dec 29 13:09:00 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:00 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:00 music---petes-salon go-librespot[2312]: go-librespot daemon starting... Dec 29 13:09:00 music---petes-salon go-librespot[2313]: time="2025-12-29T13:09:00+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:09:00 music---petes-salon go-librespot[2313]: time="2025-12-29T13:09:00+01:00" level=debug msg="app state loaded" Dec 29 13:09:00 music---petes-salon go-librespot[2313]: time="2025-12-29T13:09:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:09:00 music---petes-salon go-librespot[2313]: time="2025-12-29T13:09:00+01: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\": remote error: tls: handshake failure" Dec 29 13:09:00 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:09:00 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:09:01 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:09:01 music---petes-salon volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 13:09:01 music---petes-salon ntpd[818]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:09:01 music---petes-salon ntpd[818]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 29 13:09:01 music---petes-salon ntpd[818]: DNS: Pool skipping: 10.0.0.1 Dec 29 13:09:01 music---petes-salon ntpd[818]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 29 13:09:02 music---petes-salon ntpd[818]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:09:02 music---petes-salon ntpd[818]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 29 13:09:02 music---petes-salon ntpd[818]: DNS: Pool skipping: 10.0.0.1 Dec 29 13:09:02 music---petes-salon ntpd[818]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 29 13:09:03 music---petes-salon ntpd[818]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 13:09:03 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Dec 29 13:09:03 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:03 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:03 music---petes-salon go-librespot[2325]: go-librespot daemon starting... Dec 29 13:09:03 music---petes-salon go-librespot[2330]: time="2025-12-29T13:09:03+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:09:03 music---petes-salon go-librespot[2330]: time="2025-12-29T13:09:03+01:00" level=debug msg="app state loaded" Dec 29 13:09:03 music---petes-salon go-librespot[2330]: time="2025-12-29T13:09:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:09:03 music---petes-salon volumio[1056]: info: Volumio Network Manager: Network status updated: 1 Dec 29 13:09:04 music---petes-salon volumio[1056]: info: Initializing connection to go-librespot Websocket Dec 29 13:09:04 music---petes-salon go-librespot[2330]: time="2025-12-29T13:09:04+01:00" level=debug msg="new websocket client" Dec 29 13:09:04 music---petes-salon volumio[1056]: info: Connection to go-librespot Websocket established Dec 29 13:09:07 music---petes-salon volumio[1056]: info: Getting Spotify volume Dec 29 13:09:14 music---petes-salon ntpd[818]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 29 13:09:14 music---petes-salon ntpd[818]: DNS: dns_check: DNS error: -11, System error Dec 29 13:09:14 music---petes-salon ntpd[818]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 29 13:09:14 music---petes-salon volumio[1056]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 29 13:09:21 music---petes-salon volumio[1056]: info: CoreCommandRouter::volumioGetState Dec 29 13:09:21 music---petes-salon volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 29 13:09:21 music---petes-salon volumio[1056]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Dec 29 13:09:21 music---petes-salon volumio[1056]: SPOTIFY: SPOTIFY VOLUME undefined Dec 29 13:09:21 music---petes-salon volumio[1056]: SPOTIFY: VOLUMIO VOLUME 85 Dec 29 13:09:21 music---petes-salon volumio[1056]: info: Aligning Spotify Volume to Volumio Volume Dec 29 13:09:21 music---petes-salon volumio[1056]: info: CoreCommandRouter::volumioGetState Dec 29 13:09:21 music---petes-salon volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 29 13:09:21 music---petes-salon volumio[1056]: info: Setting Spotify Volume from Volumio: 85 Dec 29 13:09:22 music---petes-salon volumio[1056]: SPOTIFY: SETTING SPOTIFY VOLUME 85 Dec 29 13:09:22 music---petes-salon volumio[1056]: info: Sending Spotify command with payload to local API: /player/volume Dec 29 13:09:26 music---petes-salon go-librespot[2330]: time="2025-12-29T13:09:26+01: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" Dec 29 13:09:26 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:09:26 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:09:26 music---petes-salon volumio[1056]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Dec 29 13:09:26 music---petes-salon volumio[1056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 13:09:26 music---petes-salon volumio[1056]: Error: socket hang up Dec 29 13:09:26 music---petes-salon volumio[1056]: at connResetException (node:internal/errors:720:14) Dec 29 13:09:26 music---petes-salon volumio[1056]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 29 13:09:26 music---petes-salon volumio[1056]: at Socket.emit (node:events:526:35) Dec 29 13:09:26 music---petes-salon volumio[1056]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 29 13:09:26 music---petes-salon volumio[1056]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 29 13:09:26 music---petes-salon volumio[1056]: code: 'ECONNRESET', Dec 29 13:09:26 music---petes-salon volumio[1056]: response: undefined Dec 29 13:09:26 music---petes-salon volumio[1056]: } Dec 29 13:09:26 music---petes-salon volumio[1056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 13:09:29 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Dec 29 13:09:29 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:29 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:29 music---petes-salon go-librespot[2389]: go-librespot daemon starting... Dec 29 13:09:29 music---petes-salon go-librespot[2390]: time="2025-12-29T13:09:29+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:09:29 music---petes-salon go-librespot[2390]: time="2025-12-29T13:09:29+01:00" level=debug msg="app state loaded" Dec 29 13:09:29 music---petes-salon go-librespot[2390]: time="2025-12-29T13:09:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 13:09:29 music---petes-salon go-librespot[2390]: time="2025-12-29T13:09:29+01: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\": remote error: tls: handshake failure" Dec 29 13:09:29 music---petes-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 13:09:29 music---petes-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 13:09:32 music---petes-salon sudo[2398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 13:08' Dec 29 13:09:32 music---petes-salon sudo[2398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 13:09:33 music---petes-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Dec 29 13:09:33 music---petes-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:33 music---petes-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 13:09:33 music---petes-salon go-librespot[2400]: go-librespot daemon starting... Dec 29 13:09:33 music---petes-salon go-librespot[2401]: time="2025-12-29T13:09:33+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 13:09:33 music---petes-salon go-librespot[2401]: time="2025-12-29T13:09:33+01:00" level=debug msg="app state loaded" Dec 29 13:09:33 music---petes-salon go-librespot[2401]: time="2025-12-29T13:09:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"