Dec 05 21:43:00 huis-speakers bash[1144]: setdatetime-helper: all HTTPS Date fallbacks failed
Dec 05 21:43:00 huis-speakers systemd[1]: setdatetime-helper.service: Deactivated successfully.
Dec 05 21:43:00 huis-speakers systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Dec 05 21:43:00 huis-speakers systemd[1]: setdatetime-helper.service: Consumed 2.274s CPU time.
Dec 05 21:43:00 huis-speakers volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Dec 05 21:43:00 huis-speakers sudo[1264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Dec 05 21:43:00 huis-speakers sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:00 huis-speakers systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 05 21:43:00 huis-speakers systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:43:00 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:00 huis-speakers go-librespot[1271]: go-librespot daemon starting...
Dec 05 21:43:00 huis-speakers sudo[1264]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:00 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:01 huis-speakers volumio[915]: info: No need to fix Spotify hosts
Dec 05 21:43:01 huis-speakers volumio[915]: info: Starting Shairport Sync
Dec 05 21:43:01 huis-speakers volumio[915]: info: Starting Shairport Sync
Dec 05 21:43:01 huis-speakers volumio[915]: info: Starting Shairport Sync
Dec 05 21:43:01 huis-speakers sudo[1285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 05 21:43:01 huis-speakers sudo[1285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:43:01 huis-speakers volumio[1029]: Starting albumart workers
Dec 05 21:43:01 huis-speakers sudo[1287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 05 21:43:01 huis-speakers sudo[1290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 05 21:43:01 huis-speakers sudo[1290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:43:01 huis-speakers sudo[1287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:43:01 huis-speakers go-librespot[1272]: time="2025-12-05T21:43:01+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:01 huis-speakers systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 05 21:43:01 huis-speakers go-librespot[1272]: time="2025-12-05T21:43:01+01:00" level=debug msg="app state loaded"
Dec 05 21:43:01 huis-speakers go-librespot[1272]: time="2025-12-05T21:43:01+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:01 huis-speakers systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 05 21:43:01 huis-speakers systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 05 21:43:01 huis-speakers systemd[1]: shairport-sync.service: Consumed 2.641s CPU time.
Dec 05 21:43:01 huis-speakers go-librespot[1272]: time="2025-12-05T21:43:01+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:01 huis-speakers go-librespot[1272]: time="2025-12-05T21:43:01+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:01 huis-speakers systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 05 21:43:01 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:01 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:01 huis-speakers sudo[1285]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:02 huis-speakers systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 05 21:43:02 huis-speakers systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 05 21:43:02 huis-speakers systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 05 21:43:02 huis-speakers systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 05 21:43:02 huis-speakers systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 05 21:43:02 huis-speakers sudo[1290]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:02 huis-speakers systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 05 21:43:02 huis-speakers systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 05 21:43:02 huis-speakers systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 05 21:43:02 huis-speakers sudo[1287]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:02 huis-speakers volumio[915]: info: Shairport-Sync Started
Dec 05 21:43:02 huis-speakers volumio[915]: Error adding Membership: Error: addMembership EINVAL
Dec 05 21:43:03 huis-speakers volumio[915]: info: Shairport-Sync Started
Dec 05 21:43:03 huis-speakers volumio[915]: info: Shairport-Sync Started
Dec 05 21:43:03 huis-speakers volumio[915]: info: CoreCommandRouter::volumioGetState
Dec 05 21:43:03 huis-speakers volumio[915]: info: CorePlayQueue::getTrack 0
Dec 05 21:43:04 huis-speakers volumio[915]: info: go-librespot daemon successfully initialized
Dec 05 21:43:05 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Dec 05 21:43:05 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:05 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:05 huis-speakers go-librespot[1310]: go-librespot daemon starting...
Dec 05 21:43:05 huis-speakers go-librespot[1311]: time="2025-12-05T21:43:05+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:05 huis-speakers go-librespot[1311]: time="2025-12-05T21:43:05+01:00" level=debug msg="app state loaded"
Dec 05 21:43:05 huis-speakers go-librespot[1311]: time="2025-12-05T21:43:05+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:05 huis-speakers go-librespot[1311]: time="2025-12-05T21:43:05+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:05 huis-speakers go-librespot[1311]: time="2025-12-05T21:43:05+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:05 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:05 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:05 huis-speakers mpd[1186]: 2025-12-05T21:43:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 05 21:43:05 huis-speakers systemd[1]: Started mpd.service - Music Player Daemon.
Dec 05 21:43:05 huis-speakers sudo[1165]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:05 huis-speakers sudo[1133]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:05 huis-speakers volumio[915]: info: Completed starting Core Plugins
Dec 05 21:43:05 huis-speakers volumio[915]: info: -------------------------------------------
Dec 05 21:43:05 huis-speakers volumio[915]: info: ----- MyVolumio plugins startup ----
Dec 05 21:43:05 huis-speakers volumio[915]: info: -------------------------------------------
Dec 05 21:43:05 huis-speakers volumio[915]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 05 21:43:05 huis-speakers volumio[1030]: Starting albumart workers
Dec 05 21:43:06 huis-speakers volumio[1031]: Starting albumart workers
Dec 05 21:43:06 huis-speakers volumio[915]: error: MPD error: The expression evaluated to a falsy value:
Dec 05 21:43:06 huis-speakers volumio[915]: assert.ok(self.idling)
Dec 05 21:43:06 huis-speakers volumio[915]: error: The expression evaluated to a falsy value:
Dec 05 21:43:06 huis-speakers volumio[915]: assert.ok(self.idling)
Dec 05 21:43:06 huis-speakers volumio[915]: error: updateQueue error: null
Dec 05 21:43:06 huis-speakers volumio[915]: info: MPD running with PID1186
Dec 05 21:43:06 huis-speakers volumio[915]: ,establishing connection
Dec 05 21:43:06 huis-speakers volumio[915]: error: updateQueue error: null
Dec 05 21:43:07 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:07 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:08 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Dec 05 21:43:08 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:08 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:08 huis-speakers go-librespot[1327]: go-librespot daemon starting...
Dec 05 21:43:08 huis-speakers go-librespot[1330]: time="2025-12-05T21:43:08+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:08 huis-speakers go-librespot[1330]: time="2025-12-05T21:43:08+01:00" level=debug msg="app state loaded"
Dec 05 21:43:08 huis-speakers go-librespot[1330]: time="2025-12-05T21:43:08+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:08 huis-speakers go-librespot[1330]: time="2025-12-05T21:43:08+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:08 huis-speakers go-librespot[1330]: time="2025-12-05T21:43:08+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:08 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:08 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:08 huis-speakers sudo[1329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 05 21:43:08 huis-speakers sudo[1329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:43:08 huis-speakers sudo[1338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 05 21:43:08 huis-speakers sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:43:09 huis-speakers systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 05 21:43:09 huis-speakers mpd_monitor.sh[1341]: MPD Monitor Service: Starting MPD Monitor Service
Dec 05 21:43:09 huis-speakers systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 05 21:43:09 huis-speakers sudo[1329]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:09 huis-speakers systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 05 21:43:09 huis-speakers systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 05 21:43:09 huis-speakers volumio[915]: info: Successfully started MPD Monitor
Dec 05 21:43:09 huis-speakers systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 05 21:43:09 huis-speakers sudo[1338]: pam_unix(sudo:session): session closed for user root
Dec 05 21:43:09 huis-speakers volumio[915]: info: Successfully started MPD Monitor
Dec 05 21:43:09 huis-speakers mpd_monitor.sh[1343]: MPD Monitor Service: Starting MPD Monitor Service
Dec 05 21:43:09 huis-speakers ifplugd(eth0)[795]: Executing '/etc/ifplugd/ifplugd.action eth0 down'.
Dec 05 21:43:09 huis-speakers dhcpcd[1371]: dhcpcd not running
Dec 05 21:43:09 huis-speakers ifplugd(eth0)[795]: client: dhcpcd not running
Dec 05 21:43:09 huis-speakers kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Dec 05 21:43:09 huis-speakers volumio[915]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=530160d7f97c09b1b495d09fcd96279f" http://updates.volumio.org/downloader-v1/track-device
Dec 05 21:43:09 huis-speakers volumio[915]: % Total % Received % Xferd Average Speed Time Time Time Current
Dec 05 21:43:09 huis-speakers volumio[915]: Dload Upload Total Spent Left Speed
Dec 05 21:43:09 huis-speakers volumio[915]: [132B blob data]
Dec 05 21:43:09 huis-speakers volumio[915]: retrying in 5 seconds, trial 0
Dec 05 21:43:09 huis-speakers volumio[915]: info: Volumio Calling Home
Dec 05 21:43:09 huis-speakers ifplugd(eth0)[795]: Program executed successfully.
Dec 05 21:43:10 huis-speakers kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Dec 05 21:43:10 huis-speakers kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down
Dec 05 21:43:10 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:10 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:10 huis-speakers volumio[915]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Dec 05 21:43:11 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Dec 05 21:43:11 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:11 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:11 huis-speakers go-librespot[1399]: go-librespot daemon starting...
Dec 05 21:43:11 huis-speakers go-librespot[1400]: time="2025-12-05T21:43:11+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:11 huis-speakers go-librespot[1400]: time="2025-12-05T21:43:11+01:00" level=debug msg="app state loaded"
Dec 05 21:43:11 huis-speakers go-librespot[1400]: time="2025-12-05T21:43:11+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:11 huis-speakers go-librespot[1400]: time="2025-12-05T21:43:11+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:12 huis-speakers go-librespot[1400]: time="2025-12-05T21:43:12+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:12 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:12 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:13 huis-speakers dhcpcd[728]: eth0: carrier acquired
Dec 05 21:43:13 huis-speakers kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
Dec 05 21:43:13 huis-speakers dhcpcd[728]: eth0: IAID eb:fc:70:b4
Dec 05 21:43:13 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:13 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:13 huis-speakers dhcpcd[728]: eth0: soliciting an IPv6 router
Dec 05 21:43:14 huis-speakers ifplugd(eth0)[795]: Link beat detected.
Dec 05 21:43:14 huis-speakers ifplugd(eth0)[795]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Dec 05 21:43:14 huis-speakers ifplugd(eth0)[795]: client: command failed: No such device (-19)
Dec 05 21:43:14 huis-speakers dhcpcd[728]: ps_ctl_dispatch: cannot handle another client
Dec 05 21:43:14 huis-speakers ifplugd(eth0)[795]: client: sending commands to dhcpcd process
Dec 05 21:43:14 huis-speakers dhcpcd[728]: control_free: No such file or directory
Dec 05 21:43:14 huis-speakers dhcpcd[728]: eth0: soliciting a DHCP lease
Dec 05 21:43:14 huis-speakers ifplugd(eth0)[795]: Program executed successfully.
Dec 05 21:43:15 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Dec 05 21:43:15 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:15 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:15 huis-speakers go-librespot[1458]: go-librespot daemon starting...
Dec 05 21:43:15 huis-speakers go-librespot[1459]: time="2025-12-05T21:43:15+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:15 huis-speakers go-librespot[1459]: time="2025-12-05T21:43:15+01:00" level=debug msg="app state loaded"
Dec 05 21:43:15 huis-speakers go-librespot[1459]: time="2025-12-05T21:43:15+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:15 huis-speakers go-librespot[1459]: time="2025-12-05T21:43:15+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:15 huis-speakers go-librespot[1459]: time="2025-12-05T21:43:15+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:15 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:15 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:16 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:16 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:18 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Dec 05 21:43:18 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:18 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:18 huis-speakers go-librespot[1465]: go-librespot daemon starting...
Dec 05 21:43:18 huis-speakers go-librespot[1466]: time="2025-12-05T21:43:18+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:18 huis-speakers go-librespot[1466]: time="2025-12-05T21:43:18+01:00" level=debug msg="app state loaded"
Dec 05 21:43:18 huis-speakers go-librespot[1466]: time="2025-12-05T21:43:18+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:18 huis-speakers go-librespot[1466]: time="2025-12-05T21:43:18+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:18 huis-speakers go-librespot[1466]: time="2025-12-05T21:43:18+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:18 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:18 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:19 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:19 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:20 huis-speakers volumio[915]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=530160d7f97c09b1b495d09fcd96279f" http://updates.volumio.org/downloader-v1/track-device
Dec 05 21:43:20 huis-speakers volumio[915]: % Total % Received % Xferd Average Speed Time Time Time Current
Dec 05 21:43:20 huis-speakers volumio[915]: Dload Upload Total Spent Left Speed
Dec 05 21:43:20 huis-speakers volumio[915]: [132B blob data]
Dec 05 21:43:20 huis-speakers volumio[915]: retrying in 5 seconds, trial 1
Dec 05 21:43:20 huis-speakers volumio[915]: info: Volumio Calling Home
Dec 05 21:43:21 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Dec 05 21:43:21 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:21 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:21 huis-speakers go-librespot[1489]: go-librespot daemon starting...
Dec 05 21:43:21 huis-speakers go-librespot[1490]: time="2025-12-05T21:43:21+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:21 huis-speakers go-librespot[1490]: time="2025-12-05T21:43:21+01:00" level=debug msg="app state loaded"
Dec 05 21:43:21 huis-speakers go-librespot[1490]: time="2025-12-05T21:43:21+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:21 huis-speakers go-librespot[1490]: time="2025-12-05T21:43:21+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:21 huis-speakers go-librespot[1490]: time="2025-12-05T21:43:21+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:21 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:21 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:22 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:22 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:23 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 05 21:43:23 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:43:23 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 05 21:43:24 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Dec 05 21:43:24 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:24 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:24 huis-speakers go-librespot[1500]: go-librespot daemon starting...
Dec 05 21:43:25 huis-speakers go-librespot[1501]: time="2025-12-05T21:43:25+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:25 huis-speakers go-librespot[1501]: time="2025-12-05T21:43:25+01:00" level=debug msg="app state loaded"
Dec 05 21:43:25 huis-speakers go-librespot[1501]: time="2025-12-05T21:43:25+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:25 huis-speakers go-librespot[1501]: time="2025-12-05T21:43:25+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:25 huis-speakers go-librespot[1501]: time="2025-12-05T21:43:25+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:25 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:25 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:25 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:25 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:25 huis-speakers volumio[915]: error: MyVolumio Plugin failed to start in a timely fashion
Dec 05 21:43:25 huis-speakers volumio[915]: [Metrics] CommandRouter: 53s 253.44ms
Dec 05 21:43:25 huis-speakers volumio[915]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 05 21:43:25 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:43:25 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:43:25 huis-speakers volumio[915]: info: CoreCommandRouter::Close All Modals sent
Dec 05 21:43:25 huis-speakers volumio[915]: info: CoreCommandRouter::Close All Modals sent
Dec 05 21:43:26 huis-speakers dhcpcd[728]: eth0: offered 192.168.2.46 from 192.168.2.254 mijnmodem
Dec 05 21:43:26 huis-speakers dhcpcd[728]: eth0: probing address 192.168.2.46/24
Dec 05 21:43:26 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 05 21:43:26 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 05 21:43:27 huis-speakers volumio[915]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 05 21:43:28 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Dec 05 21:43:28 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:28 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:28 huis-speakers go-librespot[1513]: go-librespot daemon starting...
Dec 05 21:43:28 huis-speakers go-librespot[1514]: time="2025-12-05T21:43:28+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:28 huis-speakers go-librespot[1514]: time="2025-12-05T21:43:28+01:00" level=debug msg="app state loaded"
Dec 05 21:43:28 huis-speakers go-librespot[1514]: time="2025-12-05T21:43:28+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:28 huis-speakers go-librespot[1514]: time="2025-12-05T21:43:28+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:28 huis-speakers go-librespot[1514]: time="2025-12-05T21:43:28+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Dec 05 21:43:28 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:43:28 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:43:28 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:28 huis-speakers volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:43:30 huis-speakers volumio[915]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=530160d7f97c09b1b495d09fcd96279f" http://updates.volumio.org/downloader-v1/track-device
Dec 05 21:43:30 huis-speakers volumio[915]: curl: (6) Could not resolve host: updates.volumio.org
Dec 05 21:43:30 huis-speakers volumio[915]: retrying in 5 seconds, trial 2
Dec 05 21:43:30 huis-speakers volumio[915]: info: Volumio Calling Home
Dec 05 21:43:31 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Dec 05 21:43:31 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:31 huis-speakers dhcpcd[728]: eth0: leased 192.168.2.46 for 86400 seconds
Dec 05 21:43:31 huis-speakers avahi-daemon[574]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.46.
Dec 05 21:43:31 huis-speakers avahi-daemon[574]: New relevant interface eth0.IPv4 for mDNS.
Dec 05 21:43:31 huis-speakers avahi-daemon[574]: Registering new address record for 192.168.2.46 on eth0.IPv4.
Dec 05 21:43:31 huis-speakers dhcpcd[728]: eth0: adding route to 192.168.2.0/24
Dec 05 21:43:31 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:43:31 huis-speakers go-librespot[1541]: go-librespot daemon starting...
Dec 05 21:43:31 huis-speakers systemd[1]: welcome.service: Deactivated successfully.
Dec 05 21:43:31 huis-speakers systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 05 21:43:31 huis-speakers systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 05 21:43:31 huis-speakers go-librespot[1549]: time="2025-12-05T21:43:31+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:43:31 huis-speakers go-librespot[1549]: time="2025-12-05T21:43:31+01:00" level=debug msg="app state loaded"
Dec 05 21:43:31 huis-speakers go-librespot[1549]: time="2025-12-05T21:43:31+01:00" level=debug msg="stored credentials not found"
Dec 05 21:43:31 huis-speakers go-librespot[1549]: time="2025-12-05T21:43:31+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:43:31 huis-speakers dhcpcd[728]: eth0: adding default route via 192.168.2.254
Dec 05 21:43:31 huis-speakers systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 05 21:43:31 huis-speakers systemd[1]: Started nmbd.service - Samba NMB Daemon.
Dec 05 21:43:31 huis-speakers welcome[1567]: Resolved ip:[1] 192.168.2.46
Dec 05 21:43:31 huis-speakers systemd[1]: Starting winbind.service - Samba Winbind Daemon...
Dec 05 21:43:31 huis-speakers systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 05 21:43:31 huis-speakers volumio[915]: info: Initializing connection to go-librespot Websocket
Dec 05 21:43:31 huis-speakers systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 05 21:43:31 huis-speakers go-librespot[1549]: time="2025-12-05T21:43:31+01:00" level=debug msg="new websocket client"
Dec 05 21:43:32 huis-speakers volumio[915]: info: Connection to go-librespot Websocket established
Dec 05 21:43:32 huis-speakers volumio[915]: info: Discovery: this is already registered, 8b3cc7ca-28cc-4361-9787-3bd72769cc29
Dec 05 21:43:32 huis-speakers volumio[915]: info: Discovery: Found device huis speakers
Dec 05 21:43:32 huis-speakers volumio[915]: info: CoreCommandRouter::volumioGetState
Dec 05 21:43:32 huis-speakers volumio[915]: info: CorePlayQueue::getTrack 0
Dec 05 21:43:32 huis-speakers winbindd[1578]: [2025/12/05 21:43:32.675990, 0] ../../source3/winbindd/winbindd.c:1440(main)
Dec 05 21:43:32 huis-speakers winbindd[1578]: winbindd version 4.17.8-Raspbian started.
Dec 05 21:43:32 huis-speakers winbindd[1578]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Dec 05 21:43:32 huis-speakers winbindd[1578]: [2025/12/05 21:43:32.703540, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache)
Dec 05 21:43:32 huis-speakers winbindd[1578]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Dec 05 21:43:32 huis-speakers systemd[1]: Started winbind.service - Samba Winbind Daemon.
Dec 05 21:43:32 huis-speakers ntpd[801]: IO: Listen normally on 3 eth0 192.168.2.46:123
Dec 05 21:43:32 huis-speakers ntpd[801]: IO: new interface(s) found: waking up resolver
Dec 05 21:43:32 huis-speakers ntpd[801]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 05 21:43:32 huis-speakers ntpd[801]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Dec 05 21:43:32 huis-speakers ntpd[801]: DNS: Pool taking: 192.168.2.254
Dec 05 21:43:32 huis-speakers ntpd[801]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8
Dec 05 21:43:32 huis-speakers systemd[1]: Starting smbd.service - Samba SMB Daemon...
Dec 05 21:43:32 huis-speakers volumio[915]: info: BOOT COMPLETED
Dec 05 21:43:33 huis-speakers ntpd[801]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 05 21:43:33 huis-speakers ntpd[801]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Dec 05 21:43:33 huis-speakers ntpd[801]: DNS: Pool skipping: 192.168.2.254
Dec 05 21:43:33 huis-speakers ntpd[801]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8
Dec 05 21:43:33 huis-speakers smbd[1588]: [2025/12/05 21:43:33.830140, 0] ../../source3/smbd/server.c:1741(main)
Dec 05 21:43:33 huis-speakers smbd[1588]: smbd version 4.17.8-Raspbian started.
Dec 05 21:43:33 huis-speakers smbd[1588]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Dec 05 21:43:34 huis-speakers ntpd[801]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 05 21:43:34 huis-speakers ntpd[801]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Dec 05 21:43:34 huis-speakers ntpd[801]: DNS: Pool skipping: 192.168.2.254
Dec 05 21:43:34 huis-speakers ntpd[801]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
Dec 05 21:43:35 huis-speakers volumio[915]: info: Getting Spotify volume
Dec 05 21:43:35 huis-speakers ntpd[801]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 05 21:43:35 huis-speakers ntpd[801]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Dec 05 21:43:35 huis-speakers ntpd[801]: DNS: Pool skipping: 192.168.2.254
Dec 05 21:43:35 huis-speakers ntpd[801]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Dec 05 21:43:36 huis-speakers volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Dec 05 21:43:36 huis-speakers systemd[1]: Started smbd.service - Samba SMB Daemon.
Dec 05 21:43:36 huis-speakers systemd[1]: Reached target multi-user.target - Multi-User System.
Dec 05 21:43:36 huis-speakers systemd[1]: Reached target graphical.target - Graphical Interface.
Dec 05 21:43:36 huis-speakers systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP...
Dec 05 21:43:36 huis-speakers systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Dec 05 21:43:36 huis-speakers systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP.
Dec 05 21:43:36 huis-speakers systemd[1]: Startup finished in 19.827s (kernel) + 1min 25.501s (userspace) = 1min 45.329s.
Dec 05 21:43:37 huis-speakers volumio[915]: info: CoreCommandRouter::volumioGetState
Dec 05 21:43:37 huis-speakers volumio[915]: info: CorePlayQueue::getTrack 0
Dec 05 21:43:37 huis-speakers volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86
Dec 05 21:43:37 huis-speakers volumio[915]: SPOTIFY: SPOTIFY VOLUME undefined
Dec 05 21:43:37 huis-speakers volumio[915]: SPOTIFY: VOLUMIO VOLUME 86
Dec 05 21:43:37 huis-speakers volumio[915]: info: Aligning Spotify Volume to Volumio Volume
Dec 05 21:43:37 huis-speakers volumio[915]: info: CoreCommandRouter::volumioGetState
Dec 05 21:43:37 huis-speakers volumio[915]: info: CorePlayQueue::getTrack 0
Dec 05 21:43:37 huis-speakers volumio[915]: info: Setting Spotify Volume from Volumio: 86
Dec 05 21:43:39 huis-speakers volumio[915]: SPOTIFY: SETTING SPOTIFY VOLUME 86
Dec 05 21:43:39 huis-speakers volumio[915]: info: Sending Spotify command with payload to local API: /player/volume
Dec 05 21:43:40 huis-speakers volumio[915]: info: Volumio Network Manager: Network status updated: 1
Dec 05 21:44:01 huis-speakers go-librespot[1549]: time="2025-12-05T21:44:01+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)"
Dec 05 21:44:01 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:44:01 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:44:01 huis-speakers volumio[915]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up
Dec 05 21:44:01 huis-speakers volumio[915]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 05 21:44:01 huis-speakers volumio[915]: Error: socket hang up
Dec 05 21:44:01 huis-speakers volumio[915]: at connResetException (node:internal/errors:720:14)
Dec 05 21:44:01 huis-speakers volumio[915]: at Socket.socketOnEnd (node:_http_client:519:23)
Dec 05 21:44:01 huis-speakers volumio[915]: at Socket.emit (node:events:526:35)
Dec 05 21:44:01 huis-speakers volumio[915]: at endReadableNT (node:internal/streams/readable:1376:12)
Dec 05 21:44:01 huis-speakers volumio[915]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Dec 05 21:44:01 huis-speakers volumio[915]: code: 'ECONNRESET',
Dec 05 21:44:01 huis-speakers volumio[915]: response: undefined
Dec 05 21:44:01 huis-speakers volumio[915]: }
Dec 05 21:44:01 huis-speakers volumio[915]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 05 21:44:04 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Dec 05 21:44:04 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:44:04 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:44:04 huis-speakers go-librespot[1676]: go-librespot daemon starting...
Dec 05 21:44:04 huis-speakers go-librespot[1677]: time="2025-12-05T21:44:04+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:44:04 huis-speakers go-librespot[1677]: time="2025-12-05T21:44:04+01:00" level=debug msg="app state loaded"
Dec 05 21:44:04 huis-speakers go-librespot[1677]: time="2025-12-05T21:44:04+01:00" level=debug msg="stored credentials not found"
Dec 05 21:44:04 huis-speakers go-librespot[1677]: time="2025-12-05T21:44:04+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:44:05 huis-speakers go-librespot[1677]: time="2025-12-05T21:44:05+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-05T21:44:05+01:00 is before 2025-12-08T00:00:00Z"
Dec 05 21:44:05 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:44:05 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:44:08 huis-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Dec 05 21:44:08 huis-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:44:08 huis-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:44:08 huis-speakers go-librespot[1685]: go-librespot daemon starting...
Dec 05 21:44:08 huis-speakers go-librespot[1686]: time="2025-12-05T21:44:08+01:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:44:08 huis-speakers go-librespot[1686]: time="2025-12-05T21:44:08+01:00" level=debug msg="app state loaded"
Dec 05 21:44:08 huis-speakers go-librespot[1686]: time="2025-12-05T21:44:08+01:00" level=debug msg="stored credentials not found"
Dec 05 21:44:09 huis-speakers go-librespot[1686]: time="2025-12-05T21:44:09+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:44:09 huis-speakers go-librespot[1686]: time="2025-12-05T21:44:09+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-05T21:44:09+01:00 is before 2025-12-08T00:00:00Z"
Dec 05 21:44:09 huis-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:44:09 huis-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:44:10 huis-speakers sudo[1699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 21:43'
Dec 05 21:44:10 huis-speakers sudo[1699]: 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"