Jun 16 23:55:01 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 16 23:55:09 volumio go-librespot[15892]: time="2026-06-16T23:55:09+07: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" Jun 16 23:55:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:55:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:55:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 470. Jun 16 23:55:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:55:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:55:12 volumio go-librespot[15928]: go-librespot daemon starting... Jun 16 23:55:12 volumio go-librespot[15929]: time="2026-06-16T23:55:12+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:55:12 volumio go-librespot[15929]: time="2026-06-16T23:55:12+07:00" level=debug msg="app state loaded" Jun 16 23:55:12 volumio go-librespot[15929]: time="2026-06-16T23:55:12+07:00" level=debug msg="stored credentials not found" Jun 16 23:55:12 volumio go-librespot[15929]: time="2026-06-16T23:55:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:55:30 volumio kernel: hwmon hwmon1: Voltage normalised Jun 16 23:55:32 volumio go-librespot[15929]: time="2026-06-16T23:55:32+07: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" Jun 16 23:55:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:55:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:55:32 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 16 23:55:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 471. Jun 16 23:55:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:55:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:55:35 volumio go-librespot[15981]: go-librespot daemon starting... Jun 16 23:55:35 volumio go-librespot[15982]: time="2026-06-16T23:55:35+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:55:35 volumio go-librespot[15982]: time="2026-06-16T23:55:35+07:00" level=debug msg="app state loaded" Jun 16 23:55:35 volumio go-librespot[15982]: time="2026-06-16T23:55:35+07:00" level=debug msg="stored credentials not found" Jun 16 23:55:35 volumio go-librespot[15982]: time="2026-06-16T23:55:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:55:40 volumio login[2068]: pam_unix(login:session): session closed for user volumio Jun 16 23:55:40 volumio systemd[1]: Started getty@tty6.service - Getty on tty6. Jun 16 23:55:40 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Jun 16 23:55:40 volumio systemd[1]: session-1.scope: Deactivated successfully. Jun 16 23:55:40 volumio systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 2. Jun 16 23:55:40 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Jun 16 23:55:40 volumio systemd-logind[689]: Session 1 logged out. Waiting for processes to exit. Jun 16 23:55:40 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 16 23:55:40 volumio systemd-logind[689]: Removed session 1. Jun 16 23:55:41 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 16 23:55:50 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Jun 16 23:55:50 volumio systemd[6950]: Activating special unit exit.target... Jun 16 23:55:50 volumio systemd[6950]: Stopped target default.target - Main User Target. Jun 16 23:55:50 volumio systemd[6950]: Stopped target basic.target - Basic System. Jun 16 23:55:50 volumio systemd[6950]: Stopped target paths.target - Paths. Jun 16 23:55:50 volumio systemd[6950]: Stopped target sockets.target - Sockets. Jun 16 23:55:50 volumio systemd[6950]: Stopped target timers.target - Timers. Jun 16 23:55:50 volumio systemd[6950]: Closed dirmngr.socket - GnuPG network certificate management daemon. Jun 16 23:55:50 volumio systemd[6950]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Jun 16 23:55:50 volumio systemd[6950]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Jun 16 23:55:50 volumio systemd[6950]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Jun 16 23:55:50 volumio systemd[6950]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Jun 16 23:55:50 volumio systemd[6950]: Removed slice app.slice - User Application Slice. Jun 16 23:55:50 volumio systemd[6950]: Reached target shutdown.target - Shutdown. Jun 16 23:55:50 volumio systemd[6950]: Finished systemd-exit.service - Exit the Session. Jun 16 23:55:50 volumio systemd[6950]: Reached target exit.target - Exit the Session. Jun 16 23:55:50 volumio systemd[1]: user@1000.service: Deactivated successfully. Jun 16 23:55:50 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Jun 16 23:55:50 volumio systemd[1]: user@1000.service: Consumed 1.499s CPU time. Jun 16 23:55:50 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Jun 16 23:55:50 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Jun 16 23:55:50 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Jun 16 23:55:50 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Jun 16 23:55:50 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Jun 16 23:55:50 volumio systemd[1]: user-1000.slice: Consumed 2.451s CPU time. Jun 16 23:55:51 volumio ifplugd(eth0)[849]: Link beat lost. Jun 16 23:55:52 volumio dhcpcd[775]: eth0: carrier lost Jun 16 23:55:52 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Jun 16 23:55:52 volumio avahi-daemon[681]: Withdrawing address record for 192.168.1.20 on eth0. Jun 16 23:55:52 volumio avahi-daemon[681]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.20. Jun 16 23:55:52 volumio avahi-daemon[681]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 16 23:55:52 volumio dhcpcd[775]: eth0: deleting route to 192.168.1.0/24 Jun 16 23:55:52 volumio dhcpcd[775]: eth0: deleting default route via 192.168.1.1 Jun 16 23:55:52 volumio volumio[1127]: info: Discovery: A device disappeared from network Jun 16 23:55:52 volumio volumio[1127]: info: Discovery: A device disappeared from network Jun 16 23:55:52 volumio volumio[1127]: info: Discovery: A device disappeared from network Jun 16 23:55:52 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 16 23:55:52 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 16 23:55:52 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 16 23:55:52 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 16 23:55:52 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 16 23:55:52 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 16 23:55:52 volumio welcome[16024]: Resolved ip:[0] Jun 16 23:55:52 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 16 23:55:52 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 16 23:55:52 volumio volumio[1127]: info: Received Get System Info Jun 16 23:55:52 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:55:52 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:55:52 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:55:52 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:52 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:52 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:55:52 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:55:52 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:55:53 volumio volumio5-onboarding[1457]: time=2026-06-16T23:55:53.656+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:55:53 volumio ntpd[825]: IO: Deleting interface #4 eth0, 192.168.1.20#123, interface stats: received=0, sent=0, dropped=0, active_time=4737 secs Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 16 23:55:55 volumio go-librespot[15982]: time="2026-06-16T23:55:55+07:00" level=debug msg="new websocket client" Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: adding 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:55:55 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:55:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:55 volumio volumio[1127]: info: Connection to go-librespot Websocket established Jun 16 23:55:55 volumio volumio[1127]: info: Volumio Network Manager: Network status updated: 0 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Jun 16 23:55:55 volumio go-librespot[15982]: time="2026-06-16T23:55:55+07: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" Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Jun 16 23:55:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:55:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Jun 16 23:55:55 volumio volumio[1127]: info: Connection to go-librespot Websocket closed Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Jun 16 23:55:55 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 44 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 45 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 46 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 50 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 52 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 53 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 55 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 56 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 57 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 58 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 59 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 60 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 61 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 62 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 63 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 64 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 65 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 66 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 67 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 68 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 69 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 70 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 71 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 72 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 73 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 74 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 75 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 76 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 77 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 78 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 79 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 80 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 81 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 82 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 83 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 84 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 85 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 86 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 87 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 88 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 89 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 90 Jun 16 23:55:56 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 91 Jun 16 23:55:56 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:58 volumio volumio[1127]: info: Getting Spotify volume Jun 16 23:55:58 volumio volumio[1127]: error: Failed to get Spotify volume from local API: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 16 23:55:58 volumio volumio[1127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 92 Jun 16 23:55:58 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:55:58 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:55:58 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:55:58 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:55:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 472. Jun 16 23:55:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:55:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:55:59 volumio go-librespot[16044]: go-librespot daemon starting... Jun 16 23:55:59 volumio go-librespot[16045]: time="2026-06-16T23:55:59+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:55:59 volumio go-librespot[16045]: time="2026-06-16T23:55:59+07:00" level=debug msg="app state loaded" Jun 16 23:55:59 volumio go-librespot[16045]: time="2026-06-16T23:55:59+07:00" level=debug msg="stored credentials not found" Jun 16 23:55:59 volumio go-librespot[16045]: time="2026-06-16T23:55:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:55:59 volumio go-librespot[16045]: time="2026-06-16T23:55:59+07: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" Jun 16 23:55:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:55:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:01 volumio ifplugd(eth0)[849]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Jun 16 23:56:01 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:01 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:02 volumio ifplugd(eth0)[849]: client: dhcpcd not running Jun 16 23:56:02 volumio dhcpcd[16063]: dhcpcd not running Jun 16 23:56:02 volumio ifplugd(eth0)[849]: Program executed successfully. Jun 16 23:56:02 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Jun 16 23:56:02 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Jun 16 23:56:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 473. Jun 16 23:56:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:02 volumio go-librespot[16080]: go-librespot daemon starting... Jun 16 23:56:02 volumio go-librespot[16081]: time="2026-06-16T23:56:02+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:02 volumio go-librespot[16081]: time="2026-06-16T23:56:02+07:00" level=debug msg="app state loaded" Jun 16 23:56:02 volumio go-librespot[16081]: time="2026-06-16T23:56:02+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:02 volumio go-librespot[16081]: time="2026-06-16T23:56:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:02 volumio go-librespot[16081]: time="2026-06-16T23:56:02+07: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" Jun 16 23:56:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:02 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:02 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:02 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: === SNM TRANSITION === Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: Previous ethernet state: connected Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: New ethernet state: disconnected Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: Single Network Mode: enabled Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: First start: no Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: Action: Reconnect WiFi Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: === END TRANSITION === Jun 16 23:56:03 volumio sudo[16091]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 23:56:03 volumio sudo[16091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 23:56:03 volumio sudo[16091]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Jun 16 23:56:03 volumio wireless.js[15241]: WIRELESS.JS - INFO: reconnectWiFi: WiFi reconnection triggered Jun 16 23:56:03 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:03.475+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:04 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:04 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 474. Jun 16 23:56:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:05 volumio go-librespot[16112]: go-librespot daemon starting... Jun 16 23:56:05 volumio go-librespot[16113]: time="2026-06-16T23:56:05+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:05 volumio go-librespot[16113]: time="2026-06-16T23:56:05+07:00" level=debug msg="app state loaded" Jun 16 23:56:05 volumio go-librespot[16113]: time="2026-06-16T23:56:05+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:05 volumio go-librespot[16113]: time="2026-06-16T23:56:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:05 volumio go-librespot[16113]: time="2026-06-16T23:56:05+07: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" Jun 16 23:56:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: Trying to associate with 76:22:32:fc:76:2d (SSID='SJA AP' freq=2462 MHz) Jun 16 23:56:06 volumio wireless.js[15241]: WIRELESS.JS - INFO: reconnectWiFi: WiFi reconnect incomplete (wpa_state=ASSOCIATING), reinitializing wireless flow Jun 16 23:56:06 volumio wireless.js[15241]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: Associated with 76:22:32:fc:76:2d Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=ID Jun 16 23:56:06 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: WPA: Key negotiation completed with 76:22:32:fc:76:2d [PTK=CCMP GTK=CCMP] Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: CTRL-EVENT-CONNECTED - Connection to 76:22:32:fc:76:2d completed [id=0 id_str=] Jun 16 23:56:06 volumio dhcpcd[775]: wlan0: carrier acquired Jun 16 23:56:06 volumio dhcpcd[775]: wlan0: connected to Access Point: SJA AP Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: RRM: Unexpected neighbor report Jun 16 23:56:06 volumio dhcpcd[775]: wlan0: IAID eb:40:0f:5f Jun 16 23:56:06 volumio wpa_supplicant[15351]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 16 23:56:06 volumio wpa_supplicant[15351]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 16 23:56:06 volumio wpa_supplicant[15351]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Jun 16 23:56:06 volumio dhcpcd[775]: wlan0: soliciting an IPv6 router Jun 16 23:56:06 volumio wpa_supplicant[15351]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: CTRL-EVENT-DISCONNECTED bssid=76:22:32:fc:76:2d reason=3 locally_generated=1 Jun 16 23:56:06 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Jun 16 23:56:06 volumio dhcpcd[775]: wlan0: carrier lost - roaming Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 16 23:56:06 volumio wpa_supplicant[15351]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jun 16 23:56:06 volumio sudo[16141]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 16 23:56:06 volumio sudo[16141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:06 volumio sudo[16141]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:06 volumio wpa_supplicant[15351]: wlan0: CTRL-EVENT-TERMINATING Jun 16 23:56:06 volumio dhcpcd[775]: wlan0: carrier acquired Jun 16 23:56:06 volumio sudo[16143]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 16 23:56:06 volumio dhcpcd[775]: wlan0: IAID eb:40:0f:5f Jun 16 23:56:06 volumio sudo[16143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:07 volumio dhcpcd[775]: wlan0: soliciting an IPv6 router Jun 16 23:56:07 volumio dhcpcd[775]: wlan0: soliciting a DHCP lease Jun 16 23:56:07 volumio dhcpcd[775]: wlan0: carrier lost Jun 16 23:56:07 volumio sudo[16143]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:07 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:07 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:07 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:07 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:07 volumio wireless.js[15241]: WIRELESS.JS - INFO: Cleaning previous... Jun 16 23:56:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:07 volumio sudo[16152]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 16 23:56:07 volumio sudo[16152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:07 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 16 23:56:07 volumio sudo[16152]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:07 volumio wireless.js[15241]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Jun 16 23:56:07 volumio wireless.js[15241]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 3ms Jun 16 23:56:07 volumio wireless.js[15241]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:40:0f:5f) Jun 16 23:56:07 volumio sudo[16169]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 16 23:56:07 volumio sudo[16169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 23:56:07 volumio sudo[16169]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:07 volumio sudo[16177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 16 23:56:07 volumio sudo[16177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 23:56:07 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:07 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:08 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:08 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:08 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:08 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:08 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:08 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:08 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:08 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:08 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 475. Jun 16 23:56:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:08 volumio go-librespot[16181]: go-librespot daemon starting... Jun 16 23:56:08 volumio go-librespot[16182]: time="2026-06-16T23:56:08+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:08 volumio go-librespot[16182]: time="2026-06-16T23:56:08+07:00" level=debug msg="app state loaded" Jun 16 23:56:08 volumio go-librespot[16182]: time="2026-06-16T23:56:08+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:08 volumio go-librespot[16182]: time="2026-06-16T23:56:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:08 volumio go-librespot[16182]: time="2026-06-16T23:56:08+07: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" Jun 16 23:56:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:09 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:09.306+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:10 volumio sudo[16177]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:10 volumio wireless.js[15241]: WIRELESS.JS - INFO: Regdomain already correct: ID Jun 16 23:56:10 volumio wireless.js[15241]: WIRELESS.JS - INFO: Start wireless flow Jun 16 23:56:10 volumio wireless.js[15241]: WIRELESS.JS - INFO: Stopped hotspot (if there).. Jun 16 23:56:10 volumio sudo[16194]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 16 23:56:10 volumio sudo[16194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:10 volumio sudo[16194]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:10 volumio sudo[16196]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 16 23:56:10 volumio sudo[16196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:10 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:11 volumio sudo[16196]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:11 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:11 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:11 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:11 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:11 volumio wireless.js[15241]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Jun 16 23:56:11 volumio wireless.js[15241]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: b8:27:eb:40:0f:5f, USB: false) Jun 16 23:56:11 volumio wpa_supplicant[16202]: Successfully initialized wpa_supplicant Jun 16 23:56:11 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 16 23:56:11 volumio wpa_supplicant[16202]: nl80211: kernel reports: Registration to specific type not supported Jun 16 23:56:11 volumio sudo[16208]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 16 23:56:11 volumio sudo[16208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:11 volumio sudo[16208]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:11 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:11 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:11 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:11 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 476. Jun 16 23:56:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:12 volumio go-librespot[16213]: go-librespot daemon starting... Jun 16 23:56:12 volumio go-librespot[16214]: time="2026-06-16T23:56:12+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:12 volumio go-librespot[16214]: time="2026-06-16T23:56:12+07:00" level=debug msg="app state loaded" Jun 16 23:56:12 volumio go-librespot[16214]: time="2026-06-16T23:56:12+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:12 volumio go-librespot[16214]: time="2026-06-16T23:56:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:12 volumio go-librespot[16214]: time="2026-06-16T23:56:12+07: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" Jun 16 23:56:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:12 volumio wireless.js[15241]: WIRELESS.JS - INFO: DHCP IP fallback Jun 16 23:56:12 volumio wireless.js[15241]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor Jun 16 23:56:12 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 Jun 16 23:56:13 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) Jun 16 23:56:13 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:13.317+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:14 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:14 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:14 volumio wpa_supplicant[16205]: wlan0: Trying to associate with 72:63:f8:6f:34:8a (SSID='SJA AP' freq=2437 MHz) Jun 16 23:56:14 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> ASSOCIATING (duration: 1555ms) Jun 16 23:56:14 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:14 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:14 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:14 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 477. Jun 16 23:56:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:15 volumio go-librespot[16240]: go-librespot daemon starting... Jun 16 23:56:15 volumio go-librespot[16241]: time="2026-06-16T23:56:15+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:15 volumio go-librespot[16241]: time="2026-06-16T23:56:15+07:00" level=debug msg="app state loaded" Jun 16 23:56:15 volumio go-librespot[16241]: time="2026-06-16T23:56:15+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:15 volumio go-librespot[16241]: time="2026-06-16T23:56:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:15 volumio go-librespot[16241]: time="2026-06-16T23:56:15+07: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" Jun 16 23:56:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:15 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=76:22:32:fc:76:2d status_code=16 Jun 16 23:56:15 volumio wpa_supplicant[16205]: BSSID 76:22:32:fc:76:2d ignore list count incremented to 2, ignoring for 10 seconds Jun 16 23:56:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:15 volumio wpa_supplicant[16205]: wlan0: Trying to associate with 72:63:f8:6f:34:8a (SSID='SJA AP' freq=2437 MHz) Jun 16 23:56:15 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:15.938+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:16 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:16 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:16 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:16 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=76:22:32:fc:76:2d status_code=16 Jun 16 23:56:16 volumio wpa_supplicant[16205]: BSSID 76:22:32:fc:76:2d ignore list count incremented to 3, ignoring for 60 seconds Jun 16 23:56:16 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> DISCONNECTED (duration: 2072ms) Jun 16 23:56:17 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:17.175+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:17 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:17 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:17 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: DISCONNECTED -> SCANNING (duration: 519ms) Jun 16 23:56:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 478. Jun 16 23:56:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:18 volumio go-librespot[16281]: go-librespot daemon starting... Jun 16 23:56:18 volumio go-librespot[16282]: time="2026-06-16T23:56:18+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:18 volumio go-librespot[16282]: time="2026-06-16T23:56:18+07:00" level=debug msg="app state loaded" Jun 16 23:56:18 volumio go-librespot[16282]: time="2026-06-16T23:56:18+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:18 volumio go-librespot[16282]: time="2026-06-16T23:56:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:18 volumio go-librespot[16282]: time="2026-06-16T23:56:18+07: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" Jun 16 23:56:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:19 volumio wpa_supplicant[16205]: wlan0: Trying to associate with 72:63:f8:6f:34:8a (SSID='SJA AP' freq=2437 MHz) Jun 16 23:56:19 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> ASSOCIATING (duration: 2586ms) Jun 16 23:56:20 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:20 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:20 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:20 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:20 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:20 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:20 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:20 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:20 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:20 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:20 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:20 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=76:22:32:fc:76:2d status_code=16 Jun 16 23:56:20 volumio wpa_supplicant[16205]: BSSID 76:22:32:fc:76:2d ignore list count incremented to 4, ignoring for 120 seconds Jun 16 23:56:20 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> DISCONNECTED (duration: 1035ms) Jun 16 23:56:21 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:21.362+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 479. Jun 16 23:56:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:21 volumio go-librespot[16309]: go-librespot daemon starting... Jun 16 23:56:21 volumio go-librespot[16310]: time="2026-06-16T23:56:21+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:21 volumio go-librespot[16310]: time="2026-06-16T23:56:21+07:00" level=debug msg="app state loaded" Jun 16 23:56:21 volumio go-librespot[16310]: time="2026-06-16T23:56:21+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:21 volumio go-librespot[16310]: time="2026-06-16T23:56:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:21 volumio go-librespot[16310]: time="2026-06-16T23:56:21+07: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" Jun 16 23:56:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:21 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: DISCONNECTED -> SCANNING (duration: 1036ms) Jun 16 23:56:23 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:23 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:24 volumio wpa_supplicant[16205]: wlan0: Trying to associate with 72:63:f8:6f:34:8a (SSID='SJA AP' freq=2437 MHz) Jun 16 23:56:24 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> ASSOCIATING (duration: 2600ms) Jun 16 23:56:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 480. Jun 16 23:56:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:25 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:25 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:25 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:25 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:25 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:25 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:25 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:25 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:25 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:25 volumio go-librespot[16338]: go-librespot daemon starting... Jun 16 23:56:25 volumio go-librespot[16339]: time="2026-06-16T23:56:25+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:25 volumio go-librespot[16339]: time="2026-06-16T23:56:25+07:00" level=debug msg="app state loaded" Jun 16 23:56:25 volumio go-librespot[16339]: time="2026-06-16T23:56:25+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:25 volumio go-librespot[16339]: time="2026-06-16T23:56:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:25 volumio go-librespot[16339]: time="2026-06-16T23:56:25+07: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" Jun 16 23:56:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:25 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=76:22:32:fc:76:2d status_code=16 Jun 16 23:56:25 volumio wpa_supplicant[16205]: BSSID 76:22:32:fc:76:2d ignore list count incremented to 5, ignoring for 600 seconds Jun 16 23:56:25 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="SJA AP" auth_failures=1 duration=10 reason=CONN_FAILED Jun 16 23:56:25 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> DISCONNECTED (duration: 1039ms) Jun 16 23:56:25 volumio login[15992]: pam_unix(login:session): session opened for user volumio(uid=1000) by LOGIN(uid=0) Jun 16 23:56:25 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:25.975+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:25 volumio systemd-logind[689]: New session 3 of user volumio. Jun 16 23:56:26 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Jun 16 23:56:26 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Jun 16 23:56:26 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Jun 16 23:56:26 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Jun 16 23:56:26 volumio (systemd)[16381]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Jun 16 23:56:26 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:26 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:27 volumio systemd[16381]: Queued start job for default target default.target. Jun 16 23:56:27 volumio systemd[16381]: Created slice app.slice - User Application Slice. Jun 16 23:56:27 volumio systemd[16381]: Reached target paths.target - Paths. Jun 16 23:56:27 volumio systemd[16381]: Reached target timers.target - Timers. Jun 16 23:56:27 volumio systemd[16381]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Jun 16 23:56:27 volumio systemd[16381]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Jun 16 23:56:27 volumio systemd[16381]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Jun 16 23:56:27 volumio systemd[16381]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Jun 16 23:56:27 volumio systemd[16381]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Jun 16 23:56:27 volumio systemd[16381]: Reached target sockets.target - Sockets. Jun 16 23:56:27 volumio systemd[16381]: Reached target basic.target - Basic System. Jun 16 23:56:27 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Jun 16 23:56:27 volumio systemd[16381]: Started mpris-proxy.service - Bluetooth mpris proxy. Jun 16 23:56:27 volumio systemd[16381]: Reached target default.target - Main User Target. Jun 16 23:56:27 volumio systemd[16381]: Startup finished in 1.394s. Jun 16 23:56:27 volumio systemd[1]: Started session-3.scope - Session 3 of User volumio. Jun 16 23:56:27 volumio mpris-proxy[16402]: Can't get on session bus Jun 16 23:56:27 volumio systemd[16381]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:27 volumio systemd[16381]: mpris-proxy.service: Failed with result 'exit-code'. Jun 16 23:56:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 481. Jun 16 23:56:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:28 volumio go-librespot[16412]: go-librespot daemon starting... Jun 16 23:56:28 volumio go-librespot[16413]: time="2026-06-16T23:56:28+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:28 volumio go-librespot[16413]: time="2026-06-16T23:56:28+07:00" level=debug msg="app state loaded" Jun 16 23:56:28 volumio go-librespot[16413]: time="2026-06-16T23:56:28+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:28 volumio go-librespot[16413]: time="2026-06-16T23:56:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:28 volumio go-librespot[16413]: time="2026-06-16T23:56:28+07: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" Jun 16 23:56:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:29 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:29 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:30 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: DISCONNECTED -> SCANNING (duration: 5173ms) Jun 16 23:56:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 482. Jun 16 23:56:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:31 volumio go-librespot[16439]: go-librespot daemon starting... Jun 16 23:56:31 volumio go-librespot[16440]: time="2026-06-16T23:56:31+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:31 volumio go-librespot[16440]: time="2026-06-16T23:56:31+07:00" level=debug msg="app state loaded" Jun 16 23:56:31 volumio go-librespot[16440]: time="2026-06-16T23:56:31+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:31 volumio go-librespot[16440]: time="2026-06-16T23:56:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:31 volumio go-librespot[16440]: time="2026-06-16T23:56:31+07: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" Jun 16 23:56:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:31 volumio sudo[16448]: volumio : TTY=tty1 ; PWD=/home/volumio ; USER=root ; COMMAND=/sbin/ifconfig Jun 16 23:56:31 volumio sudo[16448]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Jun 16 23:56:31 volumio sudo[16448]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:32 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:32 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:33 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:33 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:33 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:33 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:33 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:33 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:33 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:33 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:33 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 483. Jun 16 23:56:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:34 volumio go-librespot[16469]: go-librespot daemon starting... Jun 16 23:56:34 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:34.850+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:34 volumio go-librespot[16470]: time="2026-06-16T23:56:34+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:34 volumio go-librespot[16470]: time="2026-06-16T23:56:34+07:00" level=debug msg="app state loaded" Jun 16 23:56:34 volumio go-librespot[16470]: time="2026-06-16T23:56:34+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:34 volumio go-librespot[16470]: time="2026-06-16T23:56:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:34 volumio go-librespot[16470]: time="2026-06-16T23:56:34+07: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" Jun 16 23:56:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:35 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:35 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 484. Jun 16 23:56:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:38 volumio go-librespot[16510]: go-librespot daemon starting... Jun 16 23:56:38 volumio go-librespot[16514]: time="2026-06-16T23:56:38+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:38 volumio go-librespot[16514]: time="2026-06-16T23:56:38+07:00" level=debug msg="app state loaded" Jun 16 23:56:38 volumio go-librespot[16514]: time="2026-06-16T23:56:38+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:38 volumio go-librespot[16514]: time="2026-06-16T23:56:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:38 volumio go-librespot[16514]: time="2026-06-16T23:56:38+07: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" Jun 16 23:56:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:38 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:38 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="SJA AP" Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: Trying to associate with 76:22:32:fc:76:2d (SSID='SJA AP' freq=2462 MHz) Jun 16 23:56:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 485. Jun 16 23:56:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:41 volumio go-librespot[16541]: go-librespot daemon starting... Jun 16 23:56:41 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:41 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:41 volumio go-librespot[16542]: time="2026-06-16T23:56:41+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:41 volumio go-librespot[16542]: time="2026-06-16T23:56:41+07:00" level=debug msg="app state loaded" Jun 16 23:56:41 volumio go-librespot[16542]: time="2026-06-16T23:56:41+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:41 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: Associated with 76:22:32:fc:76:2d Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=ID Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: WPA: Key negotiation completed with 76:22:32:fc:76:2d [PTK=CCMP GTK=CCMP] Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: CTRL-EVENT-CONNECTED - Connection to 76:22:32:fc:76:2d completed [id=0 id_str=] Jun 16 23:56:41 volumio dhcpcd[775]: wlan0: carrier acquired Jun 16 23:56:41 volumio wpa_supplicant[16205]: wlan0: RRM: Unexpected neighbor report Jun 16 23:56:41 volumio go-librespot[16542]: time="2026-06-16T23:56:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:41 volumio go-librespot[16542]: time="2026-06-16T23:56:41+07: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" Jun 16 23:56:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:41 volumio dhcpcd[775]: wlan0: IAID eb:40:0f:5f Jun 16 23:56:41 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 10858ms) Jun 16 23:56:41 volumio wireless.js[15241]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful Jun 16 23:56:41 volumio wireless.js[15241]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 76:22:32:fc:76:2d Jun 16 23:56:41 volumio wireless.js[15241]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow Jun 16 23:56:41 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:41 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:41 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:41 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:42 volumio dhcpcd[775]: wlan0: soliciting an IPv6 router Jun 16 23:56:42 volumio sudo[16557]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 Jun 16 23:56:42 volumio sudo[16557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:42 volumio dhcpcd[775]: control_free: No such file or directory Jun 16 23:56:42 volumio sudo[16557]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:42 volumio dhcpcd[775]: ps_ctl_dispatch: cannot handle another client Jun 16 23:56:42 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:42.970+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:42 volumio dhcpcd[775]: wlan0: soliciting a DHCP lease Jun 16 23:56:43 volumio dhcpcd[775]: wlan0: offered 10.12.15.66 from 10.12.15.254 Jun 16 23:56:43 volumio dhcpcd[775]: wlan0: probing address 10.12.15.66/24 Jun 16 23:56:44 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:44 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 486. Jun 16 23:56:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:44 volumio go-librespot[16561]: go-librespot daemon starting... Jun 16 23:56:44 volumio go-librespot[16562]: time="2026-06-16T23:56:44+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:44 volumio go-librespot[16562]: time="2026-06-16T23:56:44+07:00" level=debug msg="app state loaded" Jun 16 23:56:44 volumio go-librespot[16562]: time="2026-06-16T23:56:44+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:44 volumio go-librespot[16562]: time="2026-06-16T23:56:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:44 volumio go-librespot[16562]: time="2026-06-16T23:56:44+07: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" Jun 16 23:56:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:44 volumio wireless.js[15241]: WIRELESS.JS - INFO: Start ap Jun 16 23:56:44 volumio wireless.js[15241]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jun 16 23:56:45 volumio wireless.js[15241]: WIRELESS.JS - INFO: trying... Jun 16 23:56:45 volumio sudo[16598]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 23:56:45 volumio sudo[16598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 23:56:46 volumio sudo[16598]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:46 volumio sudo[16603]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 23:56:46 volumio sudo[16603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:46 volumio sudo[16603]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:46 volumio wireless.js[15241]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Jun 16 23:56:46 volumio wireless.js[15241]: WIRELESS.JS - INFO: trying... Jun 16 23:56:46 volumio sudo[16614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 23:56:46 volumio sudo[16614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 23:56:47 volumio sudo[16614]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:47 volumio sudo[16617]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 23:56:47 volumio sudo[16617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:47 volumio sudo[16617]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:47 volumio wireless.js[15241]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Jun 16 23:56:47 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:47 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: AggregateError Jun 16 23:56:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 487. Jun 16 23:56:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:47 volumio wireless.js[15241]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet Jun 16 23:56:47 volumio wireless.js[15241]: WIRELESS.JS - INFO: trying... Jun 16 23:56:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:47 volumio go-librespot[16619]: go-librespot daemon starting... Jun 16 23:56:47 volumio go-librespot[16627]: time="2026-06-16T23:56:47+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:47 volumio go-librespot[16627]: time="2026-06-16T23:56:47+07:00" level=debug msg="app state loaded" Jun 16 23:56:47 volumio go-librespot[16627]: time="2026-06-16T23:56:47+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:47 volumio go-librespot[16627]: time="2026-06-16T23:56:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:47 volumio go-librespot[16627]: time="2026-06-16T23:56:47+07: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" Jun 16 23:56:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:48 volumio sudo[16642]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 23:56:48 volumio sudo[16642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 23:56:48 volumio sudo[16642]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:48 volumio sudo[16645]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 23:56:48 volumio sudo[16645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:48 volumio sudo[16645]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:48 volumio wireless.js[15241]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Jun 16 23:56:48 volumio dhcpcd[775]: wlan0: leased 10.12.15.66 for 2700 seconds Jun 16 23:56:48 volumio avahi-daemon[681]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.12.15.66. Jun 16 23:56:48 volumio avahi-daemon[681]: New relevant interface wlan0.IPv4 for mDNS. Jun 16 23:56:48 volumio avahi-daemon[681]: Registering new address record for 10.12.15.66 on wlan0.IPv4. Jun 16 23:56:48 volumio dhcpcd[775]: wlan0: adding route to 10.12.15.0/24 Jun 16 23:56:48 volumio dhcpcd[775]: wlan0: adding default route via 10.12.15.1 Jun 16 23:56:48 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 16 23:56:48 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 16 23:56:48 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 16 23:56:48 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 16 23:56:48 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 16 23:56:48 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 16 23:56:48 volumio welcome[16663]: Resolved ip:[1] 10.12.15.66 Jun 16 23:56:48 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 16 23:56:48 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 16 23:56:48 volumio volumio[1127]: info: Received Get System Info Jun 16 23:56:48 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 23:56:48 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 23:56:48 volumio volumio[1127]: info: Discovery: Getting this device information Jun 16 23:56:48 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:48 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:48 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 23:56:48 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 23:56:48 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 23:56:48 volumio wireless.js[15241]: WIRELESS.JS - INFO: trying... Jun 16 23:56:49 volumio sudo[16688]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 23:56:49 volumio sudo[16688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 23:56:49 volumio sudo[16688]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:49 volumio sudo[16691]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 23:56:49 volumio sudo[16691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:49 volumio sudo[16691]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:49 volumio wireless.js[15241]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 10.12.15.66, ipV6 is undefined Jun 16 23:56:49 volumio wireless.js[15241]: WIRELESS.JS - INFO: Connected to SSID: SJA AP Jun 16 23:56:49 volumio wireless.js[15241]: WIRELESS.JS - INFO: It's done! AP Jun 16 23:56:49 volumio wireless.js[15241]: WIRELESS.JS - INFO: Restarting avahi-daemon... Jun 16 23:56:49 volumio sudo[16696]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Jun 16 23:56:49 volumio sudo[16696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 23:56:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 16 23:56:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 16 23:56:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 23:56:49 volumio systemd[1]: shairport-sync.service: Consumed 35.581s CPU time. Jun 16 23:56:49 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 16 23:56:49 volumio avahi-daemon[681]: Got SIGTERM, quitting. Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:56:49 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:49 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:49 volumio avahi-daemon[681]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.12.15.66. Jun 16 23:56:49 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:49.268+07:00 level=WARN msg="disconnected from Avahi daemon, trying to reconnect" component=discovery/localnet error="avahi: Daemon connection failed" Jun 16 23:56:49 volumio avahi-daemon[681]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 16 23:56:49 volumio avahi-daemon[681]: avahi-daemon 0.8 exiting. Jun 16 23:56:49 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Jun 16 23:56:49 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 16 23:56:49 volumio systemd[1]: avahi-daemon.service: Consumed 1.765s CPU time. Jun 16 23:56:49 volumio dbus-daemon[682]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.46' (uid=0 pid=1457 comm="/usr/bin/volumio5-onboarding") Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:56:49 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:49 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Restarting Browsing Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Restarting Browsing Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Restart already pending, ignoring duplicate call Jun 16 23:56:49 volumio volumio[1127]: error: Discovery: Advertisement error: Error: dns service error: unknown Jun 16 23:56:49 volumio volumio[1127]: error: Discovery: advertisement error: Error: dns service error: unknown Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Stopping Advertising Immediately Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Stopping existing advertisement Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Restarting Browsing Jun 16 23:56:49 volumio volumio[1127]: info: Discovery: Restart already pending, ignoring duplicate call Jun 16 23:56:49 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 16 23:56:49 volumio avahi-daemon[16698]: Process 681 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jun 16 23:56:49 volumio avahi-daemon[16698]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 16 23:56:49 volumio avahi-daemon[16698]: Successfully dropped root privileges. Jun 16 23:56:49 volumio avahi-daemon[16698]: avahi-daemon 0.8 starting up. Jun 16 23:56:49 volumio dbus-daemon[682]: [system] Successfully activated service 'org.freedesktop.Avahi' Jun 16 23:56:49 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 16 23:56:49 volumio avahi-daemon[16698]: Successfully called chroot(). Jun 16 23:56:49 volumio avahi-daemon[16698]: Successfully dropped remaining capabilities. Jun 16 23:56:49 volumio avahi-daemon[16698]: No service file found in /etc/avahi/services. Jun 16 23:56:49 volumio avahi-daemon[16698]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.12.15.66. Jun 16 23:56:49 volumio avahi-daemon[16698]: New relevant interface wlan0.IPv4 for mDNS. Jun 16 23:56:49 volumio avahi-daemon[16698]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 16 23:56:49 volumio avahi-daemon[16698]: New relevant interface lo.IPv4 for mDNS. Jun 16 23:56:49 volumio avahi-daemon[16698]: Network interface enumeration completed. Jun 16 23:56:49 volumio avahi-daemon[16698]: Registering new address record for 10.12.15.66 on wlan0.IPv4. Jun 16 23:56:49 volumio avahi-daemon[16698]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 16 23:56:49 volumio sudo[16696]: pam_unix(sudo:session): session closed for user root Jun 16 23:56:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 23:56:49 volumio wireless.js[15241]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jun 16 23:56:49 volumio ntpd[825]: IO: Listen normally on 5 wlan0 10.12.15.66:123 Jun 16 23:56:49 volumio ntpd[825]: IO: new interface(s) found: waking up resolver Jun 16 23:56:49 volumio ntpd[825]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 16 23:56:49 volumio ntpd[825]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 16 23:56:49 volumio ntpd[825]: DNS: Pool taking: 5.223.54.153 Jun 16 23:56:49 volumio ntpd[825]: DNS: Pool taking: 162.159.200.123 Jun 16 23:56:49 volumio ntpd[825]: DNS: Pool taking: 172.237.88.124 Jun 16 23:56:49 volumio ntpd[825]: DNS: Pool taking: 54.251.47.36 Jun 16 23:56:49 volumio ntpd[825]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 16 23:56:50 volumio avahi-daemon[16698]: Server startup complete. Host name is volumio.local. Local service cookie is 4251004480. Jun 16 23:56:50 volumio volumio[1127]: info: Discovery: this is already registered, 99fefd20-bd01-426d-8d9c-f0278e7f0a6e Jun 16 23:56:50 volumio volumio[1127]: info: Discovery: Found device Volumio Jun 16 23:56:50 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Jun 16 23:56:50 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Jun 16 23:56:50 volumio volumio[1127]: info: Initializing connection to go-librespot Websocket Jun 16 23:56:50 volumio volumio[1127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 16 23:56:50 volumio ntpd[825]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 16 23:56:50 volumio ntpd[825]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 5.223.50.207 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 162.159.200.1 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 172.104.182.184 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 202.91.32.240 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 240b:4000:1e:5900:7265:7447:1e27:bd97 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 2001:678:8::123 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 2001:19f0:4400:7d02:5400:5ff:fe04:48b6 Jun 16 23:56:50 volumio ntpd[825]: DNS: Pool taking: 2400:8901::2000:7dff:fe61:80e1 Jun 16 23:56:50 volumio ntpd[825]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 16 23:56:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 488. Jun 16 23:56:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 23:56:51 volumio go-librespot[16719]: go-librespot daemon starting... Jun 16 23:56:51 volumio go-librespot[16720]: time="2026-06-16T23:56:51+07:00" level=info msg="running go-librespot 0.7.1" Jun 16 23:56:51 volumio go-librespot[16720]: time="2026-06-16T23:56:51+07:00" level=debug msg="app state loaded" Jun 16 23:56:51 volumio go-librespot[16720]: time="2026-06-16T23:56:51+07:00" level=debug msg="stored credentials not found" Jun 16 23:56:51 volumio go-librespot[16720]: time="2026-06-16T23:56:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 23:56:51 volumio volumio5-onboarding[1457]: time=2026-06-16T23:56:51.340+07:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 16 23:56:51 volumio ntpd[825]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 16 23:56:51 volumio go-librespot[16720]: time="2026-06-16T23:56:51+07: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-06-16T23:56:51+07:00 is before 2026-07-09T00:00:00Z" Jun 16 23:56:51 volumio ntpd[825]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 16 23:56:51 volumio ntpd[825]: DNS: Pool taking: 142.91.103.26 Jun 16 23:56:51 volumio ntpd[825]: DNS: Pool skipping: 162.159.200.123 Jun 16 23:56:51 volumio ntpd[825]: DNS: Pool skipping: 172.237.88.124 Jun 16 23:56:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 16 23:56:51 volumio volumio[1127]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 23:56:51 volumio ntpd[825]: DNS: Pool taking: 51.79.177.199 Jun 16 23:56:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 16 23:56:51 volumio ntpd[825]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 16 23:56:51 volumio volumio[1127]: TypeError: Cannot create property 'type' on string 'invalid server key' Jun 16 23:56:51 volumio volumio[1127]: at WebSocket.onError (/volumio/node_modules/ws/lib/WebSocket.js:450:16) Jun 16 23:56:51 volumio volumio[1127]: at WebSocket.emit (node:events:514:28) Jun 16 23:56:51 volumio volumio[1127]: at ClientRequest.upgrade (/volumio/node_modules/ws/lib/WebSocket.js:738:12) Jun 16 23:56:51 volumio volumio[1127]: at Object.onceWrapper (node:events:629:26) Jun 16 23:56:51 volumio volumio[1127]: at ClientRequest.emit (node:events:514:28) Jun 16 23:56:51 volumio volumio[1127]: at Socket.socketOnData (node:_http_client:575:11) Jun 16 23:56:51 volumio volumio[1127]: at Socket.emit (node:events:514:28) Jun 16 23:56:51 volumio volumio[1127]: at addChunk (node:internal/streams/readable:343:12) Jun 16 23:56:51 volumio volumio[1127]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 16 23:56:51 volumio volumio[1127]: at Readable.push (node:internal/streams/readable:253:10) Jun 16 23:56:51 volumio volumio[1127]: at TCP.onStreamRead (node:internal/stream_base_commons:190:23) Jun 16 23:56:51 volumio volumio[1127]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 23:56:52 volumio ntpd[825]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 16 23:56:52 volumio ntpd[825]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 16 23:56:52 volumio ntpd[825]: DNS: Pool taking: 8.219.216.20 Jun 16 23:56:52 volumio ntpd[825]: DNS: Pool skipping: 5.223.54.153 Jun 16 23:56:52 volumio ntpd[825]: DNS: Pool taking: 84.33.16.6 Jun 16 23:56:52 volumio ntpd[825]: DNS: Pool taking: 64.235.61.113 Jun 16 23:56:52 volumio ntpd[825]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 16 23:56:53 volumio sudo[16748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-16 23:55' Jun 16 23:56:53 volumio sudo[16748]: 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"