Dec 18 15:25:00 sdp wireless.js[7103]: WIRELESS.JS: trying... Dec 18 15:25:00 sdp sudo[7458]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:00 sdp sudo[7458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:00 sdp sudo[7458]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:00 sdp sudo[7461]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:00 sdp sudo[7461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:00 sdp sudo[7461]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:01 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 18 15:25:01 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:01 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:01 sdp go-librespot[7463]: go-librespot daemon starting... Dec 18 15:25:01 sdp go-librespot[7464]: time="2025-12-18T15:25:01-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:01 sdp go-librespot[7464]: time="2025-12-18T15:25:01-05:00" level=debug msg="app state loaded" Dec 18 15:25:01 sdp go-librespot[7464]: time="2025-12-18T15:25:01-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:01 sdp go-librespot[7464]: time="2025-12-18T15:25:01-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:01 sdp go-librespot[7464]: time="2025-12-18T15:25:01-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:01 sdp wireless.js[7103]: WIRELESS.JS: trying... Dec 18 15:25:01 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:01 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:01 sdp sudo[7472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:01 sdp sudo[7472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:01 sdp sudo[7472]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:01 sdp sudo[7475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:01 sdp sudo[7475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:01 sdp sudo[7475]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:02 sdp volumio[6599]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=4c0baf5d9d650bed27f83007f7898fdd" http://updates.volumio.org/downloader-v1/track-device Dec 18 15:25:02 sdp volumio[6599]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 18 15:25:02 sdp volumio[6599]: Dload Upload Total Spent Left Speed Dec 18 15:25:02 sdp volumio[6599]: [132B blob data] Dec 18 15:25:02 sdp volumio[6599]: retrying in 5 seconds, trial 2 Dec 18 15:25:02 sdp volumio[6599]: info: Volumio Calling Home Dec 18 15:25:02 sdp wireless.js[7103]: WIRELESS.JS: trying... Dec 18 15:25:02 sdp sudo[7482]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:02 sdp sudo[7482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:02 sdp sudo[7482]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:02 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:02 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:02 sdp volumio[6599]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 18 15:25:02 sdp volumio[6599]: info: Saving new wireless network Dec 18 15:25:02 sdp sudo[7485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 18 15:25:02 sdp sudo[7485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:02 sdp sudo[7485]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:02 sdp volumio[6599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 18 15:25:02 sdp volumio[6599]: info: Discovery: Restarting Advertising due to device name change Dec 18 15:25:02 sdp sudo[7488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 18 15:25:02 sdp sudo[7488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:02 sdp systemd[1]: wireless.service: Killing process 7223 (wpa_supplicant) with signal SIGKILL. Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_run_escan: error (-512) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_scan: scan error (-512) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp systemd[1]: wireless.service: Deactivated successfully. Dec 18 15:25:02 sdp systemd[1]: Stopped wireless.service - Wireless Services. Dec 18 15:25:02 sdp systemd[1]: wireless.service: Consumed 1.048s CPU time. Dec 18 15:25:02 sdp systemd[1]: Starting wireless.service - Wireless Services... Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp wireless.js[7490]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 18 15:25:02 sdp wireless.js[7490]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 18 15:25:02 sdp wireless.js[7490]: WIRELESS.JS: Cleaning previous... Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:02 sdp sudo[7508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:02 sdp sudo[7508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:02 sdp sudo[7508]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:02 sdp sudo[7510]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 18 15:25:02 sdp sudo[7510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 15:25:02 sdp sudo[7510]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:02 sdp sudo[7513]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 18 15:25:02 sdp sudo[7513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:02 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp systemd-networkd[571]: wlan0: Link DOWN Dec 18 15:25:03 sdp sudo[7513]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:03 sdp wireless.js[7490]: WIRELESS.JS: Stopped aP Dec 18 15:25:03 sdp sudo[7521]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 18 15:25:03 sdp sudo[7521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:03 sdp systemd-networkd[571]: wlan0: Link UP Dec 18 15:25:03 sdp sudo[7521]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:03 sdp kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 18 15:25:03 sdp sudo[7523]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 18 15:25:03 sdp sudo[7523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:03 sdp sudo[7523]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:03 sdp sudo[7531]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 18 15:25:03 sdp sudo[7531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:03 sdp sudo[7531]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:03 sdp sudo[7533]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 18 15:25:03 sdp sudo[7533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp volumio[6599]: info: Discovery: A device disappeared from network Dec 18 15:25:03 sdp volumio[6599]: info: Discovery: Device sdp disappeared from network Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp sudo[7538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:03 sdp sudo[7538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:03 sdp sudo[7538]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp sudo[7541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:03 sdp sudo[7541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:03 sdp sudo[7541]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:03 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 18 15:25:04 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:04 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:04 sdp go-librespot[7546]: go-librespot daemon starting... Dec 18 15:25:04 sdp go-librespot[7547]: time="2025-12-18T15:25:04-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:04 sdp go-librespot[7547]: time="2025-12-18T15:25:04-05:00" level=debug msg="app state loaded" Dec 18 15:25:04 sdp go-librespot[7547]: time="2025-12-18T15:25:04-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:04 sdp go-librespot[7547]: time="2025-12-18T15:25:04-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:04 sdp go-librespot[7547]: time="2025-12-18T15:25:04-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:04 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:04 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:04 sdp sudo[7555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:04 sdp sudo[7555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:04 sdp sudo[7555]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:04 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:04 sdp sudo[7558]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:04 sdp sudo[7558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:04 sdp sudo[7558]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:05 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:05 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:05 sdp sudo[7561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:05 sdp sudo[7561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:05 sdp sudo[7561]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:05 sdp sudo[7564]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:05 sdp sudo[7564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:05 sdp sudo[7564]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:06 sdp kernel: ieee80211 phy0: brcmf_cfg80211_escan_handler: scan not ready, bsscfgidx=0 Dec 18 15:25:06 sdp kernel: ieee80211 phy0: brcmf_fweh_event_worker: event handler failed (69) Dec 18 15:25:06 sdp sudo[7567]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:06 sdp sudo[7567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:06 sdp sudo[7567]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:06 sdp sudo[7570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:06 sdp sudo[7570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:06 sdp sudo[7570]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:07 sdp volumio[6599]: info: Discovery: Started advertising with name: SDP Dec 18 15:25:07 sdp sudo[7574]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:07 sdp sudo[7574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:07 sdp sudo[7574]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:07 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 18 15:25:07 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:07 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:07 sdp go-librespot[7590]: go-librespot daemon starting... Dec 18 15:25:07 sdp go-librespot[7591]: time="2025-12-18T15:25:07-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:07 sdp go-librespot[7591]: time="2025-12-18T15:25:07-05:00" level=debug msg="app state loaded" Dec 18 15:25:07 sdp go-librespot[7591]: time="2025-12-18T15:25:07-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:07 sdp go-librespot[7591]: time="2025-12-18T15:25:07-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:07 sdp go-librespot[7591]: time="2025-12-18T15:25:07-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:07 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:07 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:07 sdp sudo[7599]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:07 sdp sudo[7599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:07 sdp sudo[7599]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:08 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:08 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:08 sdp volumio[6599]: info: Discovery: adding 755cf0cf-f86f-46d4-a67a-81deff368daa Dec 18 15:25:08 sdp volumio[6599]: info: Discovery: Found device SDP Dec 18 15:25:08 sdp volumio[6599]: info: CoreCommandRouter::volumioGetState Dec 18 15:25:08 sdp volumio[6599]: info: CorePlayQueue::getTrack 0 Dec 18 15:25:08 sdp sudo[7603]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:08 sdp sudo[7603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:08 sdp sudo[7603]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:08 sdp sudo[7606]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:08 sdp sudo[7606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:08 sdp sudo[7606]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:09 sdp sudo[7609]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:09 sdp sudo[7609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:09 sdp sudo[7609]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:09 sdp sudo[7612]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:09 sdp sudo[7612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:09 sdp sudo[7612]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:10 sdp sudo[7615]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:10 sdp sudo[7615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:10 sdp sudo[7615]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:10 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 18 15:25:10 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:10 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:10 sdp go-librespot[7617]: go-librespot daemon starting... Dec 18 15:25:10 sdp go-librespot[7618]: time="2025-12-18T15:25:10-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:10 sdp go-librespot[7618]: time="2025-12-18T15:25:10-05:00" level=debug msg="app state loaded" Dec 18 15:25:10 sdp go-librespot[7618]: time="2025-12-18T15:25:10-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:10 sdp go-librespot[7618]: time="2025-12-18T15:25:10-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:10 sdp go-librespot[7618]: time="2025-12-18T15:25:10-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:10 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:10 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:11 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:11 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:11 sdp sudo[7626]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:11 sdp sudo[7626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:11 sdp sudo[7626]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:12 sdp sudo[7629]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:12 sdp sudo[7629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:12 sdp sudo[7629]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:13 sdp sudo[7632]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:13 sdp sudo[7632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:13 sdp sudo[7632]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:14 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 18 15:25:14 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:14 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:14 sdp go-librespot[7634]: go-librespot daemon starting... Dec 18 15:25:14 sdp go-librespot[7635]: time="2025-12-18T15:25:14-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:14 sdp go-librespot[7635]: time="2025-12-18T15:25:14-05:00" level=debug msg="app state loaded" Dec 18 15:25:14 sdp go-librespot[7635]: time="2025-12-18T15:25:14-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:14 sdp go-librespot[7635]: time="2025-12-18T15:25:14-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:14 sdp go-librespot[7635]: time="2025-12-18T15:25:14-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:14 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:14 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:14 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:14 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:14 sdp sudo[7643]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:14 sdp sudo[7643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:14 sdp sudo[7643]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:15 sdp sudo[7646]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:15 sdp sudo[7646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:15 sdp sudo[7646]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:16 sdp sudo[7649]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:16 sdp sudo[7649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:16 sdp sudo[7649]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:17 sdp nmbd[3751]: [2025/12/18 15:25:17.282075, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 18 15:25:17 sdp nmbd[3751]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Dec 18 15:25:17 sdp nmbd[3751]: [2025/12/18 15:25:17.282205, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 18 15:25:17 sdp nmbd[3751]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Dec 18 15:25:17 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:17 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:17 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 18 15:25:17 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:17 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:17 sdp go-librespot[7652]: go-librespot daemon starting... Dec 18 15:25:17 sdp go-librespot[7653]: time="2025-12-18T15:25:17-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:17 sdp go-librespot[7653]: time="2025-12-18T15:25:17-05:00" level=debug msg="app state loaded" Dec 18 15:25:17 sdp go-librespot[7653]: time="2025-12-18T15:25:17-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:17 sdp go-librespot[7653]: time="2025-12-18T15:25:17-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:17 sdp go-librespot[7653]: time="2025-12-18T15:25:17-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:17 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:17 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:17 sdp sudo[7661]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:17 sdp sudo[7661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:17 sdp sudo[7661]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:18 sdp sudo[7678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:18 sdp sudo[7678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:18 sdp sudo[7678]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:19 sdp sudo[7681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:19 sdp sudo[7681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:19 sdp sudo[7681]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:20 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:20 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:20 sdp sudo[7684]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:20 sdp sudo[7684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:20 sdp sudo[7684]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:20 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 18 15:25:20 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:20 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:20 sdp go-librespot[7686]: go-librespot daemon starting... Dec 18 15:25:20 sdp go-librespot[7687]: time="2025-12-18T15:25:20-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:20 sdp go-librespot[7687]: time="2025-12-18T15:25:20-05:00" level=debug msg="app state loaded" Dec 18 15:25:20 sdp go-librespot[7687]: time="2025-12-18T15:25:20-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:20 sdp go-librespot[7687]: time="2025-12-18T15:25:20-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:20 sdp go-librespot[7687]: time="2025-12-18T15:25:20-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:20 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:20 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:21 sdp sudo[7695]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:21 sdp sudo[7695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:21 sdp sudo[7695]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:22 sdp sudo[7698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:22 sdp sudo[7698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:22 sdp sudo[7698]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:23 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:23 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:23 sdp sudo[7701]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:23 sdp sudo[7701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:23 sdp sudo[7701]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:23 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 18 15:25:23 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:23 sdp go-librespot[7703]: go-librespot daemon starting... Dec 18 15:25:23 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:23 sdp go-librespot[7704]: time="2025-12-18T15:25:23-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:23 sdp go-librespot[7704]: time="2025-12-18T15:25:23-05:00" level=debug msg="app state loaded" Dec 18 15:25:23 sdp go-librespot[7704]: time="2025-12-18T15:25:23-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:23 sdp go-librespot[7704]: time="2025-12-18T15:25:23-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:23 sdp go-librespot[7704]: time="2025-12-18T15:25:23-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:23 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:23 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:24 sdp sudo[7714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:24 sdp sudo[7714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:24 sdp sudo[7714]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:25 sdp sudo[7717]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:25 sdp sudo[7717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:25 sdp sudo[7717]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:26 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:26 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:26 sdp sudo[7720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:26 sdp sudo[7720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:26 sdp sudo[7720]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:27 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 18 15:25:27 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:27 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:27 sdp go-librespot[7722]: go-librespot daemon starting... Dec 18 15:25:27 sdp go-librespot[7723]: time="2025-12-18T15:25:27-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:27 sdp go-librespot[7723]: time="2025-12-18T15:25:27-05:00" level=debug msg="app state loaded" Dec 18 15:25:27 sdp go-librespot[7723]: time="2025-12-18T15:25:27-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:27 sdp go-librespot[7723]: time="2025-12-18T15:25:27-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:27 sdp go-librespot[7723]: time="2025-12-18T15:25:27-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:27 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:27 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:27 sdp sudo[7731]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:27 sdp sudo[7731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:27 sdp sudo[7731]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:28 sdp sudo[7748]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:28 sdp sudo[7748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:28 sdp sudo[7748]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:29 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:29 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:29 sdp sudo[7751]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:29 sdp sudo[7751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:29 sdp sudo[7751]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:30 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 18 15:25:30 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:30 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:30 sdp go-librespot[7753]: go-librespot daemon starting... Dec 18 15:25:30 sdp go-librespot[7754]: time="2025-12-18T15:25:30-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:30 sdp go-librespot[7754]: time="2025-12-18T15:25:30-05:00" level=debug msg="app state loaded" Dec 18 15:25:30 sdp go-librespot[7754]: time="2025-12-18T15:25:30-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:30 sdp go-librespot[7754]: time="2025-12-18T15:25:30-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:30 sdp go-librespot[7754]: time="2025-12-18T15:25:30-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:30 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:30 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:30 sdp sudo[7761]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:30 sdp sudo[7761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:30 sdp sudo[7761]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:31 sdp sudo[7764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:31 sdp sudo[7764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:31 sdp sudo[7764]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:32 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:32 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:32 sdp sudo[7767]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:32 sdp sudo[7767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:32 sdp sudo[7767]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:33 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 18 15:25:33 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:33 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:33 sdp go-librespot[7769]: go-librespot daemon starting... Dec 18 15:25:33 sdp go-librespot[7770]: time="2025-12-18T15:25:33-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:33 sdp go-librespot[7770]: time="2025-12-18T15:25:33-05:00" level=debug msg="app state loaded" Dec 18 15:25:33 sdp go-librespot[7770]: time="2025-12-18T15:25:33-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:33 sdp go-librespot[7770]: time="2025-12-18T15:25:33-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:33 sdp go-librespot[7770]: time="2025-12-18T15:25:33-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:33 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:33 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:35 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:35 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:36 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 18 15:25:36 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:36 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:36 sdp go-librespot[7776]: go-librespot daemon starting... Dec 18 15:25:36 sdp go-librespot[7777]: time="2025-12-18T15:25:36-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:36 sdp go-librespot[7777]: time="2025-12-18T15:25:36-05:00" level=debug msg="app state loaded" Dec 18 15:25:36 sdp go-librespot[7777]: time="2025-12-18T15:25:36-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:36 sdp go-librespot[7777]: time="2025-12-18T15:25:36-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:36 sdp go-librespot[7777]: time="2025-12-18T15:25:36-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:36 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:36 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:38 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:38 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:40 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 18 15:25:40 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:40 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:40 sdp go-librespot[7799]: go-librespot daemon starting... Dec 18 15:25:40 sdp go-librespot[7800]: time="2025-12-18T15:25:40-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:40 sdp go-librespot[7800]: time="2025-12-18T15:25:40-05:00" level=debug msg="app state loaded" Dec 18 15:25:40 sdp go-librespot[7800]: time="2025-12-18T15:25:40-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:40 sdp go-librespot[7800]: time="2025-12-18T15:25:40-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:40 sdp go-librespot[7800]: time="2025-12-18T15:25:40-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:40 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:40 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:40 sdp volumio[6599]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 18 15:25:40 sdp volumio[6599]: info: Saving new wireless network Dec 18 15:25:40 sdp sudo[7807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 18 15:25:40 sdp sudo[7807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:40 sdp sudo[7807]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:40 sdp volumio[6599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 18 15:25:40 sdp volumio[6599]: info: Discovery: Restarting Advertising due to device name change Dec 18 15:25:40 sdp sudo[7810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 18 15:25:40 sdp sudo[7810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:40 sdp systemd[1]: wireless.service: Killing process 7530 (sh) with signal SIGKILL. Dec 18 15:25:40 sdp systemd[1]: wireless.service: Killing process 7533 (sudo) with signal SIGKILL. Dec 18 15:25:40 sdp systemd[1]: wireless.service: Killing process 7534 (grep) with signal SIGKILL. Dec 18 15:25:40 sdp systemd[1]: wireless.service: Killing process 7535 (cut) with signal SIGKILL. Dec 18 15:25:40 sdp systemd[1]: wireless.service: Killing process 7536 (iw) with signal SIGKILL. Dec 18 15:25:40 sdp systemd[1]: wireless.service: Deactivated successfully. Dec 18 15:25:40 sdp systemd[1]: Stopped wireless.service - Wireless Services. Dec 18 15:25:40 sdp systemd[1]: Starting wireless.service - Wireless Services... Dec 18 15:25:41 sdp wireless.js[7812]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 18 15:25:41 sdp wireless.js[7812]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 18 15:25:41 sdp wireless.js[7812]: WIRELESS.JS: Cleaning previous... Dec 18 15:25:41 sdp sudo[7830]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 18 15:25:41 sdp sudo[7830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 15:25:41 sdp sudo[7830]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:41 sdp sudo[7832]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 18 15:25:41 sdp sudo[7832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 15:25:41 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:41 sdp systemd-networkd[571]: wlan0: Link DOWN Dec 18 15:25:41 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:41 sdp sudo[7832]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:41 sdp wireless.js[7812]: WIRELESS.JS: Stopped aP Dec 18 15:25:41 sdp sudo[7840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 18 15:25:41 sdp sudo[7840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:41 sdp systemd-networkd[571]: wlan0: Link UP Dec 18 15:25:41 sdp kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 18 15:25:41 sdp sudo[7840]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:41 sdp sudo[7842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 18 15:25:41 sdp sudo[7842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:41 sdp sudo[7842]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:41 sdp sudo[7850]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 18 15:25:41 sdp sudo[7850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:41 sdp sudo[7850]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:41 sdp sudo[7852]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 18 15:25:41 sdp sudo[7852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:41 sdp volumio[6599]: info: Discovery: A device disappeared from network Dec 18 15:25:41 sdp volumio[6599]: info: Discovery: Device sdp disappeared from network Dec 18 15:25:41 sdp sudo[7857]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:41 sdp sudo[7857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:41 sdp sudo[7857]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:42 sdp sudo[7860]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:42 sdp sudo[7860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:42 sdp sudo[7860]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:43 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 18 15:25:43 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:43 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:43 sdp go-librespot[7862]: go-librespot daemon starting... Dec 18 15:25:43 sdp go-librespot[7863]: time="2025-12-18T15:25:43-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:43 sdp go-librespot[7863]: time="2025-12-18T15:25:43-05:00" level=debug msg="app state loaded" Dec 18 15:25:43 sdp go-librespot[7863]: time="2025-12-18T15:25:43-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:43 sdp go-librespot[7863]: time="2025-12-18T15:25:43-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:43 sdp go-librespot[7863]: time="2025-12-18T15:25:43-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:43 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:43 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:43 sdp sudo[7870]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:43 sdp sudo[7870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:43 sdp sudo[7870]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:44 sdp sudo[7852]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:44 sdp wireless.js[7812]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US Dec 18 15:25:44 sdp sudo[7877]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 18 15:25:44 sdp sudo[7877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:44 sdp sudo[7877]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:44 sdp sudo[7879]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 18 15:25:44 sdp sudo[7879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:44 sdp sudo[7879]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:44 sdp wireless.js[7812]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 18 15:25:44 sdp wireless.js[7812]: WIRELESS.JS: Start wireless flow Dec 18 15:25:44 sdp wireless.js[7812]: WIRELESS.JS: Stopped hotspot (if there).. Dec 18 15:25:44 sdp sudo[7887]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 18 15:25:44 sdp sudo[7887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 15:25:44 sdp sudo[7887]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:44 sdp sudo[7889]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 18 15:25:44 sdp sudo[7889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 15:25:44 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:45 sdp systemd-networkd[571]: wlan0: Link DOWN Dec 18 15:25:45 sdp sudo[7889]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:45 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:45 sdp sudo[7892]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:45 sdp sudo[7892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:45 sdp sudo[7892]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:45 sdp wireless.js[7812]: WIRELESS.JS: DHCP IP fallback Dec 18 15:25:45 sdp wireless.js[7812]: WIRELESS.JS: Start ap Dec 18 15:25:45 sdp wpa_supplicant[7897]: Successfully initialized wpa_supplicant Dec 18 15:25:45 sdp sudo[7898]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 18 15:25:45 sdp sudo[7898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 15:25:45 sdp dhcpcd[1055]: ps_ctl_dispatch: cannot handle another client Dec 18 15:25:45 sdp dhcpcd[1055]: control_free: No such file or directory Dec 18 15:25:45 sdp sudo[7898]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:45 sdp kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 18 15:25:45 sdp systemd-networkd[571]: wlan0: Link UP Dec 18 15:25:45 sdp wpa_supplicant[7897]: nl80211: kernel reports: Registration to specific type not supported Dec 18 15:25:45 sdp volumio[6599]: info: Discovery: Started advertising with name: SDP Dec 18 15:25:45 sdp volumio[6599]: compat.c: read() failed: Resource temporarily unavailable Dec 18 15:25:45 sdp volumio[6599]: info: Discovery: mDNS temporarily unavailable during network transition Dec 18 15:25:45 sdp sudo[7905]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:45 sdp sudo[7905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:45 sdp sudo[7905]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:46 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:46 sdp sudo[7909]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:46 sdp sudo[7909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:46 sdp sudo[7909]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:46 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Dec 18 15:25:46 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:46 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:46 sdp go-librespot[7911]: go-librespot daemon starting... Dec 18 15:25:46 sdp go-librespot[7912]: time="2025-12-18T15:25:46-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:46 sdp go-librespot[7912]: time="2025-12-18T15:25:46-05:00" level=debug msg="app state loaded" Dec 18 15:25:46 sdp go-librespot[7912]: time="2025-12-18T15:25:46-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:46 sdp go-librespot[7912]: time="2025-12-18T15:25:46-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:46 sdp go-librespot[7912]: time="2025-12-18T15:25:46-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:46 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:46 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:46 sdp sudo[7919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:46 sdp sudo[7919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:46 sdp sudo[7919]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:47 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:47 sdp sudo[7923]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:47 sdp sudo[7923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:47 sdp sudo[7923]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:47 sdp sudo[7940]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:47 sdp sudo[7940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:47 sdp sudo[7940]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:48 sdp wpa_supplicant[7902]: wlan0: Trying to associate with f6:26:79:05:86:d3 (SSID='JJLaptop' freq=2412 MHz) Dec 18 15:25:48 sdp wpa_supplicant[7902]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 18 15:25:48 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:48 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:48 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:48 sdp sudo[7944]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:48 sdp sudo[7944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:48 sdp sudo[7944]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:48 sdp systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 18 15:25:48 sdp systemd[1]: Started getty@tty4.service - Getty on tty4. Dec 18 15:25:48 sdp sudo[7948]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:48 sdp sudo[7948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:48 sdp sudo[7948]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:49 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:49 sdp sudo[7952]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:49 sdp sudo[7952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:49 sdp sudo[7952]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:49 sdp sudo[7955]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:49 sdp sudo[7955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:49 sdp sudo[7955]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:49 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Dec 18 15:25:49 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:49 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:49 sdp go-librespot[7957]: go-librespot daemon starting... Dec 18 15:25:49 sdp go-librespot[7958]: time="2025-12-18T15:25:49-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:49 sdp go-librespot[7958]: time="2025-12-18T15:25:49-05:00" level=debug msg="app state loaded" Dec 18 15:25:49 sdp go-librespot[7958]: time="2025-12-18T15:25:49-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:49 sdp go-librespot[7958]: time="2025-12-18T15:25:49-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:49 sdp go-librespot[7958]: time="2025-12-18T15:25:49-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:49 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:49 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:50 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:50 sdp sudo[7966]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:50 sdp sudo[7966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:50 sdp sudo[7966]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:50 sdp wpa_supplicant[7902]: wlan0: Trying to associate with f6:26:79:05:86:d3 (SSID='JJLaptop' freq=2412 MHz) Dec 18 15:25:50 sdp sudo[7969]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:50 sdp sudo[7969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:50 sdp sudo[7969]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:50 sdp wpa_supplicant[7902]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 18 15:25:50 sdp wpa_supplicant[7902]: BSSID f6:26:79:05:86:d3 ignore list count incremented to 2, ignoring for 10 seconds Dec 18 15:25:51 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:51 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:51 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:51 sdp sudo[7973]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:51 sdp sudo[7973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:51 sdp sudo[7973]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:51 sdp sudo[7976]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:51 sdp sudo[7976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:51 sdp sudo[7976]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:52 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:52 sdp sudo[7980]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:52 sdp sudo[7980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:52 sdp sudo[7980]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:52 sdp sudo[7983]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:52 sdp sudo[7983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:52 sdp sudo[7983]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:53 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:53 sdp sudo[7987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:53 sdp sudo[7987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:53 sdp sudo[7987]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:53 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Dec 18 15:25:53 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:53 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:53 sdp go-librespot[7989]: go-librespot daemon starting... Dec 18 15:25:53 sdp go-librespot[7990]: time="2025-12-18T15:25:53-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:53 sdp go-librespot[7990]: time="2025-12-18T15:25:53-05:00" level=debug msg="app state loaded" Dec 18 15:25:53 sdp go-librespot[7990]: time="2025-12-18T15:25:53-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:53 sdp go-librespot[7990]: time="2025-12-18T15:25:53-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:53 sdp go-librespot[7990]: time="2025-12-18T15:25:53-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:53 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:53 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:53 sdp sudo[7997]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:53 sdp sudo[7997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:53 sdp sudo[7997]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:54 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:54 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:54 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:54 sdp sudo[8001]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:54 sdp sudo[8001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:54 sdp sudo[8001]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:54 sdp wpa_supplicant[7902]: wlan0: Trying to associate with f6:26:79:05:86:d3 (SSID='JJLaptop' freq=2412 MHz) Dec 18 15:25:54 sdp wpa_supplicant[7902]: wlan0: Associated with f6:26:79:05:86:d3 Dec 18 15:25:54 sdp wpa_supplicant[7902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 18 15:25:54 sdp systemd-networkd[571]: wlan0: Connected WiFi access point: JJLaptop (f6:26:79:05:86:d3) Dec 18 15:25:54 sdp wpa_supplicant[7902]: wlan0: WPA: Key negotiation completed with f6:26:79:05:86:d3 [PTK=CCMP GTK=CCMP] Dec 18 15:25:54 sdp wpa_supplicant[7902]: wlan0: CTRL-EVENT-CONNECTED - Connection to f6:26:79:05:86:d3 completed [id=0 id_str=] Dec 18 15:25:54 sdp dhcpcd[1055]: wlan0: carrier acquired Dec 18 15:25:54 sdp dhcpcd[1055]: wlan0: connected to Access Point: JJLaptop Dec 18 15:25:54 sdp systemd-networkd[571]: wlan0: Gained carrier Dec 18 15:25:54 sdp dhcpcd[1055]: wlan0: IAID 67:7e:89:12 Dec 18 15:25:54 sdp dhcpcd[1055]: wlan0: soliciting a DHCP lease Dec 18 15:25:54 sdp dhcpcd[1055]: wlan0: soliciting an IPv6 router Dec 18 15:25:54 sdp sudo[8006]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:54 sdp sudo[8006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:54 sdp sudo[8006]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:55 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:55 sdp sudo[8010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:55 sdp sudo[8010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:55 sdp sudo[8010]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:55 sdp volumio[6599]: info: Discovery: Started advertising with name: SDP Dec 18 15:25:55 sdp sudo[8013]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:55 sdp sudo[8013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:55 sdp sudo[8013]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:56 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:56 sdp sudo[8018]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:56 sdp sudo[8018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:56 sdp sudo[8018]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:56 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 18 15:25:56 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:56 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:56 sdp go-librespot[8020]: go-librespot daemon starting... Dec 18 15:25:56 sdp go-librespot[8021]: time="2025-12-18T15:25:56-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:56 sdp go-librespot[8021]: time="2025-12-18T15:25:56-05:00" level=debug msg="app state loaded" Dec 18 15:25:56 sdp go-librespot[8021]: time="2025-12-18T15:25:56-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:56 sdp go-librespot[8021]: time="2025-12-18T15:25:56-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:56 sdp go-librespot[8021]: time="2025-12-18T15:25:56-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:56 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:56 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:56 sdp volumio[6599]: info: Discovery: adding 755cf0cf-f86f-46d4-a67a-81deff368daa Dec 18 15:25:56 sdp volumio[6599]: info: Discovery: Found device SDP Dec 18 15:25:56 sdp volumio[6599]: info: CoreCommandRouter::volumioGetState Dec 18 15:25:56 sdp volumio[6599]: info: CorePlayQueue::getTrack 0 Dec 18 15:25:56 sdp sudo[8030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:56 sdp sudo[8030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:56 sdp sudo[8030]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:57 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:25:57 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:25:57 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:57 sdp sudo[8034]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:57 sdp sudo[8034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:57 sdp sudo[8034]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:57 sdp sudo[8052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:57 sdp sudo[8052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:57 sdp sudo[8052]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:58 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:58 sdp sudo[8056]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:58 sdp sudo[8056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:58 sdp sudo[8056]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:58 sdp sudo[8059]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:58 sdp sudo[8059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:58 sdp sudo[8059]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:59 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:25:59 sdp sudo[8063]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:25:59 sdp sudo[8063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:59 sdp sudo[8063]: pam_unix(sudo:session): session closed for user root Dec 18 15:25:59 sdp dhcpcd[1055]: wlan0: probing for an IPv4LL address Dec 18 15:25:59 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 18 15:25:59 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:59 sdp go-librespot[8066]: go-librespot daemon starting... Dec 18 15:25:59 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:25:59 sdp go-librespot[8067]: time="2025-12-18T15:25:59-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:25:59 sdp go-librespot[8067]: time="2025-12-18T15:25:59-05:00" level=debug msg="app state loaded" Dec 18 15:25:59 sdp go-librespot[8067]: time="2025-12-18T15:25:59-05:00" level=debug msg="stored credentials not found" Dec 18 15:25:59 sdp go-librespot[8067]: time="2025-12-18T15:25:59-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:25:59 sdp go-librespot[8067]: time="2025-12-18T15:25:59-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:25:59 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:25:59 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:25:59 sdp sudo[8074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:25:59 sdp sudo[8074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:25:59 sdp sudo[8074]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:00 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:26:00 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:26:00 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:26:00 sdp sudo[8078]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:26:00 sdp sudo[8078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:00 sdp sudo[8078]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:00 sdp login[7946]: pam_unix(login:session): session opened for user volumio(uid=1000) by LOGIN(uid=0) Dec 18 15:26:00 sdp systemd-logind[968]: New session 3 of user volumio. Dec 18 15:26:00 sdp systemd[1]: Started session-3.scope - Session 3 of User volumio. Dec 18 15:26:00 sdp sudo[8093]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:26:00 sdp sudo[8093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:00 sdp sudo[8093]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:01 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:26:01 sdp sudo[8097]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:26:01 sdp sudo[8097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:01 sdp sudo[8097]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:01 sdp sudo[8100]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:26:01 sdp sudo[8100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:01 sdp sudo[8100]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:02 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:26:02 sdp sudo[8104]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:26:02 sdp sudo[8104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:02 sdp sudo[8104]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:02 sdp sudo[8107]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:26:02 sdp sudo[8107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:02 sdp sudo[8107]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:02 sdp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Dec 18 15:26:02 sdp systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:26:02 sdp go-librespot[8109]: go-librespot daemon starting... Dec 18 15:26:02 sdp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 18 15:26:02 sdp go-librespot[8110]: time="2025-12-18T15:26:02-05:00" level=info msg="running go-librespot 0.4.0" Dec 18 15:26:02 sdp go-librespot[8110]: time="2025-12-18T15:26:02-05:00" level=debug msg="app state loaded" Dec 18 15:26:02 sdp go-librespot[8110]: time="2025-12-18T15:26:02-05:00" level=debug msg="stored credentials not found" Dec 18 15:26:02 sdp go-librespot[8110]: time="2025-12-18T15:26:02-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 18 15:26:02 sdp go-librespot[8110]: time="2025-12-18T15:26:02-05:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 18 15:26:02 sdp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 18 15:26:02 sdp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 18 15:26:03 sdp volumio[6599]: info: Initializing connection to go-librespot Websocket Dec 18 15:26:03 sdp volumio[6599]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 18 15:26:03 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:26:03 sdp sudo[8118]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:26:03 sdp sudo[8118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:03 sdp sudo[8118]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:03 sdp sudo[8132]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:26:03 sdp sudo[8132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:03 sdp sudo[8132]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:04 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:26:04 sdp sudo[8136]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:26:04 sdp sudo[8136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:04 sdp sudo[8136]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:04 sdp sudo[8142]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 15:26:04 sdp sudo[8142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:04 sdp sudo[8142]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:05 sdp dhcpcd[1055]: wlan0: offered 192.168.137.77 from 192.168.137.1 Dec 18 15:26:05 sdp wireless.js[7812]: WIRELESS.JS: trying... Dec 18 15:26:05 sdp sudo[8146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 18 15:26:05 sdp dhcpcd[1055]: wlan0: probing address 192.168.137.77/24 Dec 18 15:26:05 sdp sudo[8146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 15:26:05 sdp sudo[8146]: pam_unix(sudo:session): session closed for user root Dec 18 15:26:05 sdp dhcpcd[1055]: wlan0: using IPv4LL address 169.254.170.77 Dec 18 15:26:05 sdp avahi-daemon[940]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.170.77. Dec 18 15:26:05 sdp avahi-daemon[940]: New relevant interface wlan0.IPv4 for mDNS. Dec 18 15:26:05 sdp avahi-daemon[940]: Registering new address record for 169.254.170.77 on wlan0.IPv4. Dec 18 15:26:05 sdp systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 18 15:26:05 sdp dhcpcd[1055]: wlan0: adding route to 169.254.0.0/16 Dec 18 15:26:05 sdp systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 18 15:26:05 sdp dhcpcd[1055]: wlan0: adding default route Dec 18 15:26:05 sdp systemd[1]: welcome.service: Deactivated successfully. Dec 18 15:26:05 sdp systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 18 15:26:05 sdp systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 18 15:26:05 sdp systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 18 15:26:05 sdp welcome[8150]: Resolved ip:[1] 169.254.170.77 Dec 18 15:26:05 sdp systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 18 15:26:05 sdp systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 18 15:26:05 sdp volumio[6599]: compat.c: read() failed: Resource temporarily unavailable Dec 18 15:26:05 sdp volumio[6599]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 15:26:05 sdp volumio[6599]: Error: dns service error: unknown Dec 18 15:26:05 sdp volumio[6599]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 18 15:26:05 sdp volumio[6599]: errorCode: -65537 Dec 18 15:26:05 sdp volumio[6599]: } Dec 18 15:26:05 sdp volumio[6599]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 15:26:05 sdp sudo[8178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-18 15:25' Dec 18 15:26:05 sdp sudo[8178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"