May 24 14:56:00 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 14:56:00 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 14:56:00 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 14:56:01 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. May 24 14:56:01 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:01 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:01 badezimmer go-librespot[1699]: go-librespot daemon starting... May 24 14:56:01 badezimmer go-librespot[1700]: time="2026-05-24T14:56:01+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:01 badezimmer go-librespot[1700]: time="2026-05-24T14:56:01+02:00" level=debug msg="app state loaded" May 24 14:56:01 badezimmer go-librespot[1700]: time="2026-05-24T14:56:01+02:00" level=debug msg="stored credentials not found" May 24 14:56:01 badezimmer go-librespot[1700]: time="2026-05-24T14:56:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:01 badezimmer go-librespot[1700]: time="2026-05-24T14:56:01+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" May 24 14:56:01 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:01 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:02 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:02 badezimmer volumio[1197]: info: BOOT COMPLETED May 24 14:56:02 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:04 badezimmer volumio[1197]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=5dafea73b3c95095330fa904af219d70" http://updates.volumio.org/downloader-v1/track-device May 24 14:56:04 badezimmer volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current May 24 14:56:04 badezimmer volumio[1197]: Dload Upload Total Spent Left Speed May 24 14:56:04 badezimmer volumio[1197]: [132B blob data] May 24 14:56:04 badezimmer volumio[1197]: retrying in 5 seconds, trial 2 May 24 14:56:04 badezimmer volumio[1197]: info: Volumio Calling Home May 24 14:56:04 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. May 24 14:56:04 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:04 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:04 badezimmer go-librespot[1725]: go-librespot daemon starting... May 24 14:56:04 badezimmer go-librespot[1726]: time="2026-05-24T14:56:04+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:04 badezimmer go-librespot[1726]: time="2026-05-24T14:56:04+02:00" level=debug msg="app state loaded" May 24 14:56:04 badezimmer go-librespot[1726]: time="2026-05-24T14:56:04+02:00" level=debug msg="stored credentials not found" May 24 14:56:04 badezimmer go-librespot[1726]: time="2026-05-24T14:56:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:04 badezimmer go-librespot[1726]: time="2026-05-24T14:56:04+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" May 24 14:56:04 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:04 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:05 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:05 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:07 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. May 24 14:56:07 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:07 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:07 badezimmer go-librespot[1734]: go-librespot daemon starting... May 24 14:56:07 badezimmer go-librespot[1735]: time="2026-05-24T14:56:07+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:07 badezimmer go-librespot[1735]: time="2026-05-24T14:56:07+02:00" level=debug msg="app state loaded" May 24 14:56:07 badezimmer go-librespot[1735]: time="2026-05-24T14:56:07+02:00" level=debug msg="stored credentials not found" May 24 14:56:07 badezimmer go-librespot[1735]: time="2026-05-24T14:56:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:07 badezimmer go-librespot[1735]: time="2026-05-24T14:56:07+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" May 24 14:56:07 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:07 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:08 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:08 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:10 badezimmer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. May 24 14:56:10 badezimmer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:10 badezimmer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:10 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. May 24 14:56:10 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:10 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:10 badezimmer go-librespot[1744]: go-librespot daemon starting... May 24 14:56:10 badezimmer upmpdcli[1743]: Could not open config: /tmp/upmpdcli.conf May 24 14:56:10 badezimmer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:10 badezimmer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 24 14:56:10 badezimmer go-librespot[1745]: time="2026-05-24T14:56:10+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:10 badezimmer go-librespot[1745]: time="2026-05-24T14:56:10+02:00" level=debug msg="app state loaded" May 24 14:56:10 badezimmer go-librespot[1745]: time="2026-05-24T14:56:10+02:00" level=debug msg="stored credentials not found" May 24 14:56:10 badezimmer go-librespot[1745]: time="2026-05-24T14:56:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:10 badezimmer go-librespot[1745]: time="2026-05-24T14:56:10+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" May 24 14:56:10 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:10 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:11 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:11 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:14 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:14 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. May 24 14:56:14 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:14 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:14 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:14 badezimmer go-librespot[1766]: go-librespot daemon starting... May 24 14:56:14 badezimmer go-librespot[1767]: time="2026-05-24T14:56:14+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:14 badezimmer go-librespot[1767]: time="2026-05-24T14:56:14+02:00" level=debug msg="app state loaded" May 24 14:56:14 badezimmer go-librespot[1767]: time="2026-05-24T14:56:14+02:00" level=debug msg="stored credentials not found" May 24 14:56:14 badezimmer go-librespot[1767]: time="2026-05-24T14:56:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:14 badezimmer go-librespot[1767]: time="2026-05-24T14:56:14+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" May 24 14:56:14 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:14 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:17 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:17 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:17 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. May 24 14:56:17 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:17 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:17 badezimmer go-librespot[1775]: go-librespot daemon starting... May 24 14:56:17 badezimmer go-librespot[1776]: time="2026-05-24T14:56:17+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:17 badezimmer go-librespot[1776]: time="2026-05-24T14:56:17+02:00" level=debug msg="app state loaded" May 24 14:56:17 badezimmer go-librespot[1776]: time="2026-05-24T14:56:17+02:00" level=debug msg="stored credentials not found" May 24 14:56:17 badezimmer go-librespot[1776]: time="2026-05-24T14:56:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:17 badezimmer go-librespot[1776]: time="2026-05-24T14:56:17+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" May 24 14:56:17 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:17 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:18 badezimmer dhcpcd[763]: eth0: offered 192.168.169.9 from 192.168.169.1 May 24 14:56:18 badezimmer dhcpcd[763]: eth0: probing address 192.168.169.9/24 May 24 14:56:20 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:20 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:20 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. May 24 14:56:20 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:21 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:21 badezimmer go-librespot[1784]: go-librespot daemon starting... May 24 14:56:21 badezimmer go-librespot[1785]: time="2026-05-24T14:56:21+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:21 badezimmer go-librespot[1785]: time="2026-05-24T14:56:21+02:00" level=debug msg="app state loaded" May 24 14:56:21 badezimmer go-librespot[1785]: time="2026-05-24T14:56:21+02:00" level=debug msg="stored credentials not found" May 24 14:56:21 badezimmer go-librespot[1785]: time="2026-05-24T14:56:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:21 badezimmer go-librespot[1785]: time="2026-05-24T14:56:21+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" May 24 14:56:21 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:21 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:22 badezimmer dhcpcd[763]: eth0: leased 192.168.169.9 for 864000 seconds May 24 14:56:22 badezimmer avahi-daemon[685]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.169.9. May 24 14:56:22 badezimmer avahi-daemon[685]: New relevant interface eth0.IPv4 for mDNS. May 24 14:56:22 badezimmer avahi-daemon[685]: Registering new address record for 192.168.169.9 on eth0.IPv4. May 24 14:56:22 badezimmer dhcpcd[763]: eth0: adding route to 192.168.169.0/24 May 24 14:56:22 badezimmer dhcpcd[763]: eth0: adding default route via 192.168.169.1 May 24 14:56:22 badezimmer systemd[1]: welcome.service: Deactivated successfully. May 24 14:56:22 badezimmer systemd[1]: Stopped welcome.service - Show a welcome message on console. May 24 14:56:22 badezimmer systemd[1]: Stopping welcome.service - Show a welcome message on console... May 24 14:56:22 badezimmer systemd[1]: Starting welcome.service - Show a welcome message on console... May 24 14:56:22 badezimmer welcome[1803]: Resolved ip:[1] 192.168.169.9 May 24 14:56:23 badezimmer systemd[1]: Finished welcome.service - Show a welcome message on console. May 24 14:56:23 badezimmer systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 24 14:56:23 badezimmer systemd[1]: Started nmbd.service - Samba NMB Daemon. May 24 14:56:23 badezimmer systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 24 14:56:23 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:23 badezimmer volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:56:23 badezimmer volumio[1197]: info: Received Get System Info May 24 14:56:23 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 14:56:23 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 14:56:23 badezimmer volumio[1197]: info: Discovery: Getting this device information May 24 14:56:23 badezimmer volumio[1197]: info: CoreCommandRouter::volumioGetState May 24 14:56:23 badezimmer volumio[1197]: info: CorePlayQueue::getTrack 0 May 24 14:56:23 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 14:56:23 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 24 14:56:23 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 24 14:56:23 badezimmer winbindd[1830]: [2026/05/24 14:56:23.508835, 0] ../../source3/winbindd/winbindd.c:1440(main) May 24 14:56:23 badezimmer winbindd[1830]: winbindd version 4.17.8-Raspbian started. May 24 14:56:23 badezimmer winbindd[1830]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 24 14:56:23 badezimmer winbindd[1830]: [2026/05/24 14:56:23.529846, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) May 24 14:56:23 badezimmer winbindd[1830]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 24 14:56:23 badezimmer systemd[1]: Started winbind.service - Samba Winbind Daemon. May 24 14:56:23 badezimmer systemd[1]: Starting smbd.service - Samba SMB Daemon... May 24 14:56:24 badezimmer smbd[1848]: [2026/05/24 14:56:24.051329, 0] ../../source3/smbd/server.c:1741(main) May 24 14:56:24 badezimmer smbd[1848]: smbd version 4.17.8-Raspbian started. May 24 14:56:24 badezimmer smbd[1848]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 24 14:56:24 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. May 24 14:56:24 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:24 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:24 badezimmer go-librespot[1852]: go-librespot daemon starting... May 24 14:56:24 badezimmer go-librespot[1853]: time="2026-05-24T14:56:24+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:24 badezimmer go-librespot[1853]: time="2026-05-24T14:56:24+02:00" level=debug msg="app state loaded" May 24 14:56:24 badezimmer go-librespot[1853]: time="2026-05-24T14:56:24+02:00" level=debug msg="stored credentials not found" May 24 14:56:24 badezimmer go-librespot[1853]: time="2026-05-24T14:56:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:24 badezimmer ntpd[987]: IO: Listen normally on 3 eth0 192.168.169.9:123 May 24 14:56:24 badezimmer ntpd[987]: IO: new interface(s) found: waking up resolver May 24 14:56:24 badezimmer ntpd[987]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 24 14:56:24 badezimmer volumio5-onboarding[1372]: time=2026-05-24T14:56:24.297+02:00 level=INFO msg="service successfully established" component=discovery/localnet May 24 14:56:24 badezimmer volumio[1197]: info: Volumio Network Manager: Network status updated: 1 May 24 14:56:24 badezimmer systemd[1]: Started smbd.service - Samba SMB Daemon. May 24 14:56:24 badezimmer systemd[1]: Reached target multi-user.target - Multi-User System. May 24 14:56:24 badezimmer systemd[1]: Reached target graphical.target - Graphical Interface. May 24 14:56:24 badezimmer systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 24 14:56:25 badezimmer systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 24 14:56:25 badezimmer systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 24 14:56:25 badezimmer systemd[1]: Startup finished in 13.167s (kernel) + 1min 20.373s (userspace) = 1min 33.540s. May 24 14:56:25 badezimmer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. May 24 14:56:25 badezimmer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:26 badezimmer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:26 badezimmer upmpdcli[1883]: Could not open config: /tmp/upmpdcli.conf May 24 14:56:26 badezimmer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:26 badezimmer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 24 14:56:26 badezimmer volumio[1197]: info: Initializing connection to go-librespot Websocket May 24 14:56:41 badezimmer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. May 24 14:56:41 badezimmer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:41 badezimmer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:41 badezimmer upmpdcli[1898]: Could not open config: /tmp/upmpdcli.conf May 24 14:56:41 badezimmer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:41 badezimmer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 24 14:56:52 badezimmer ntpd[987]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 24 14:56:52 badezimmer ntpd[987]: DNS: dns_check: DNS error: -11, System error May 24 14:56:52 badezimmer ntpd[987]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 May 24 14:56:52 badezimmer go-librespot[1853]: time="2026-05-24T14:56:52+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" May 24 14:56:52 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:52 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:56:53 badezimmer ntpd[987]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 24 14:56:55 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. May 24 14:56:55 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:55 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:56:55 badezimmer go-librespot[1928]: go-librespot daemon starting... May 24 14:56:55 badezimmer go-librespot[1929]: time="2026-05-24T14:56:55+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:56:55 badezimmer go-librespot[1929]: time="2026-05-24T14:56:55+02:00" level=debug msg="app state loaded" May 24 14:56:55 badezimmer go-librespot[1929]: time="2026-05-24T14:56:55+02:00" level=debug msg="stored credentials not found" May 24 14:56:55 badezimmer go-librespot[1929]: time="2026-05-24T14:56:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:56:56 badezimmer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. May 24 14:56:56 badezimmer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:56 badezimmer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:56:56 badezimmer upmpdcli[1937]: Could not open config: /tmp/upmpdcli.conf May 24 14:56:56 badezimmer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 24 14:56:56 badezimmer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 24 14:57:11 badezimmer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. May 24 14:57:11 badezimmer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:57:11 badezimmer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:57:11 badezimmer upmpdcli[1952]: Could not open config: /tmp/upmpdcli.conf May 24 14:57:11 badezimmer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 24 14:57:11 badezimmer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 24 14:57:19 badezimmer volumio[1197]: info: Discovery: adding 87f8ec3d-6a4e-41e7-bd61-0d8686d430b3 May 24 14:57:19 badezimmer volumio[1197]: info: Discovery: Found device Küche May 24 14:57:19 badezimmer volumio[1197]: info: Discovery: Connecting to remote: 192.168.169.38 May 24 14:57:19 badezimmer volumio[1197]: info: Discovery: Connected to remote: 192.168.169.38 May 24 14:57:21 badezimmer ntpd[987]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 24 14:57:21 badezimmer ntpd[987]: DNS: dns_check: DNS error: -11, System error May 24 14:57:21 badezimmer ntpd[987]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 May 24 14:57:22 badezimmer ntpd[987]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 24 14:57:23 badezimmer go-librespot[1929]: time="2026-05-24T14:57:23+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" May 24 14:57:23 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:57:23 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:57:26 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. May 24 14:57:26 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:57:26 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:57:26 badezimmer go-librespot[1982]: go-librespot daemon starting... May 24 14:57:26 badezimmer go-librespot[1983]: time="2026-05-24T14:57:26+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:57:26 badezimmer go-librespot[1983]: time="2026-05-24T14:57:26+02:00" level=debug msg="app state loaded" May 24 14:57:26 badezimmer go-librespot[1983]: time="2026-05-24T14:57:26+02:00" level=debug msg="stored credentials not found" May 24 14:57:26 badezimmer go-librespot[1983]: time="2026-05-24T14:57:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:57:26 badezimmer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9. May 24 14:57:26 badezimmer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:57:27 badezimmer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 24 14:57:27 badezimmer upmpdcli[1992]: Could not open config: /tmp/upmpdcli.conf May 24 14:57:27 badezimmer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 24 14:57:27 badezimmer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 24 14:57:29 badezimmer volumio[1197]: info: Discovery: adding 16b86a2f-708b-4c4a-a9a4-116ec3b38d06 May 24 14:57:29 badezimmer volumio[1197]: info: Discovery: Found device Garage May 24 14:57:29 badezimmer volumio[1197]: info: Discovery: Connecting to remote: 192.168.169.10 May 24 14:57:29 badezimmer volumio[1197]: info: Discovery: this is already registered, e62f1040-eb2b-44f1-9daa-b7e775eb0c03 May 24 14:57:29 badezimmer volumio[1197]: info: Discovery: Found device Badezimmer May 24 14:57:29 badezimmer volumio[1197]: info: CoreCommandRouter::volumioGetState May 24 14:57:29 badezimmer volumio[1197]: info: CorePlayQueue::getTrack 0 May 24 14:57:29 badezimmer go-librespot[1983]: time="2026-05-24T14:57:29+02:00" level=debug msg="new websocket client" May 24 14:57:29 badezimmer volumio[1197]: info: Connection to go-librespot Websocket established May 24 14:57:29 badezimmer volumio[1197]: verbose: New Socket.io Connection to 192.168.169.9:3000 from 192.168.169.38 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 24 14:57:29 badezimmer volumio[1197]: info: Discovery: Connected to remote: 192.168.169.10 May 24 14:57:29 badezimmer volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 24 14:57:29 badezimmer volumio[1197]: info: CoreCommandRouter::volumioGetState May 24 14:57:29 badezimmer volumio[1197]: info: CorePlayQueue::getTrack 0 May 24 14:57:30 badezimmer ntpd[987]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 24 14:57:30 badezimmer ntpd[987]: DNS: Pool taking: 195.201.173.232 May 24 14:57:30 badezimmer ntpd[987]: DNS: Pool taking: 129.70.132.32 May 24 14:57:30 badezimmer ntpd[987]: DNS: Pool taking: 129.250.35.251 May 24 14:57:30 badezimmer ntpd[987]: DNS: Pool taking: 131.188.3.220 May 24 14:57:30 badezimmer ntpd[987]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 24 14:57:31 badezimmer ntpd[987]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 24 14:57:31 badezimmer ntpd[987]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 24 14:57:31 badezimmer ntpd[987]: DNS: Pool taking: 194.35.12.210 May 24 14:57:31 badezimmer ntpd[987]: DNS: Pool taking: 213.239.234.28 May 24 14:57:31 badezimmer ntpd[987]: DNS: Pool skipping: 129.70.132.32 May 24 14:57:31 badezimmer ntpd[987]: DNS: Pool taking: 94.159.103.202 May 24 14:57:31 badezimmer ntpd[987]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 24 14:57:32 badezimmer go-librespot[1983]: time="2026-05-24T14:57:32+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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-05-24T14:57:31+02:00 is before 2026-07-09T00:00:00Z" May 24 14:57:32 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:57:32 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:57:32 badezimmer volumio[1197]: info: Connection to go-librespot Websocket closed May 24 14:57:32 badezimmer volumio[1197]: verbose: New Socket.io Connection to 192.168.169.9:3000 from 192.168.169.10 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 24 14:57:32 badezimmer volumio[1197]: info: Getting Spotify volume May 24 14:57:32 badezimmer volumio[1197]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 14:57:32 badezimmer volumio[1197]: Error: connect ECONNREFUSED 127.0.0.1:9879 May 24 14:57:32 badezimmer volumio[1197]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 24 14:57:32 badezimmer volumio[1197]: errno: -111, May 24 14:57:32 badezimmer volumio[1197]: code: 'ECONNREFUSED', May 24 14:57:32 badezimmer volumio[1197]: syscall: 'connect', May 24 14:57:32 badezimmer volumio[1197]: address: '127.0.0.1', May 24 14:57:32 badezimmer volumio[1197]: port: 9879, May 24 14:57:32 badezimmer volumio[1197]: response: undefined May 24 14:57:32 badezimmer volumio[1197]: } May 24 14:57:32 badezimmer volumio[1197]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 14:57:35 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. May 24 14:57:35 badezimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 24 14:57:35 badezimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 24 14:57:35 badezimmer go-librespot[2006]: go-librespot daemon starting... May 24 14:57:35 badezimmer go-librespot[2007]: time="2026-05-24T14:57:35+02:00" level=info msg="running go-librespot 0.7.1" May 24 14:57:35 badezimmer go-librespot[2007]: time="2026-05-24T14:57:35+02:00" level=debug msg="app state loaded" May 24 14:57:35 badezimmer go-librespot[2007]: time="2026-05-24T14:57:35+02:00" level=debug msg="stored credentials not found" May 24 14:57:35 badezimmer go-librespot[2007]: time="2026-05-24T14:57:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 24 14:57:35 badezimmer go-librespot[2007]: time="2026-05-24T14:57:35+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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-05-24T14:57:35+02:00 is before 2026-07-09T00:00:00Z" May 24 14:57:35 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 24 14:57:35 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 24 14:57:35 badezimmer sudo[2018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-24 14:56' May 24 14:57:35 badezimmer sudo[2018]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"