Oct 16 20:58:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117. Oct 16 20:58:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:00 volumio go-librespot[4708]: go-librespot daemon starting... Oct 16 20:58:00 volumio go-librespot[4709]: time="2026-10-16T20:58:00+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:00 volumio go-librespot[4709]: time="2026-10-16T20:58:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:00 volumio go-librespot[4709]: time="2026-10-16T20:58:00+02:00" level=debug msg="app state loaded" Oct 16 20:58:00 volumio go-librespot[4709]: time="2026-10-16T20:58:00+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:02 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:02 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 118. Oct 16 20:58:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:03 volumio go-librespot[4737]: go-librespot daemon starting... Oct 16 20:58:03 volumio go-librespot[4738]: time="2026-10-16T20:58:03+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:03 volumio go-librespot[4738]: time="2026-10-16T20:58:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:03 volumio go-librespot[4738]: time="2026-10-16T20:58:03+02:00" level=debug msg="app state loaded" Oct 16 20:58:03 volumio go-librespot[4738]: time="2026-10-16T20:58:03+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:05 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:05 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 119. Oct 16 20:58:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:07 volumio go-librespot[4751]: go-librespot daemon starting... Oct 16 20:58:07 volumio go-librespot[4752]: time="2026-10-16T20:58:07+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:07 volumio go-librespot[4752]: time="2026-10-16T20:58:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:07 volumio go-librespot[4752]: time="2026-10-16T20:58:07+02:00" level=debug msg="app state loaded" Oct 16 20:58:07 volumio go-librespot[4752]: time="2026-10-16T20:58:07+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:08 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:08 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 120. Oct 16 20:58:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:10 volumio go-librespot[4765]: go-librespot daemon starting... Oct 16 20:58:10 volumio go-librespot[4766]: time="2026-10-16T20:58:10+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:10 volumio go-librespot[4766]: time="2026-10-16T20:58:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:10 volumio go-librespot[4766]: time="2026-10-16T20:58:10+02:00" level=debug msg="app state loaded" Oct 16 20:58:10 volumio go-librespot[4766]: time="2026-10-16T20:58:10+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:11 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:11.599+02:00 level=INFO msg="connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="00:00:00:00:00:00%01 @ 0xc00032a900" latency=35.935900056s timeout=1m0s ssid=Bozinovski Oct 16 20:58:11 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:11.601+02:00 level=INFO msg="wpa_supplicant control socket not functional, starting temporary daemon" component=volumio reason="dial unixgram /tmp/wpa_supplicant2934024220->/var/run/wpa_supplicant/wlan0: connect: connection refused" Oct 16 20:58:11 volumio wpa_supplicant[4777]: Successfully initialized wpa_supplicant Oct 16 20:58:11 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:11 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 121. Oct 16 20:58:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:13 volumio wpa_supplicant[4777]: wlan0: Trying to associate with d6:92:5e:92:a9:80 (SSID='Bozinovski' freq=2462 MHz) Oct 16 20:58:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:13 volumio go-librespot[4780]: go-librespot daemon starting... Oct 16 20:58:13 volumio go-librespot[4781]: time="2026-10-16T20:58:13+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:13 volumio go-librespot[4781]: time="2026-10-16T20:58:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:13 volumio go-librespot[4781]: time="2026-10-16T20:58:13+02:00" level=debug msg="app state loaded" Oct 16 20:58:13 volumio go-librespot[4781]: time="2026-10-16T20:58:13+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:13 volumio dhcpcd[926]: wlan0: carrier acquired Oct 16 20:58:13 volumio wpa_supplicant[4777]: wlan0: Associated with d6:92:5e:92:a9:80 Oct 16 20:58:13 volumio wpa_supplicant[4777]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 16 20:58:13 volumio wpa_supplicant[4777]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 16 20:58:13 volumio dhcpcd[926]: wlan0: IAID 8c:ca:79:f4 Oct 16 20:58:13 volumio dhcpcd[926]: wlan0: carrier lost - roaming Oct 16 20:58:13 volumio dhcpcd[926]: wlan0: carrier acquired Oct 16 20:58:13 volumio dhcpcd[926]: wlan0: IAID 8c:ca:79:f4 Oct 16 20:58:14 volumio volumio[1683]: info: Received Get System Info Oct 16 20:58:14 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:58:14 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:58:14 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:58:14 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:58:14 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:58:14 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:58:14 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:14.299+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=true macAddress=38:a2:8c:ca:79:f4 ip4Address=192.168.211.1/24 ip6Address= ssid=Bozinovski Oct 16 20:58:14 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:58:14 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:58:14 volumio dhcpcd[926]: wlan0: soliciting an IPv6 router Oct 16 20:58:14 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:14 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:14 volumio dhcpcd[926]: wlan0: soliciting a DHCP lease Oct 16 20:58:15 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:15.277+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:58:15 volumio ntpd[1220]: IO: Listen normally on 4 wlan0 192.168.211.1:123 Oct 16 20:58:15 volumio ntpd[1220]: IO: new interface(s) found: waking up resolver Oct 16 20:58:15 volumio ntpd[1220]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:15 volumio ntpd[1220]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 16 20:58:15 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:15 volumio ntpd[1220]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Oct 16 20:58:16 volumio ntpd[1220]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:16 volumio ntpd[1220]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 16 20:58:16 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:16 volumio ntpd[1220]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Oct 16 20:58:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122. Oct 16 20:58:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:16 volumio go-librespot[4822]: go-librespot daemon starting... Oct 16 20:58:16 volumio go-librespot[4823]: time="2026-10-16T20:58:16+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:16 volumio go-librespot[4823]: time="2026-10-16T20:58:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:16 volumio go-librespot[4823]: time="2026-10-16T20:58:16+02:00" level=debug msg="app state loaded" Oct 16 20:58:16 volumio go-librespot[4823]: time="2026-10-16T20:58:16+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:17 volumio ntpd[1220]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:17 volumio ntpd[1220]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 16 20:58:17 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:17 volumio ntpd[1220]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Oct 16 20:58:17 volumio wpa_supplicant[4777]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d6:92:5e:92:a9:80 reason=0 locally_generated=1 Oct 16 20:58:17 volumio wpa_supplicant[4777]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Oct 16 20:58:17 volumio wpa_supplicant[4777]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Bozinovski" auth_failures=1 duration=10 reason=WRONG_KEY Oct 16 20:58:17 volumio dhcpcd[926]: wlan0: carrier lost Oct 16 20:58:17 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:17.805+02:00 level=WARN msg="failed to connect temporarily to WiFi network" component=volumio ssid=Bozinovski msg="id=0 ssid=\"Bozinovski\" auth_failures=1 duration=10 reason=WRONG_KEY" Oct 16 20:58:17 volumio wpa_supplicant[4777]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 16 20:58:17 volumio wpa_supplicant[4777]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Oct 16 20:58:17 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:17.874+02:00 level=ERROR msg="failed to connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="00:00:00:00:00:00%01 @ 0xc00032a900" latency=35.935900056s timeout=1m0s ssid=Bozinovski error="failed to connect temporarily to WiFi network: WRONG_KEY" Oct 16 20:58:17 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:17 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:18 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:18.309+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 20:58:18 volumio volumio[1683]: info: Received Get System Info Oct 16 20:58:18 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:58:18 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:58:18 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:58:18 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:58:18 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:58:18 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:58:18 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:58:18 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:58:18 volumio ntpd[1220]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:18 volumio ntpd[1220]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 16 20:58:18 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:18 volumio ntpd[1220]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Oct 16 20:58:19 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:19.298+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:58:19 volumio ntpd[1220]: IO: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Oct 16 20:58:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123. Oct 16 20:58:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:20 volumio go-librespot[4853]: go-librespot daemon starting... Oct 16 20:58:20 volumio go-librespot[4854]: time="2026-10-16T20:58:20+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:20 volumio go-librespot[4854]: time="2026-10-16T20:58:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:20 volumio go-librespot[4854]: time="2026-10-16T20:58:20+02:00" level=debug msg="app state loaded" Oct 16 20:58:20 volumio go-librespot[4854]: time="2026-10-16T20:58:20+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:20 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:20 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:21 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:21.708+02:00 level=INFO msg="connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="00:00:00:00:00:00%01 @ 0xc00032a900" latency=35.937816801s timeout=1m0s ssid=Bozinovski Oct 16 20:58:23 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:23.251+02:00 level=INFO msg="wpa_supplicant control socket not functional, starting temporary daemon" component=volumio reason="dial unixgram /tmp/wpa_supplicant764097344->/var/run/wpa_supplicant/wlan0: connect: connection refused" Oct 16 20:58:23 volumio wpa_supplicant[4867]: Successfully initialized wpa_supplicant Oct 16 20:58:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124. Oct 16 20:58:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:23 volumio go-librespot[4868]: go-librespot daemon starting... Oct 16 20:58:23 volumio go-librespot[4869]: time="2026-10-16T20:58:23+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:23 volumio go-librespot[4869]: time="2026-10-16T20:58:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:23 volumio go-librespot[4869]: time="2026-10-16T20:58:23+02:00" level=debug msg="app state loaded" Oct 16 20:58:23 volumio go-librespot[4869]: time="2026-10-16T20:58:23+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:23 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:23.782+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 20:58:23 volumio volumio[1683]: info: Received Get System Info Oct 16 20:58:23 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:58:23 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:58:23 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:58:23 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:58:23 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:58:23 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:58:23 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:58:23 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:58:23 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:23 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:24 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:24.762+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:58:25 volumio wpa_supplicant[4867]: wlan0: Trying to associate with d6:92:5e:92:a9:80 (SSID='Bozinovski' freq=2462 MHz) Oct 16 20:58:25 volumio wpa_supplicant[4867]: wlan0: Associated with d6:92:5e:92:a9:80 Oct 16 20:58:25 volumio wpa_supplicant[4867]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 16 20:58:25 volumio wpa_supplicant[4867]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 16 20:58:25 volumio dhcpcd[926]: wlan0: carrier acquired Oct 16 20:58:25 volumio dhcpcd[926]: wlan0: IAID 8c:ca:79:f4 Oct 16 20:58:25 volumio dhcpcd[926]: wlan0: carrier lost - roaming Oct 16 20:58:25 volumio dhcpcd[926]: wlan0: carrier acquired Oct 16 20:58:25 volumio dhcpcd[926]: wlan0: IAID 8c:ca:79:f4 Oct 16 20:58:25 volumio dhcpcd[926]: wlan0: soliciting a DHCP lease Oct 16 20:58:26 volumio volumio[1683]: info: Received Get System Info Oct 16 20:58:26 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:58:26 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:58:26 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:58:26 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:58:26 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:58:26 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:58:26 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:26.079+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=true macAddress=38:a2:8c:ca:79:f4 ip4Address=192.168.211.1/24 ip6Address= ssid=Bozinovski Oct 16 20:58:26 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:58:26 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:58:26 volumio dhcpcd[926]: wlan0: soliciting an IPv6 router Oct 16 20:58:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 125. Oct 16 20:58:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:26 volumio go-librespot[4907]: go-librespot daemon starting... Oct 16 20:58:26 volumio go-librespot[4908]: time="2026-10-16T20:58:26+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:26 volumio go-librespot[4908]: time="2026-10-16T20:58:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:26 volumio go-librespot[4908]: time="2026-10-16T20:58:26+02:00" level=debug msg="app state loaded" Oct 16 20:58:26 volumio go-librespot[4908]: time="2026-10-16T20:58:26+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:26 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:26 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:26 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:26.945+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:58:27 volumio ntpd[1220]: IO: Listen normally on 5 wlan0 192.168.211.1:123 Oct 16 20:58:27 volumio ntpd[1220]: IO: new interface(s) found: waking up resolver Oct 16 20:58:27 volumio ntpd[1220]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:27 volumio ntpd[1220]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 16 20:58:27 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:27 volumio ntpd[1220]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Oct 16 20:58:28 volumio ntpd[1220]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:28 volumio ntpd[1220]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 16 20:58:28 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:28 volumio ntpd[1220]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Oct 16 20:58:29 volumio ntpd[1220]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:29 volumio ntpd[1220]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 16 20:58:29 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:29 volumio ntpd[1220]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Oct 16 20:58:29 volumio wpa_supplicant[4867]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d6:92:5e:92:a9:80 reason=0 locally_generated=1 Oct 16 20:58:29 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:29.578+02:00 level=WARN msg="failed to connect temporarily to WiFi network" component=volumio ssid=Bozinovski msg="id=0 ssid=\"Bozinovski\" auth_failures=1 duration=10 reason=WRONG_KEY" Oct 16 20:58:29 volumio wpa_supplicant[4867]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Oct 16 20:58:29 volumio wpa_supplicant[4867]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Bozinovski" auth_failures=1 duration=10 reason=WRONG_KEY Oct 16 20:58:29 volumio dhcpcd[926]: wlan0: carrier lost Oct 16 20:58:29 volumio wpa_supplicant[4867]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 16 20:58:29 volumio wpa_supplicant[4867]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Oct 16 20:58:29 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:29.641+02:00 level=ERROR msg="failed to connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="00:00:00:00:00:00%01 @ 0xc00032a900" latency=35.937816801s timeout=1m0s ssid=Bozinovski error="failed to connect temporarily to WiFi network: WRONG_KEY" Oct 16 20:58:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 126. Oct 16 20:58:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:29 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:29 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:29 volumio go-librespot[4938]: go-librespot daemon starting... Oct 16 20:58:29 volumio go-librespot[4939]: time="2026-10-16T20:58:29+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:29 volumio go-librespot[4939]: time="2026-10-16T20:58:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:29 volumio go-librespot[4939]: time="2026-10-16T20:58:29+02:00" level=debug msg="app state loaded" Oct 16 20:58:29 volumio go-librespot[4939]: time="2026-10-16T20:58:29+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:30 volumio volumio[1683]: info: Received Get System Info Oct 16 20:58:30 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:58:30 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:58:30 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:58:30 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:58:30 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:58:30 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:58:30 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:30.086+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 20:58:30 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:58:30 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:58:30 volumio ntpd[1220]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 20:58:30 volumio ntpd[1220]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 16 20:58:30 volumio ntpd[1220]: DNS: dns_check: DNS error: -11, System error Oct 16 20:58:30 volumio ntpd[1220]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Oct 16 20:58:30 volumio volumio5-onboarding[2162]: time=2026-10-16T20:58:30.946+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:58:31 volumio ntpd[1220]: IO: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Oct 16 20:58:32 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:32 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 127. Oct 16 20:58:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:33 volumio go-librespot[4954]: go-librespot daemon starting... Oct 16 20:58:33 volumio go-librespot[4955]: time="2026-10-16T20:58:33+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:33 volumio go-librespot[4955]: time="2026-10-16T20:58:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:33 volumio go-librespot[4955]: time="2026-10-16T20:58:33+02:00" level=debug msg="app state loaded" Oct 16 20:58:33 volumio go-librespot[4955]: time="2026-10-16T20:58:33+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:35 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:35 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 128. Oct 16 20:58:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:36 volumio go-librespot[4981]: go-librespot daemon starting... Oct 16 20:58:36 volumio go-librespot[4982]: time="2026-10-16T20:58:36+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:36 volumio go-librespot[4982]: time="2026-10-16T20:58:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:36 volumio go-librespot[4982]: time="2026-10-16T20:58:36+02:00" level=debug msg="app state loaded" Oct 16 20:58:36 volumio go-librespot[4982]: time="2026-10-16T20:58:36+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:38 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:38 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129. Oct 16 20:58:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:39 volumio go-librespot[4995]: go-librespot daemon starting... Oct 16 20:58:39 volumio go-librespot[4996]: time="2026-10-16T20:58:39+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:39 volumio go-librespot[4996]: time="2026-10-16T20:58:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:39 volumio go-librespot[4996]: time="2026-10-16T20:58:39+02:00" level=debug msg="app state loaded" Oct 16 20:58:39 volumio go-librespot[4996]: time="2026-10-16T20:58:39+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:41 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:41 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 130. Oct 16 20:58:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:42 volumio go-librespot[5010]: go-librespot daemon starting... Oct 16 20:58:42 volumio go-librespot[5011]: time="2026-10-16T20:58:42+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:42 volumio go-librespot[5011]: time="2026-10-16T20:58:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:42 volumio go-librespot[5011]: time="2026-10-16T20:58:42+02:00" level=debug msg="app state loaded" Oct 16 20:58:42 volumio go-librespot[5011]: time="2026-10-16T20:58:42+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:44 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:44 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 131. Oct 16 20:58:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:46 volumio go-librespot[5038]: go-librespot daemon starting... Oct 16 20:58:46 volumio go-librespot[5040]: time="2026-10-16T20:58:46+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:46 volumio go-librespot[5040]: time="2026-10-16T20:58:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:46 volumio go-librespot[5040]: time="2026-10-16T20:58:46+02:00" level=debug msg="app state loaded" Oct 16 20:58:46 volumio go-librespot[5040]: time="2026-10-16T20:58:46+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:47 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:47 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 132. Oct 16 20:58:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:49 volumio go-librespot[5054]: go-librespot daemon starting... Oct 16 20:58:49 volumio go-librespot[5055]: time="2026-10-16T20:58:49+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:49 volumio go-librespot[5055]: time="2026-10-16T20:58:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:49 volumio go-librespot[5055]: time="2026-10-16T20:58:49+02:00" level=debug msg="app state loaded" Oct 16 20:58:49 volumio go-librespot[5055]: time="2026-10-16T20:58:49+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:50 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:50 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 133. Oct 16 20:58:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:52 volumio go-librespot[5068]: go-librespot daemon starting... Oct 16 20:58:52 volumio go-librespot[5069]: time="2026-10-16T20:58:52+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:52 volumio go-librespot[5069]: time="2026-10-16T20:58:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:52 volumio go-librespot[5069]: time="2026-10-16T20:58:52+02:00" level=debug msg="app state loaded" Oct 16 20:58:52 volumio go-librespot[5069]: time="2026-10-16T20:58:52+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:53 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:53 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 134. Oct 16 20:58:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:55 volumio go-librespot[5096]: go-librespot daemon starting... Oct 16 20:58:55 volumio go-librespot[5097]: time="2026-10-16T20:58:55+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:55 volumio go-librespot[5097]: time="2026-10-16T20:58:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:55 volumio go-librespot[5097]: time="2026-10-16T20:58:55+02:00" level=debug msg="app state loaded" Oct 16 20:58:55 volumio go-librespot[5097]: time="2026-10-16T20:58:55+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:58:56 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:58:56 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:58:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 135. Oct 16 20:58:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:58:59 volumio go-librespot[5109]: go-librespot daemon starting... Oct 16 20:58:59 volumio go-librespot[5110]: time="2026-10-16T20:58:59+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:58:59 volumio go-librespot[5110]: time="2026-10-16T20:58:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:58:59 volumio go-librespot[5110]: time="2026-10-16T20:58:59+02:00" level=debug msg="app state loaded" Oct 16 20:58:59 volumio go-librespot[5110]: time="2026-10-16T20:58:59+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:58:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:58:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:00 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:00 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 136. Oct 16 20:59:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:02 volumio go-librespot[5125]: go-librespot daemon starting... Oct 16 20:59:02 volumio go-librespot[5126]: time="2026-10-16T20:59:02+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:02 volumio go-librespot[5126]: time="2026-10-16T20:59:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:02 volumio go-librespot[5126]: time="2026-10-16T20:59:02+02:00" level=debug msg="app state loaded" Oct 16 20:59:02 volumio go-librespot[5126]: time="2026-10-16T20:59:02+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:03 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:03 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 137. Oct 16 20:59:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:05 volumio go-librespot[5153]: go-librespot daemon starting... Oct 16 20:59:05 volumio go-librespot[5154]: time="2026-10-16T20:59:05+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:05 volumio go-librespot[5154]: time="2026-10-16T20:59:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:05 volumio go-librespot[5154]: time="2026-10-16T20:59:05+02:00" level=debug msg="app state loaded" Oct 16 20:59:05 volumio go-librespot[5154]: time="2026-10-16T20:59:05+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:06 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:06 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:08 volumio kernel: usb 1-1: new full-speed USB device number 3 using xhci_hcd Oct 16 20:59:08 volumio kernel: usb 1-1: New USB device found, idVendor=145f, idProduct=02a5, bcdDevice= 1.00 Oct 16 20:59:08 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Oct 16 20:59:08 volumio kernel: usb 1-1: Product: GXT 922 Gaming Mouse Oct 16 20:59:08 volumio kernel: input: GXT 922 Gaming Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1:1.0/0003:145F:02A5.0001/input/input9 Oct 16 20:59:08 volumio kernel: hid-generic 0003:145F:02A5.0001: input,hidraw0: USB HID v1.11 Mouse [GXT 922 Gaming Mouse] on usb-0000:00:14.0-1/input0 Oct 16 20:59:08 volumio kernel: input: GXT 922 Gaming Mouse Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1:1.1/0003:145F:02A5.0002/input/input11 Oct 16 20:59:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 138. Oct 16 20:59:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:08 volumio kernel: hid-generic 0003:145F:02A5.0002: input,hiddev0,hidraw1: USB HID v1.11 Keyboard [GXT 922 Gaming Mouse] on usb-0000:00:14.0-1/input1 Oct 16 20:59:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:08 volumio go-librespot[5168]: go-librespot daemon starting... Oct 16 20:59:08 volumio go-librespot[5169]: time="2026-10-16T20:59:08+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:09 volumio kernel: usbcore: registered new interface driver usbmouse Oct 16 20:59:09 volumio go-librespot[5169]: time="2026-10-16T20:59:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:09 volumio go-librespot[5169]: time="2026-10-16T20:59:09+02:00" level=debug msg="app state loaded" Oct 16 20:59:09 volumio go-librespot[5169]: time="2026-10-16T20:59:09+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:09 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:09 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:09 volumio systemd-logind[851]: Watching system buttons on /dev/input/event10 (GXT 922 Gaming Mouse Keyboard) Oct 16 20:59:12 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:12 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 139. Oct 16 20:59:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:12 volumio go-librespot[5192]: go-librespot daemon starting... Oct 16 20:59:12 volumio go-librespot[5193]: time="2026-10-16T20:59:12+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:12 volumio go-librespot[5193]: time="2026-10-16T20:59:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:12 volumio go-librespot[5193]: time="2026-10-16T20:59:12+02:00" level=debug msg="app state loaded" Oct 16 20:59:12 volumio go-librespot[5193]: time="2026-10-16T20:59:12+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:15 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:15 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 140. Oct 16 20:59:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:15 volumio go-librespot[5227]: go-librespot daemon starting... Oct 16 20:59:15 volumio go-librespot[5228]: time="2026-10-16T20:59:15+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:15 volumio go-librespot[5228]: time="2026-10-16T20:59:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:15 volumio go-librespot[5228]: time="2026-10-16T20:59:15+02:00" level=debug msg="app state loaded" Oct 16 20:59:15 volumio go-librespot[5228]: time="2026-10-16T20:59:15+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:16 volumio volumio[1683]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 20:59:16 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 20:59:17 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 16 20:59:17 volumio sudo[5254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 16 20:59:17 volumio sudo[5254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:17 volumio sudo[5265]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 20:59:17 volumio sudo[5265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:17 volumio sudo[5259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 20:59:17 volumio sudo[5259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:17 volumio sudo[5265]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:17 volumio sudo[5259]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:17 volumio sudo[5270]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 20:59:17 volumio sudo[5270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:17 volumio sudo[5270]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:17 volumio sudo[5254]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:17 volumio sudo[5275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 20:59:17 volumio sudo[5275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:17 volumio sudo[5281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:17 volumio sudo[5281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:17 volumio sudo[5275]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:17 volumio sudo[5281]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:17 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 16 20:59:17 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 16 20:59:17 volumio sudo[5286]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 16 20:59:17 volumio sudo[5286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:18 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:18 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 141. Oct 16 20:59:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:18 volumio go-librespot[5290]: go-librespot daemon starting... Oct 16 20:59:18 volumio go-librespot[5291]: time="2026-10-16T20:59:18+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:18 volumio go-librespot[5291]: time="2026-10-16T20:59:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:18 volumio go-librespot[5291]: time="2026-10-16T20:59:18+02:00" level=debug msg="app state loaded" Oct 16 20:59:18 volumio go-librespot[5291]: time="2026-10-16T20:59:18+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:19 volumio sudo[5286]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:19 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:19.767+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 20:59:19 volumio volumio[1683]: info: Received Get System Info Oct 16 20:59:19 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:59:19 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:59:19 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:59:19 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:19 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:19 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:59:19 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:59:19 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:59:20 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:20.685+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:59:21 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:21 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 142. Oct 16 20:59:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:21 volumio go-librespot[5305]: go-librespot daemon starting... Oct 16 20:59:21 volumio go-librespot[5306]: time="2026-10-16T20:59:21+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:21 volumio go-librespot[5306]: time="2026-10-16T20:59:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:21 volumio go-librespot[5306]: time="2026-10-16T20:59:21+02:00" level=debug msg="app state loaded" Oct 16 20:59:22 volumio go-librespot[5306]: time="2026-10-16T20:59:21+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:24 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:24 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 143. Oct 16 20:59:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:25 volumio go-librespot[5333]: go-librespot daemon starting... Oct 16 20:59:25 volumio go-librespot[5334]: time="2026-10-16T20:59:25+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:25 volumio go-librespot[5334]: time="2026-10-16T20:59:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:25 volumio go-librespot[5334]: time="2026-10-16T20:59:25+02:00" level=debug msg="app state loaded" Oct 16 20:59:25 volumio go-librespot[5334]: time="2026-10-16T20:59:25+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:27 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:27 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 144. Oct 16 20:59:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:28 volumio go-librespot[5347]: go-librespot daemon starting... Oct 16 20:59:28 volumio go-librespot[5348]: time="2026-10-16T20:59:28+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:28 volumio go-librespot[5348]: time="2026-10-16T20:59:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:28 volumio go-librespot[5348]: time="2026-10-16T20:59:28+02:00" level=debug msg="app state loaded" Oct 16 20:59:28 volumio go-librespot[5348]: time="2026-10-16T20:59:28+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:30 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:30 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 145. Oct 16 20:59:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:31 volumio go-librespot[5364]: go-librespot daemon starting... Oct 16 20:59:31 volumio go-librespot[5365]: time="2026-10-16T20:59:31+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:31 volumio go-librespot[5365]: time="2026-10-16T20:59:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:31 volumio go-librespot[5365]: time="2026-10-16T20:59:31+02:00" level=debug msg="app state loaded" Oct 16 20:59:31 volumio go-librespot[5365]: time="2026-10-16T20:59:31+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:33 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:33 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 146. Oct 16 20:59:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:34 volumio go-librespot[5392]: go-librespot daemon starting... Oct 16 20:59:34 volumio go-librespot[5393]: time="2026-10-16T20:59:34+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:34 volumio go-librespot[5393]: time="2026-10-16T20:59:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:34 volumio go-librespot[5393]: time="2026-10-16T20:59:34+02:00" level=debug msg="app state loaded" Oct 16 20:59:34 volumio go-librespot[5393]: time="2026-10-16T20:59:34+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:36 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:36 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 147. Oct 16 20:59:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:38 volumio go-librespot[5406]: go-librespot daemon starting... Oct 16 20:59:38 volumio go-librespot[5407]: time="2026-10-16T20:59:38+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:38 volumio go-librespot[5407]: time="2026-10-16T20:59:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:38 volumio go-librespot[5407]: time="2026-10-16T20:59:38+02:00" level=debug msg="app state loaded" Oct 16 20:59:38 volumio go-librespot[5407]: time="2026-10-16T20:59:38+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:39 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:39 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 148. Oct 16 20:59:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:41 volumio go-librespot[5420]: go-librespot daemon starting... Oct 16 20:59:41 volumio go-librespot[5421]: time="2026-10-16T20:59:41+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:41 volumio go-librespot[5421]: time="2026-10-16T20:59:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:41 volumio go-librespot[5421]: time="2026-10-16T20:59:41+02:00" level=debug msg="app state loaded" Oct 16 20:59:41 volumio go-librespot[5421]: time="2026-10-16T20:59:41+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:42 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:42 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 20:59:43 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Oct 16 20:59:43 volumio volumio[1683]: info: Saving new wireless network Oct 16 20:59:43 volumio sudo[5434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Oct 16 20:59:43 volumio sudo[5434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:43 volumio sudo[5434]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:43 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 16 20:59:43 volumio sudo[5437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 16 20:59:43 volumio sudo[5437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:43 volumio systemd[1]: Stopping wireless.service - Wireless Services... Oct 16 20:59:43 volumio systemd[1]: wireless.service: Deactivated successfully. Oct 16 20:59:43 volumio systemd[1]: Stopped wireless.service - Wireless Services. Oct 16 20:59:43 volumio systemd[1]: wireless.service: Consumed 45.147s CPU time. Oct 16 20:59:43 volumio systemd[1]: Starting wireless.service - Wireless Services... Oct 16 20:59:43 volumio wireless.js[5442]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Oct 16 20:59:43 volumio wireless.js[5442]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Oct 16 20:59:43 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Oct 16 20:59:43 volumio dnsmasq[2555]: exiting on receipt of SIGTERM Oct 16 20:59:43 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Oct 16 20:59:43 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Oct 16 20:59:43 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Oct 16 20:59:43 volumio systemd[1]: hostapd.service: Deactivated successfully. Oct 16 20:59:43 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Oct 16 20:59:43 volumio sudo[5468]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 20:59:43 volumio sudo[5468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:43 volumio avahi-daemon[838]: Withdrawing address record for 192.168.211.1 on wlan0. Oct 16 20:59:43 volumio avahi-daemon[838]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 16 20:59:43 volumio sudo[5468]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:43 volumio avahi-daemon[838]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 16 20:59:43 volumio volumio[1683]: info: Discovery: A device disappeared from network Oct 16 20:59:43 volumio sudo[5471]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 20:59:43 volumio sudo[5471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:43 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 16 20:59:43 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Oct 16 20:59:43 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 16 20:59:43 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 16 20:59:43 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 16 20:59:43 volumio sudo[5471]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:43 volumio wireless.js[5442]: WIRELESS.JS - INFO: Cleaning previous... Oct 16 20:59:44 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 16 20:59:44 volumio welcome[5473]: Resolved ip:[0] Oct 16 20:59:44 volumio sudo[5475]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 20:59:44 volumio sudo[5475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:44 volumio sudo[5475]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:44 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 16 20:59:44 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 16 20:59:44 volumio wireless.js[5442]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Oct 16 20:59:44 volumio wireless.js[5442]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 3ms Oct 16 20:59:44 volumio wireless.js[5442]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 38:a2:8c:ca:79:f4) Oct 16 20:59:44 volumio sudo[5486]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 16 20:59:44 volumio sudo[5486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:44 volumio sudo[5486]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:44 volumio sudo[5495]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 16 20:59:44 volumio sudo[5495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:44 volumio sudo[5498]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:44 volumio sudo[5498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:44 volumio sudo[5498]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:44 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:44.613+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 20:59:44 volumio volumio[1683]: info: Received Get System Info Oct 16 20:59:44 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:59:44 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:59:44 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:59:44 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:44 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:44 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:59:44 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:59:44 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:59:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 149. Oct 16 20:59:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:44 volumio go-librespot[5516]: go-librespot daemon starting... Oct 16 20:59:44 volumio go-librespot[5517]: time="2026-10-16T20:59:44+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:44 volumio go-librespot[5517]: time="2026-10-16T20:59:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:44 volumio go-librespot[5517]: time="2026-10-16T20:59:44+02:00" level=debug msg="app state loaded" Oct 16 20:59:44 volumio go-librespot[5517]: time="2026-10-16T20:59:44+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:45 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:45 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: AggregateError Oct 16 20:59:45 volumio sudo[5528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:45 volumio sudo[5528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:45 volumio sudo[5528]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:45 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:45.556+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:59:46 volumio sudo[5495]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:46 volumio wireless.js[5442]: WIRELESS.JS - INFO: Regdomain already correct: DE Oct 16 20:59:46 volumio wireless.js[5442]: WIRELESS.JS - INFO: Start wireless flow Oct 16 20:59:46 volumio wireless.js[5442]: WIRELESS.JS - INFO: Stopped hotspot (if there).. Oct 16 20:59:46 volumio sudo[5536]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 20:59:46 volumio sudo[5536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:46 volumio sudo[5536]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:46 volumio sudo[5538]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 20:59:46 volumio sudo[5538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:46 volumio sudo[5538]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:46 volumio sudo[5541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:46 volumio sudo[5541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:46 volumio sudo[5541]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:46 volumio wireless.js[5442]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Oct 16 20:59:46 volumio wireless.js[5442]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: 38:a2:8c:ca:79:f4, USB: false) Oct 16 20:59:46 volumio wpa_supplicant[5547]: Successfully initialized wpa_supplicant Oct 16 20:59:46 volumio sudo[5551]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 20:59:46 volumio sudo[5551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:46 volumio sudo[5551]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:46 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:46.785+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 20:59:46 volumio volumio[1683]: info: Received Get System Info Oct 16 20:59:46 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:59:46 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:59:46 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:59:46 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:46 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:46 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:59:46 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:59:46 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:59:47 volumio sudo[5558]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:47 volumio sudo[5558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:47 volumio sudo[5558]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:47 volumio wireless.js[5442]: WIRELESS.JS - INFO: DHCP IP fallback Oct 16 20:59:47 volumio wireless.js[5442]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor Oct 16 20:59:47 volumio wireless.js[5442]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 Oct 16 20:59:47 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:47.729+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:59:47 volumio wireless.js[5442]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) Oct 16 20:59:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 150. Oct 16 20:59:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:47 volumio go-librespot[5567]: go-librespot daemon starting... Oct 16 20:59:47 volumio go-librespot[5568]: time="2026-10-16T20:59:47+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:48 volumio go-librespot[5568]: time="2026-10-16T20:59:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:48 volumio go-librespot[5568]: time="2026-10-16T20:59:48+02:00" level=debug msg="app state loaded" Oct 16 20:59:48 volumio go-librespot[5568]: time="2026-10-16T20:59:48+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:48 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:48 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: AggregateError Oct 16 20:59:48 volumio sudo[5580]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:48 volumio sudo[5580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:48 volumio sudo[5580]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:48 volumio wpa_supplicant[5548]: wlan0: Trying to associate with d6:92:5e:92:a9:80 (SSID='Bozinovski' freq=2462 MHz) Oct 16 20:59:48 volumio wireless.js[5442]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> ASSOCIATING (duration: 777ms) Oct 16 20:59:48 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:48.707+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 20:59:48 volumio volumio[1683]: info: Received Get System Info Oct 16 20:59:48 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:59:48 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:59:48 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:59:48 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:48 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:48 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:59:48 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:59:48 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:59:49 volumio wpa_supplicant[5548]: wlan0: Associated with d6:92:5e:92:a9:80 Oct 16 20:59:49 volumio wpa_supplicant[5548]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 16 20:59:49 volumio wpa_supplicant[5548]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 16 20:59:49 volumio sudo[5589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:49 volumio sudo[5589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:49 volumio sudo[5589]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:49 volumio wpa_supplicant[5548]: wlan0: WPA: Key negotiation completed with d6:92:5e:92:a9:80 [PTK=CCMP GTK=CCMP] Oct 16 20:59:49 volumio wpa_supplicant[5548]: wlan0: CTRL-EVENT-CONNECTED - Connection to d6:92:5e:92:a9:80 completed [id=0 id_str=] Oct 16 20:59:49 volumio dhcpcd[926]: wlan0: carrier acquired Oct 16 20:59:49 volumio dhcpcd[926]: wlan0: IAID 8c:ca:79:f4 Oct 16 20:59:49 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:49.643+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:59:49 volumio wireless.js[5442]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> COMPLETED (duration: 1029ms) Oct 16 20:59:49 volumio wireless.js[5442]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful Oct 16 20:59:49 volumio wireless.js[5442]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to d6:92:5e:92:a9:80 Oct 16 20:59:49 volumio wireless.js[5442]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow Oct 16 20:59:49 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:49.713+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=true macAddress=38:a2:8c:ca:79:f4 ip4Address= ip6Address= ssid=Bozinovski Oct 16 20:59:49 volumio volumio[1683]: info: Received Get System Info Oct 16 20:59:49 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:59:49 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:59:49 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:59:49 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:49 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:49 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:59:49 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:59:49 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:59:49 volumio dhcpcd[926]: wlan0: soliciting an IPv6 router Oct 16 20:59:50 volumio sudo[5601]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:50 volumio sudo[5601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:50 volumio sudo[5601]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:50 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:50.644+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 20:59:50 volumio sudo[5604]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 Oct 16 20:59:50 volumio sudo[5604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:50 volumio dhcpcd[926]: control_free: No such file or directory Oct 16 20:59:50 volumio dhcpcd[926]: ps_ctl_dispatch: cannot handle another client Oct 16 20:59:50 volumio sudo[5604]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:51 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:51 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: AggregateError Oct 16 20:59:51 volumio dhcpcd[926]: wlan0: soliciting a DHCP lease Oct 16 20:59:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 151. Oct 16 20:59:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:51 volumio sudo[5608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:51 volumio sudo[5608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:51 volumio go-librespot[5609]: go-librespot daemon starting... Oct 16 20:59:51 volumio sudo[5608]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:51 volumio go-librespot[5611]: time="2026-10-16T20:59:51+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:51 volumio go-librespot[5611]: time="2026-10-16T20:59:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:51 volumio go-librespot[5611]: time="2026-10-16T20:59:51+02:00" level=debug msg="app state loaded" Oct 16 20:59:51 volumio go-librespot[5611]: time="2026-10-16T20:59:51+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:52 volumio sudo[5623]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:52 volumio sudo[5623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:52 volumio sudo[5623]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:52 volumio wireless.js[5442]: WIRELESS.JS - INFO: Start ap Oct 16 20:59:52 volumio wireless.js[5442]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 16 20:59:52 volumio systemd[1]: Started wireless.service - Wireless Services. Oct 16 20:59:52 volumio sudo[5437]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:53 volumio volumio[1683]: info: Discovery: Networking Restart detected, restarting advertisement and browsing Oct 16 20:59:53 volumio volumio[1683]: info: Discovery: Restarting Advertising Oct 16 20:59:53 volumio volumio[1683]: info: Discovery: Stopping existing advertisement Oct 16 20:59:53 volumio volumio[1683]: info: Discovery: Restarting Browsing Oct 16 20:59:53 volumio sudo[5633]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:53 volumio sudo[5633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:53 volumio sudo[5633]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:53 volumio wireless.js[5442]: WIRELESS.JS - INFO: trying... Oct 16 20:59:53 volumio sudo[5645]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 20:59:53 volumio sudo[5645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:53 volumio sudo[5645]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:53 volumio sudo[5648]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:53 volumio sudo[5648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:53 volumio sudo[5648]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:53 volumio wireless.js[5442]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Oct 16 20:59:54 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:54 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: AggregateError Oct 16 20:59:54 volumio volumio[1683]: info: Discovery: A device disappeared from network Oct 16 20:59:54 volumio sudo[5651]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:54 volumio sudo[5651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:54 volumio dhcpcd[926]: wlan0: probing address 192.168.1.243/24 Oct 16 20:59:54 volumio sudo[5651]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 152. Oct 16 20:59:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:54 volumio go-librespot[5668]: go-librespot daemon starting... Oct 16 20:59:54 volumio go-librespot[5669]: time="2026-10-16T20:59:54+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:54 volumio go-librespot[5669]: time="2026-10-16T20:59:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:54 volumio go-librespot[5669]: time="2026-10-16T20:59:54+02:00" level=debug msg="app state loaded" Oct 16 20:59:54 volumio go-librespot[5669]: time="2026-10-16T20:59:54+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:54 volumio wireless.js[5442]: WIRELESS.JS - INFO: trying... Oct 16 20:59:54 volumio sudo[5689]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 20:59:54 volumio sudo[5689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:54 volumio sudo[5689]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:54 volumio sudo[5692]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:54 volumio sudo[5692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:54 volumio sudo[5692]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:54 volumio wireless.js[5442]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Oct 16 20:59:55 volumio sudo[5695]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:55 volumio sudo[5695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:55 volumio sudo[5695]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:55 volumio wireless.js[5442]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet Oct 16 20:59:55 volumio wireless.js[5442]: WIRELESS.JS - INFO: trying... Oct 16 20:59:55 volumio sudo[5712]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 20:59:55 volumio sudo[5712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:55 volumio sudo[5712]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:55 volumio sudo[5715]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:55 volumio sudo[5715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:55 volumio sudo[5715]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:55 volumio wireless.js[5442]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Oct 16 20:59:56 volumio sudo[5718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:56 volumio sudo[5718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:56 volumio sudo[5718]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:56 volumio wireless.js[5442]: WIRELESS.JS - INFO: trying... Oct 16 20:59:56 volumio sudo[5730]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 20:59:56 volumio sudo[5730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:56 volumio sudo[5730]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:56 volumio sudo[5733]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:56 volumio sudo[5733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:56 volumio sudo[5733]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:56 volumio wireless.js[5442]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Oct 16 20:59:57 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 20:59:57 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: AggregateError Oct 16 20:59:57 volumio sudo[5736]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:57 volumio sudo[5736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:57 volumio sudo[5736]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 153. Oct 16 20:59:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 20:59:57 volumio go-librespot[5738]: go-librespot daemon starting... Oct 16 20:59:57 volumio go-librespot[5739]: time="2026-10-16T20:59:57+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 20:59:57 volumio go-librespot[5739]: time="2026-10-16T20:59:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 20:59:57 volumio go-librespot[5739]: time="2026-10-16T20:59:57+02:00" level=debug msg="app state loaded" Oct 16 20:59:57 volumio go-librespot[5739]: time="2026-10-16T20:59:57+02:00" level=fatal msg="daemon exited with error" 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" Oct 16 20:59:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 20:59:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 20:59:57 volumio wireless.js[5442]: WIRELESS.JS - INFO: trying... Oct 16 20:59:57 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 16 20:59:57 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 16 20:59:57 volumio sudo[5760]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 20:59:57 volumio sudo[5760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:57 volumio sudo[5760]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:57 volumio sudo[5762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:57 volumio sudo[5762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:57 volumio sudo[5762]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:57 volumio sudo[5766]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:57 volumio sudo[5766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:57 volumio sudo[5766]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:57 volumio wireless.js[5442]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Oct 16 20:59:58 volumio volumio[1683]: info: Discovery: Started advertising with name: Volumio Oct 16 20:59:58 volumio sudo[5771]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:58 volumio sudo[5771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:58 volumio sudo[5771]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:58 volumio wireless.js[5442]: WIRELESS.JS - INFO: trying... Oct 16 20:59:58 volumio sudo[5783]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 20:59:58 volumio sudo[5783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:58 volumio sudo[5783]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:58 volumio sudo[5786]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:58 volumio sudo[5786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:58 volumio sudo[5786]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:58 volumio wireless.js[5442]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Oct 16 20:59:59 volumio volumio[1683]: info: Discovery: adding d26fdb17-cd25-4246-91be-76eff8738a93 Oct 16 20:59:59 volumio volumio[1683]: info: Discovery: Found device Volumio Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:59 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:59 volumio volumio[1683]: info: Discovery: this is already registered, d26fdb17-cd25-4246-91be-76eff8738a93 Oct 16 20:59:59 volumio volumio[1683]: info: Discovery: Found device Volumio Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:59 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:59 volumio sudo[5791]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:59 volumio sudo[5791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:59 volumio sudo[5791]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:59 volumio dhcpcd[926]: wlan0: leased 192.168.1.243 for 86400 seconds Oct 16 20:59:59 volumio avahi-daemon[838]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.243. Oct 16 20:59:59 volumio avahi-daemon[838]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 20:59:59 volumio avahi-daemon[838]: Registering new address record for 192.168.1.243 on wlan0.IPv4. Oct 16 20:59:59 volumio dhcpcd[926]: wlan0: adding route to 192.168.1.0/24 Oct 16 20:59:59 volumio dhcpcd[926]: wlan0: adding default route via 192.168.1.1 Oct 16 20:59:59 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 16 20:59:59 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Oct 16 20:59:59 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 16 20:59:59 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 16 20:59:59 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 16 20:59:59 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 16 20:59:59 volumio welcome[5806]: Resolved ip:[1] 192.168.1.243 Oct 16 20:59:59 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 16 20:59:59 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 16 20:59:59 volumio wireless.js[5442]: WIRELESS.JS - INFO: trying... Oct 16 20:59:59 volumio volumio5-onboarding[2162]: time=2026-10-16T20:59:59.873+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc00032a900" available=true connected=true macAddress=38:a2:8c:ca:79:f4 ip4Address=192.168.1.243/24 ip6Address= ssid=Bozinovski Oct 16 20:59:59 volumio volumio[1683]: info: Received Get System Info Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:59:59 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 20:59:59 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 20:59:59 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 20:59:59 volumio sudo[5831]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 20:59:59 volumio sudo[5831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 20:59:59 volumio sudo[5831]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:59 volumio sudo[5834]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 20:59:59 volumio sudo[5834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 20:59:59 volumio sudo[5834]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:59 volumio wireless.js[5442]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.243, ipV6 is undefined Oct 16 20:59:59 volumio wireless.js[5442]: WIRELESS.JS - INFO: Connected to SSID: Bozinovski Oct 16 20:59:59 volumio wireless.js[5442]: WIRELESS.JS - INFO: It's done! AP Oct 16 21:00:00 volumio wireless.js[5442]: WIRELESS.JS - INFO: Restarting avahi-daemon... Oct 16 21:00:00 volumio sudo[5839]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Oct 16 21:00:00 volumio sudo[5839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 21:00:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 21:00:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 21:00:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 21:00:00 volumio systemd[1]: shairport-sync.service: Consumed 11.498s CPU time. Oct 16 21:00:00 volumio avahi-daemon[838]: Got SIGTERM, quitting. Oct 16 21:00:00 volumio avahi-daemon[838]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.243. Oct 16 21:00:00 volumio avahi-daemon[838]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 16 21:00:00 volumio volumio5-onboarding[2162]: time=2026-10-16T21:00:00.061+02:00 level=WARN msg="disconnected from Avahi daemon, trying to reconnect" component=discovery/localnet error="avahi: Daemon connection failed" Oct 16 21:00:00 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Browse raised the following error Error: dns service error: unknown Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Restarting Browsing Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Browse raised the following error Error: dns service error: unknown Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Restarting Browsing Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Restart already pending, ignoring duplicate call Oct 16 21:00:00 volumio dbus-daemon[839]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.29' (uid=0 pid=2162 comm="/usr/bin/volumio5-onboarding") Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Browse raised the following error Error: dns service error: unknown Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Restarting Browsing Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Restart already pending, ignoring duplicate call Oct 16 21:00:00 volumio volumio[1683]: error: Discovery: Advertisement error: Error: dns service error: unknown Oct 16 21:00:00 volumio volumio[1683]: error: Discovery: advertisement error: Error: dns service error: unknown Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Stopping Advertising Immediately Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Stopping existing advertisement Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Browse raised the following error Error: dns service error: unknown Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Restarting Browsing Oct 16 21:00:00 volumio volumio[1683]: info: Discovery: Restart already pending, ignoring duplicate call Oct 16 21:00:00 volumio avahi-daemon[838]: avahi-daemon 0.8 exiting. Oct 16 21:00:00 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Oct 16 21:00:00 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 16 21:00:00 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 16 21:00:00 volumio avahi-daemon[5841]: Process 838 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Oct 16 21:00:00 volumio avahi-daemon[5841]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 16 21:00:00 volumio avahi-daemon[5841]: Successfully dropped root privileges. Oct 16 21:00:00 volumio avahi-daemon[5841]: avahi-daemon 0.8 starting up. Oct 16 21:00:00 volumio dbus-daemon[839]: [system] Successfully activated service 'org.freedesktop.Avahi' Oct 16 21:00:00 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 16 21:00:00 volumio avahi-daemon[5841]: Successfully called chroot(). Oct 16 21:00:00 volumio avahi-daemon[5841]: Successfully dropped remaining capabilities. Oct 16 21:00:00 volumio avahi-daemon[5841]: No service file found in /etc/avahi/services. Oct 16 21:00:00 volumio avahi-daemon[5841]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.243. Oct 16 21:00:00 volumio avahi-daemon[5841]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 21:00:00 volumio avahi-daemon[5841]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 16 21:00:00 volumio avahi-daemon[5841]: New relevant interface lo.IPv4 for mDNS. Oct 16 21:00:00 volumio avahi-daemon[5841]: Network interface enumeration completed. Oct 16 21:00:00 volumio avahi-daemon[5841]: Registering new address record for 192.168.1.243 on wlan0.IPv4. Oct 16 21:00:00 volumio avahi-daemon[5841]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 16 21:00:00 volumio sudo[5839]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 21:00:00 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 21:00:00 volumio volumio[1683]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 16 21:00:00 volumio wireless.js[5442]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 21:00:00 volumio volumio[1683]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 21:00:00 volumio volumio[1683]: info: Adding plugin multiroom to MyMusic Plugins Oct 16 21:00:00 volumio volumio[1683]: info: Adding plugin metavolumio to MyMusic Plugins Oct 16 21:00:00 volumio volumio[1683]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 21:00:00 volumio volumio[1683]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 16 21:00:00 volumio volumio[1683]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 21:00:00 volumio volumio[1683]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 21:00:00 volumio volumio[1683]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 21:00:00 volumio ntpd[1220]: IO: Listen normally on 6 wlan0 192.168.1.243:123 Oct 16 21:00:00 volumio ntpd[1220]: IO: new interface(s) found: waking up resolver Oct 16 21:00:00 volumio ntpd[1220]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 21:00:00 volumio ntpd[1220]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 16 21:00:00 volumio ntpd[1220]: DNS: Pool taking: 89.185.82.114 Oct 16 21:00:00 volumio ntpd[1220]: DNS: Pool taking: 79.99.56.72 Oct 16 21:00:00 volumio ntpd[1220]: DNS: Pool taking: 79.99.56.71 Oct 16 21:00:00 volumio ntpd[1220]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 16 21:00:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154. Oct 16 21:00:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 21:00:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 21:00:00 volumio go-librespot[5863]: go-librespot daemon starting... Oct 16 21:00:00 volumio go-librespot[5864]: time="2026-10-16T21:00:00+02:00" level=info msg="running go-librespot 0.9.1" Oct 16 21:00:00 volumio avahi-daemon[5841]: Server startup complete. Host name is volumio.local. Local service cookie is 3748188076. Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="app state loaded" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=info msg="zeroconf server listening on port 42985" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 16 21:00:01 volumio ntpd[1220]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 21:00:01 volumio ntpd[1220]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 16 21:00:01 volumio ntpd[1220]: DNS: Pool skipping: 79.99.56.71 Oct 16 21:00:01 volumio ntpd[1220]: DNS: Pool skipping: 79.99.56.72 Oct 16 21:00:01 volumio ntpd[1220]: DNS: Pool skipping: 89.185.82.114 Oct 16 21:00:01 volumio ntpd[1220]: DNS: Pool taking: 2a01:a180:ffff::72 Oct 16 21:00:01 volumio ntpd[1220]: DNS: Pool taking: 2a05:5502:12:11::123 Oct 16 21:00:01 volumio ntpd[1220]: DNS: Pool taking: 2a13:9400:10:1002::123 Oct 16 21:00:01 volumio ntpd[1220]: DNS: Pool taking: 2a01:a180:ffff::71 Oct 16 21:00:01 volumio ntpd[1220]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 16 21:00:01 volumio volumio[1683]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 21:00:01 volumio volumio[1683]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 21:00:01 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 21:00:01 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 21:00:01 volumio volumio[1683]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="obtained new client token: AAFwl+FX1E5I184qirvr5djtts9yhvX5ZIMwvfJhyhpdGUhJmUG3z6hKgYamhMCCRrhOk5eR9O/KhhFbq4dTl664JJ+GpgQ1zF5oaQhaoB4GttrOWpYQJIqmpaS9AKduRHXV0KTuulXg2miIVNU3fMWw5QNXErjUZFvIJoZ1IuCjxOy/xHO7nlWny7Dyyc/HKKlZiaL5OH7l+DYFYjc/PDUqVXQbfTRTJLIK1l83O80QdXerr8YQ43OzYQ==" Oct 16 21:00:01 volumio volumio[1683]: info: MyVolumio login type: Token Oct 16 21:00:01 volumio volumio[1683]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 21:00:01 volumio volumio[1683]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="completed keyexchange" Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=debug msg="completed challenge" Oct 16 21:00:01 volumio volumio5-onboarding[2162]: time=2026-10-16T21:00:01.862+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 21:00:01 volumio go-librespot[5864]: time="2026-10-16T21:00:01+02:00" level=info msg="authenticated AP" username="ow*********************az" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=info msg="authenticated Login5" username="ow*********************az" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="initializing zeroconf session" username="ow*********************az" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="avahi service \"Volumio\" established" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="connected to gew1-dealer.spotify.com:443" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=trace msg="starting accesspoint recv loop" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=trace msg="starting dealer recv loop" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="received connection id: YTg3Mzc4NjctZWU5...RTgwNkZEQUEzMg==" Oct 16 21:00:02 volumio ntpd[1220]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=trace msg="emitting websocket event: playback_ready" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 16 21:00:02 volumio ntpd[1220]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 16 21:00:02 volumio ntpd[1220]: DNS: Pool skipping: 79.99.56.72 Oct 16 21:00:02 volumio ntpd[1220]: DNS: Pool skipping: 79.99.56.71 Oct 16 21:00:02 volumio ntpd[1220]: DNS: Pool skipping: 89.185.82.114 Oct 16 21:00:02 volumio ntpd[1220]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 16 21:00:02 volumio go-librespot[5864]: time="2026-10-16T21:00:02+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 16 21:00:03 volumio volumio[1683]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 21:00:03 volumio volumio[1683]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 21:00:03 volumio volumio[1683]: info: Streaming services startup Oct 16 21:00:03 volumio volumio[1683]: info: Starting Streaming Daemon Oct 16 21:00:03 volumio volumio[1683]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 16 21:00:03 volumio sudo[5883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 21:00:03 volumio sudo[5883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:03 volumio sudo[5883]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:03 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 16 21:00:03 volumio sudo[5890]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 21:00:03 volumio sudo[5890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:03 volumio sudo[5890]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:03 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 16 21:00:03 volumio volumio[1683]: info: Refreshing Cached IP Addresses Oct 16 21:00:03 volumio sudo[5893]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 16 21:00:03 volumio sudo[5893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:03 volumio sudo[5893]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:03 volumio sudo[5897]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 21:00:03 volumio sudo[5895]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 21:00:03 volumio sudo[5897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:03 volumio sudo[5895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:03 volumio sudo[5895]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:03 volumio sudo[5897]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:03 volumio volumio[1683]: info: Initializing connection to go-librespot Websocket Oct 16 21:00:03 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 21:00:03 volumio volumio[1683]: info: Received Get System Info Oct 16 21:00:03 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 21:00:03 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 21:00:03 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 21:00:03 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 21:00:03 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 21:00:03 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 21:00:03 volumio sudo[5903]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 21:00:03 volumio sudo[5903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:03 volumio sudo[5903]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:03 volumio volumio[1683]: error: Cannot start Volumio Streaming Daemon Oct 16 21:00:03 volumio volumio[1683]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 21:00:03 volumio volumio[1683]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 21:00:03 volumio go-librespot[5864]: time="2026-10-16T21:00:03+02:00" level=debug msg="new websocket client" Oct 16 21:00:03 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Oct 16 21:00:03 volumio systemd[1]: upmpdcli.service: Consumed 12.657s CPU time. Oct 16 21:00:03 volumio volumio[1683]: info: Connection to go-librespot Websocket established Oct 16 21:00:03 volumio ntpd[1220]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 21:00:03 volumio ntpd[1220]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 16 21:00:03 volumio ntpd[1220]: DNS: Pool skipping: 79.99.56.72 Oct 16 21:00:03 volumio ntpd[1220]: DNS: Pool skipping: 89.185.82.114 Oct 16 21:00:03 volumio ntpd[1220]: DNS: Pool skipping: 79.99.56.71 Oct 16 21:00:03 volumio ntpd[1220]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 16 21:00:03 volumio volumio[1683]: error: MyVolumio Custom Token format not valid, refreshing it Oct 16 21:00:04 volumio volumio[1683]: info: Volumio Network Manager: Network status updated: 2 Oct 16 21:00:04 volumio volumio[1683]: info: MyVolumio login type: Token Oct 16 21:00:04 volumio volumio[1683]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 16 21:00:05 volumio volumio[1683]: info: Discovery: Restarting Advertising Oct 16 21:00:05 volumio volumio[1683]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 21:00:06 volumio volumio[1683]: info: MyVolumio token set successfully Oct 16 21:00:06 volumio volumio[1683]: info: MYVOLUMIO: Adding device Oct 16 21:00:06 volumio volumio[1683]: info: MYVOLUMIO: Evaluating Server Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 21:00:06 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 21:00:06 volumio volumio[1683]: info: Received Get System Info Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 21:00:06 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 21:00:06 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 21:00:06 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 21:00:06 volumio volumio[1683]: info: Listing playlists Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 21:00:06 volumio volumio[1683]: info: Received Get System Info Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 21:00:06 volumio volumio[1683]: info: Discovery: Getting this device information Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::volumioGetState Oct 16 21:00:06 volumio volumio[1683]: info: CorePlayQueue::getTrack 0 Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 21:00:06 volumio volumio[1683]: info: MyVolumio status changed Oct 16 21:00:06 volumio volumio[1683]: info: Streaming services startup Oct 16 21:00:06 volumio volumio[1683]: info: Starting Streaming Daemon Oct 16 21:00:06 volumio volumio[1683]: info: Removing browser output: myVolumio user plan is not superstar Oct 16 21:00:06 volumio volumio[1683]: info: Removing audio output: Oct 16 21:00:06 volumio volumio[1683]: info: Stoppping Tunnel 1 Oct 16 21:00:06 volumio sudo[5946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 21:00:06 volumio sudo[5946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:06 volumio volumio[1683]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 21:00:06 volumio sudo[5946]: pam_unix(sudo:session): session closed for user root Oct 16 21:00:06 volumio sudo[5948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 16 21:00:06 volumio sudo[5948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 21:00:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 21:00:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 21:00:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 21:00:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 21:00:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 21:00:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 20:59:30 volumio systemd-journald[584]: Time jumped backwards, rotating. Oct 16 20:59:30 volumio ntpd[1220]: CLOCK: time stepped by -35.906695 Oct 16 20:59:30 volumio ntpd[1220]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Oct 16 20:59:30 volumio sudo[5948]: pam_unix(sudo:session): session closed for user root Oct 16 20:59:30 volumio volumio[1683]: info: Getting Spotify volume Oct 16 20:59:30 volumio volumio[1683]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Oct 16 20:59:30 volumio volumio[1683]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:59:30 volumio volumio[1683]: Error: Unable to resolve or reject the same promise twice Oct 16 20:59:30 volumio volumio[1683]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 16 20:59:30 volumio volumio[1683]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Oct 16 20:59:30 volumio volumio[1683]: at Socket.emit (node:events:514:28) Oct 16 20:59:30 volumio volumio[1683]: at addChunk (node:internal/streams/readable:343:12) Oct 16 20:59:30 volumio volumio[1683]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 16 20:59:30 volumio volumio[1683]: at Readable.push (node:internal/streams/readable:253:10) Oct 16 20:59:30 volumio volumio[1683]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 16 20:59:31 volumio volumio[1683]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:59:31 volumio sudo[5985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 20:58' Oct 16 20:59:31 volumio sudo[5985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"