Jan 11 00:02:00 volumio volumio[26627]: info: peppyspectrum Daemon Stop Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio sudo[27122]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:00 volumio sudo[27122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:00 volumio sudo[27122]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:00 volumio sudo[27128]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jan 11 00:02:00 volumio sudo[27128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:00 volumio sudo[27128]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::volumioGetState Jan 11 00:02:00 volumio volumio[26627]: info: CorePlayQueue::getTrack 0 Jan 11 00:02:00 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jan 11 00:02:00 volumio volumio[26627]: info: Starting Shairport Sync Jan 11 00:02:00 volumio sudo[27138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 11 00:02:00 volumio sudo[27138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:00 volumio volumio[26627]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"} Jan 11 00:02:00 volumio volumio[26627]: info: Spotify Successfully logged in Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 00:02:00 volumio volumio[26627]: info: [1768111320201] CoreMusicLibrary::Adding element Spotify Jan 11 00:02:00 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 00:02:00 volumio volumio[26627]: Cannot find translation for source Bandcamp Discover Jan 11 00:02:00 volumio volumio[26627]: Cannot find translation for source SoundCloud Jan 11 00:02:00 volumio volumio[26627]: Cannot find translation for source YouTube2 Jan 11 00:02:00 volumio volumio[26627]: Cannot find translation for source YouTube Music Jan 11 00:02:00 volumio volumio[26627]: Cannot find translation for source Podcast Jan 11 00:02:00 volumio volumio[26627]: Cannot find translation for source Volusonic Jan 11 00:02:00 volumio volumio[26627]: Cannot find translation for source Spotify Jan 11 00:02:00 volumio sudo[27140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 00:02:00 volumio sudo[27140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:00 volumio systemd[1]: Reloading. Jan 11 00:02:01 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:01 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:01 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:01 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:02:01 volumio volumio[26627]: info: go-librespot daemon successfully initialized Jan 11 00:02:02 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.55.153 Jan 11 00:02:02 volumio avahi-daemon[664]: Registering new address record for 169.254.55.153 on eth0.IPv4. Jan 11 00:02:02 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:03 volumio 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. Jan 11 00:02:03 volumio 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. Jan 11 00:02:03 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.55.153 Jan 11 00:02:03 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.55.153 Jan 11 00:02:03 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.55.153 Jan 11 00:02:03 volumio avahi-daemon[664]: Withdrawing address record for 169.254.55.153 on eth0. Jan 11 00:02:03 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:03 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:03 volumio sudo[27138]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 11 00:02:03 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 11 00:02:03 volumio sudo[27193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 11 00:02:03 volumio sudo[27193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:03 volumio go-librespot[27196]: go-librespot daemon starting... Jan 11 00:02:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 00:02:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 00:02:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 00:02:03 volumio systemd[1]: shairport-sync.service: Consumed 1.419s CPU time. Jan 11 00:02:03 volumio go-librespot[27197]: time="2026-01-11T00:02:03-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:03 volumio go-librespot[27197]: time="2026-01-11T00:02:03-06:00" level=debug msg="app state loaded" Jan 11 00:02:03 volumio go-librespot[27197]: time="2026-01-11T00:02:03-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:03 volumio go-librespot[27197]: time="2026-01-11T00:02:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:04 volumio sudo[27193]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 00:02:04 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Jan 11 00:02:04 volumio sudo[27140]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:04 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:04 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:04 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:04 volumio volumio[26627]: info: Shairport-Sync Started Jan 11 00:02:04 volumio sudo[27205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Jan 11 00:02:04 volumio sudo[27205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:04 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Jan 11 00:02:04 volumio systemd[1]: squeezelite.service: Deactivated successfully. Jan 11 00:02:04 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jan 11 00:02:04 volumio sudo[27205]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=info msg="zeroconf server listening on port 43231" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=debug msg="obtained new client token: AADdvKOH3F3cFosWI5LLumtq8U9oDtg9OvJOAZeIvE4k1oPgKaLCBcwOuSvmWHjqkCXe1QibbDK+fepe9k1xVjown//BvYVvemfn0p6M/K7AkoXNRzhQVzQ4AJ5imBtByOaXZcCa5rGaPbWeiiMuQiiy4pdK7PdNt8Oi7+ChY78Pv7/cabu0iGGcnw0Q4Qu4Bm24W5h2dUzA/bMKBTCIGw2yd86G1a2sr6iV0V/0gNWoGtbkpPyhv0L44A==" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=debug msg="completed challenge" Jan 11 00:02:04 volumio go-librespot[27197]: time="2026-01-11T00:02:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:04 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:04 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:04 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 11 00:02:04 volumio sudo[27224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 11 00:02:04 volumio sudo[27224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:04 volumio sudo[27224]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:04 volumio volumio[26627]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Jan 11 00:02:04 volumio sudo[27228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Jan 11 00:02:04 volumio sudo[27228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:04 volumio sudo[27228]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:04 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jan 11 00:02:05 volumio sudo[27231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jan 11 00:02:05 volumio sudo[27231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:05 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Jan 11 00:02:05 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jan 11 00:02:05 volumio sudo[27231]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:05 volumio mpd[27136]: 2026-01-11T00:02:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 00:02:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 00:02:05 volumio sudo[26950]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:05 volumio sudo[27096]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:05 volumio sudo[26940]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:05 volumio volumio[26627]: error: MPD error: The expression evaluated to a falsy value: Jan 11 00:02:05 volumio volumio[26627]: assert.ok(self.idling) Jan 11 00:02:05 volumio volumio[26627]: error: The expression evaluated to a falsy value: Jan 11 00:02:05 volumio volumio[26627]: assert.ok(self.idling) Jan 11 00:02:05 volumio volumio[26627]: error: MPD error: The expression evaluated to a falsy value: Jan 11 00:02:05 volumio volumio[26627]: assert.ok(self.idling) Jan 11 00:02:05 volumio volumio[26627]: error: The expression evaluated to a falsy value: Jan 11 00:02:05 volumio volumio[26627]: assert.ok(self.idling) Jan 11 00:02:05 volumio volumio[26627]: info: MPD running with PID27136 Jan 11 00:02:05 volumio volumio[26627]: ,establishing connection Jan 11 00:02:05 volumio volumio[26627]: error: updateQueue error: null Jan 11 00:02:05 volumio volumio[26627]: error: updateQueue error: null Jan 11 00:02:05 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 11 00:02:05 volumio sudo[27242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 11 00:02:05 volumio sudo[27242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:05 volumio sudo[27242]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:06 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 11 00:02:06 volumio sudo[27248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 11 00:02:06 volumio sudo[27248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:06 volumio sudo[27248]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:06 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 11 00:02:06 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 00:02:06 volumio sudo[27251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 11 00:02:06 volumio sudo[27251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:06 volumio sudo[27251]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:07 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 11 00:02:07 volumio sudo[27255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 11 00:02:07 volumio sudo[27255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:07 volumio sudo[27255]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 11 00:02:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:07 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:07 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:07 volumio go-librespot[27257]: go-librespot daemon starting... Jan 11 00:02:07 volumio go-librespot[27258]: time="2026-01-11T00:02:07-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:07 volumio go-librespot[27258]: time="2026-01-11T00:02:07-06:00" level=debug msg="app state loaded" Jan 11 00:02:07 volumio go-librespot[27258]: time="2026-01-11T00:02:07-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:07 volumio go-librespot[27258]: time="2026-01-11T00:02:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:07 volumio go-librespot[27258]: time="2026-01-11T00:02:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 00:02:07 volumio go-librespot[27258]: time="2026-01-11T00:02:07-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 00:02:07 volumio go-librespot[27258]: time="2026-01-11T00:02:07-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 00:02:08 volumio go-librespot[27258]: time="2026-01-11T00:02:08-06:00" level=info msg="zeroconf server listening on port 37963" Jan 11 00:02:08 volumio volumio[26627]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 11 00:02:08 volumio sudo[27266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 11 00:02:08 volumio sudo[27266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:08 volumio go-librespot[27258]: time="2026-01-11T00:02:08-06:00" level=debug msg="obtained new client token: AAB9ccZvxQaD+IcafxxcK88guJWHGgC5imBnC8mROHtxBjdab/0pKF39S3eAUAOkQBZadNkhdAQUIqh5TCV6BkugpTU0RT7NyRLKvH0eLTwbX0EJu215iw4FdJ7gDi+C0UgMotlCgM3Z6uO5ReTi2DRNc2FLgEhZvlBfLqLpW9PJkdyOJ7FX+hHjLPB/xmEHtNtJmssnw6dzeNr3GIC3s3+JZQhbfUO9eVk6vJV8yduKI3GQtN+ohk8=" Jan 11 00:02:08 volumio sudo[27266]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:08 volumio volumio[26627]: info: Completed starting Core Plugins Jan 11 00:02:08 volumio volumio[26627]: info: ------------------------------------------- Jan 11 00:02:08 volumio volumio[26627]: info: ----- MyVolumio plugins startup ---- Jan 11 00:02:08 volumio volumio[26627]: info: ------------------------------------------- Jan 11 00:02:08 volumio volumio[26627]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 11 00:02:08 volumio go-librespot[27258]: time="2026-01-11T00:02:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:08 volumio go-librespot[27258]: time="2026-01-11T00:02:08-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:08 volumio go-librespot[27258]: time="2026-01-11T00:02:08-06:00" level=debug msg="completed challenge" Jan 11 00:02:08 volumio go-librespot[27258]: time="2026-01-11T00:02:08-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:08 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.229.23 Jan 11 00:02:08 volumio avahi-daemon[664]: Registering new address record for 169.254.229.23 on eth0.IPv4. Jan 11 00:02:08 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:08 volumio sudo[27285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 00:02:08 volumio sudo[27285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:08 volumio sudo[27287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 00:02:08 volumio sudo[27287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:08 volumio sudo[27290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 00:02:08 volumio sudo[27290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:08 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 00:02:08 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 00:02:08 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 00:02:08 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 00:02:08 volumio sudo[27287]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:08 volumio sudo[27285]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:08 volumio mpd_monitor.sh[27293]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 00:02:08 volumio sudo[27290]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:08 volumio volumio[26627]: info: Successfully started MPD Monitor Jan 11 00:02:08 volumio volumio[26627]: info: Successfully started MPD Monitor Jan 11 00:02:08 volumio volumio[26627]: info: Successfully started MPD Monitor Jan 11 00:02:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.229.23 Jan 11 00:02:09 volumio ntpd[872]: IO: Listen normally on 12070 eth0 169.254.229.23:123 Jan 11 00:02:09 volumio ntpd[872]: IO: new interface(s) found: waking up resolver Jan 11 00:02:09 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.229.23 Jan 11 00:02:09 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.229.23 Jan 11 00:02:09 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:09 volumio avahi-daemon[664]: Withdrawing address record for 169.254.229.23 on eth0. Jan 11 00:02:09 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:09 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:09 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:09 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:10 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:10 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:11 volumio ntpd[872]: IO: Deleting interface #12070 eth0, 169.254.229.23#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Jan 11 00:02:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 11 00:02:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:11 volumio go-librespot[27328]: go-librespot daemon starting... Jan 11 00:02:11 volumio go-librespot[27329]: time="2026-01-11T00:02:11-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:11 volumio go-librespot[27329]: time="2026-01-11T00:02:11-06:00" level=debug msg="app state loaded" Jan 11 00:02:11 volumio go-librespot[27329]: time="2026-01-11T00:02:11-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:11 volumio go-librespot[27329]: time="2026-01-11T00:02:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=info msg="zeroconf server listening on port 33837" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=debug msg="obtained new client token: AADGiCtwSUMywiTLsa1CIOyTppbXHT8Epguk/0RSO3qFZ2sFrm4ppzW75wSZek/aUPsZAYFgw+djRMIesKFpp5IGkiUNJjB9zPitgFHU8RRICHrPi2PBrGHD4/Ffxj/exPHMmrwRYwy5GmSbkLTqLhIBVHP7K3lNMpjnO0cqOrag2ld9t8UC0OrZjJQf1ZVwFznV8ovyw2KpzPXe+/ABu8rsRfMcDpb1/YNppRZ/SBLU21fLBDYsVKo=" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=debug msg="completed challenge" Jan 11 00:02:12 volumio go-librespot[27329]: time="2026-01-11T00:02:12-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:13 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 11 00:02:13 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.244.174 Jan 11 00:02:13 volumio avahi-daemon[664]: Registering new address record for 169.254.244.174 on eth0.IPv4. Jan 11 00:02:13 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.244.174 Jan 11 00:02:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.244.174 Jan 11 00:02:14 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.244.174 Jan 11 00:02:14 volumio avahi-daemon[664]: Withdrawing address record for 169.254.244.174 on eth0. Jan 11 00:02:14 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:14 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:14 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:14 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 11 00:02:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:15 volumio go-librespot[27369]: go-librespot daemon starting... Jan 11 00:02:15 volumio go-librespot[27370]: time="2026-01-11T00:02:15-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:15 volumio go-librespot[27370]: time="2026-01-11T00:02:15-06:00" level=debug msg="app state loaded" Jan 11 00:02:15 volumio go-librespot[27370]: time="2026-01-11T00:02:15-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:15 volumio go-librespot[27370]: time="2026-01-11T00:02:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:15 volumio go-librespot[27370]: time="2026-01-11T00:02:15-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:15 volumio go-librespot[27370]: time="2026-01-11T00:02:15-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:15 volumio go-librespot[27370]: time="2026-01-11T00:02:15-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:16 volumio go-librespot[27370]: time="2026-01-11T00:02:16-06:00" level=info msg="zeroconf server listening on port 44655" Jan 11 00:02:16 volumio go-librespot[27370]: time="2026-01-11T00:02:16-06:00" level=debug msg="obtained new client token: AACx00wAneatdCGTnEKzGOJTCsfTCHkkjI6OkezNCk+dqpXqY1PZ9p2IyhzftwDp5WBHf5cEeO5MACLio1YzXLdzxCui4fdtJNW9wF4U1Mx7B35MgbJ+zEBke/2AwdZFxdGHaZxgARm+wPqDI4MwaEEN2nORHkM9nJPDYY+/Wl9Uc/kRGh6kd4Afsm7xE6fSH0Zo8j2bvwj1QWhFjVXe4uwHG0Y1FTtyVYc92jz/U3X1v1va0npsvAE=" Jan 11 00:02:16 volumio go-librespot[27370]: time="2026-01-11T00:02:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:16 volumio go-librespot[27370]: time="2026-01-11T00:02:16-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:16 volumio go-librespot[27370]: time="2026-01-11T00:02:16-06:00" level=debug msg="completed challenge" Jan 11 00:02:16 volumio go-librespot[27370]: time="2026-01-11T00:02:16-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 11 00:02:16 volumio volumio[26627]: info: Adding plugin bluetooth to MyMusic Plugins Jan 11 00:02:16 volumio volumio[26627]: info: Adding plugin multiroom to MyMusic Plugins Jan 11 00:02:16 volumio volumio[26627]: info: Adding plugin metavolumio to MyMusic Plugins Jan 11 00:02:16 volumio volumio[26627]: info: Adding plugin cd_controller to MyMusic Plugins Jan 11 00:02:16 volumio volumio[26627]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 11 00:02:16 volumio volumio[26627]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 11 00:02:16 volumio volumio[26627]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 11 00:02:16 volumio volumio[26627]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 11 00:02:17 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 00:02:17 volumio volumio[26627]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 11 00:02:17 volumio volumio[26627]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 11 00:02:17 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:17 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:17 volumio volumio[26627]: info: Starting MyVolumio Remote Streaming Endpoints Jan 11 00:02:17 volumio volumio[26627]: info: MyVolumio login type: Token Jan 11 00:02:17 volumio volumio[26627]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 11 00:02:17 volumio volumio[26627]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 11 00:02:18 volumio volumio[26627]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 11 00:02:18 volumio volumio[26627]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 11 00:02:18 volumio volumio[26627]: info: Streaming services startup Jan 11 00:02:18 volumio volumio[26627]: info: Starting Streaming Daemon Jan 11 00:02:18 volumio sudo[27379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 11 00:02:18 volumio sudo[27379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:18 volumio volumio[26627]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 11 00:02:18 volumio sudo[27379]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:18 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:18 volumio volumio[26627]: error: Cannot start Volumio Streaming Daemon Jan 11 00:02:18 volumio volumio[26627]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 11 00:02:18 volumio volumio[26627]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 11 00:02:18 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:18 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.233.32 Jan 11 00:02:18 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:18 volumio avahi-daemon[664]: Registering new address record for 169.254.233.32 on eth0.IPv4. Jan 11 00:02:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:19 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 11 00:02:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jan 11 00:02:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:19 volumio volumio[26627]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 11 00:02:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:19 volumio go-librespot[27415]: go-librespot daemon starting... Jan 11 00:02:19 volumio go-librespot[27416]: time="2026-01-11T00:02:19-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:19 volumio go-librespot[27416]: time="2026-01-11T00:02:19-06:00" level=debug msg="app state loaded" Jan 11 00:02:19 volumio go-librespot[27416]: time="2026-01-11T00:02:19-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:19 volumio go-librespot[27416]: time="2026-01-11T00:02:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.233.32 Jan 11 00:02:19 volumio go-librespot[27416]: time="2026-01-11T00:02:19-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:19 volumio go-librespot[27416]: time="2026-01-11T00:02:19-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:19 volumio go-librespot[27416]: time="2026-01-11T00:02:19-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:20 volumio go-librespot[27416]: time="2026-01-11T00:02:20-06:00" level=info msg="zeroconf server listening on port 39335" Jan 11 00:02:20 volumio volumio[26627]: info: MyVolumio token set successfully Jan 11 00:02:20 volumio volumio[26627]: info: MYVOLUMIO: Adding device Jan 11 00:02:20 volumio volumio[26627]: info: MYVOLUMIO: Evaluating Server Jan 11 00:02:20 volumio go-librespot[27416]: time="2026-01-11T00:02:20-06:00" level=debug msg="obtained new client token: AADAXWj2JrsyVW8Fc2E++v/JVfNhZFsBpdaF/wpoYr453yzeTIOoFmeVa42tHXzTglztOIjxfaYshxGoU1qTEpSany5eeSrpCRAhX+aN3vmd1TOgcN8PVkYrENNMSr3U33ANL4L/oakul89qMhYRuBk1hkQCpv/rgqCdu3QFljaF5VYfa6145S1w/0kZ3Fbh4STplvOqBC4MbVzYcAye3YdpCxKHQNKCqccBtJ2Toe6HTvgQj3Ynprw=" Jan 11 00:02:20 volumio go-librespot[27416]: time="2026-01-11T00:02:20-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Jan 11 00:02:20 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.233.32 Jan 11 00:02:20 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.233.32 Jan 11 00:02:20 volumio avahi-daemon[664]: Withdrawing address record for 169.254.233.32 on eth0. Jan 11 00:02:20 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:20 volumio go-librespot[27416]: time="2026-01-11T00:02:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Jan 11 00:02:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:20 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:20 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:20 volumio go-librespot[27416]: time="2026-01-11T00:02:20-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:20 volumio go-librespot[27416]: time="2026-01-11T00:02:20-06:00" level=debug msg="completed challenge" Jan 11 00:02:20 volumio go-librespot[27416]: time="2026-01-11T00:02:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:20 volumio volumio[26627]: info: MyVolumio status changed Jan 11 00:02:20 volumio volumio[26627]: info: Streaming services startup Jan 11 00:02:20 volumio volumio[26627]: info: Starting Streaming Daemon Jan 11 00:02:20 volumio volumio[26627]: info: Removing browser output: myVolumio user plan is not superstar Jan 11 00:02:20 volumio volumio[26627]: info: Removing audio output: Jan 11 00:02:20 volumio volumio[26627]: info: Stoppping Tunnel 1 Jan 11 00:02:20 volumio sudo[27460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 11 00:02:20 volumio sudo[27460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:20 volumio sudo[27462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 11 00:02:20 volumio sudo[27462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:02:20 volumio sudo[27460]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio volumio[26627]: error: Cannot start Volumio Streaming Daemon Jan 11 00:02:20 volumio volumio[26627]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 11 00:02:20 volumio volumio[26627]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 11 00:02:20 volumio volumio[26627]: info: Setting Geolocation for MyVolumio to us1 Jan 11 00:02:20 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:20 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:20 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 00:02:20 volumio sudo[27462]: pam_unix(sudo:session): session closed for user root Jan 11 00:02:20 volumio volumio[26627]: info: Remote SSH Stopped Jan 11 00:02:21 volumio volumio[26627]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 11 00:02:21 volumio volumio[26627]: info: Updating MyVolumio device info Jan 11 00:02:21 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:21 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:21 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:21 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:21 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:21 volumio volumio[26627]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 11 00:02:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jan 11 00:02:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:23 volumio go-librespot[27465]: go-librespot daemon starting... Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="app state loaded" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=info msg="zeroconf server listening on port 37563" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="obtained new client token: AACtYSfMdXeeB+VHZN2G1Z+TBOjHxmj0o+8Sboad6uho01mY4/lz4ZCqs73P8/qU5wPVG23E15NAZ3Js5zxgKIJXw6Y4209HlvHMC36erIIZo+rj7rrDaHo926xsRmju0a4+Pt/QoJafHHIAq03MgutVHtmn5PM+AqBWSe9L/Ke3ccdWF4fyvnXt0QPAjYi2dRUNRsd4+q5GGrbx76DPvFCfamjMH2KRlKPxwPtgXl6Gjyd5eeZn0v0yVQ==" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=debug msg="completed challenge" Jan 11 00:02:23 volumio go-librespot[27466]: time="2026-01-11T00:02:23-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:24 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.230.227 Jan 11 00:02:24 volumio avahi-daemon[664]: Registering new address record for 169.254.230.227 on eth0.IPv4. Jan 11 00:02:24 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:24 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.230.227 Jan 11 00:02:24 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:24 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:24 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:24 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:24 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.230.227 Jan 11 00:02:25 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.230.227 Jan 11 00:02:25 volumio avahi-daemon[664]: Withdrawing address record for 169.254.230.227 on eth0. Jan 11 00:02:25 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:25 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:25 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:25 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:25 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:25 volumio volumio[26627]: info: MYVOLUMIO: Adding device Jan 11 00:02:25 volumio volumio[26627]: info: MYVOLUMIO: Evaluating Server Jan 11 00:02:25 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 00:02:25 volumio volumio[26627]: info: Setting Geolocation for MyVolumio to us1 Jan 11 00:02:25 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:25 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:25 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:26 volumio volumio[26627]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 11 00:02:26 volumio volumio[26627]: info: Updating MyVolumio device info Jan 11 00:02:26 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:26 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:26 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:27 volumio volumio[26627]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 11 00:02:27 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 00:02:27 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:27 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 00:02:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jan 11 00:02:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:27 volumio volumio-remote-updater[14193]: Test mode disabled Jan 11 00:02:27 volumio volumio-remote-updater[14193]: Alpha mode disabled Jan 11 00:02:27 volumio volumio-remote-updater[14193]: Alpha legacy test mode disabled Jan 11 00:02:27 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 11 00:02:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:27 volumio go-librespot[27527]: go-librespot daemon starting... Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="app state loaded" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:27 volumio volumio[26627]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

ANNOUNCEMENT

\n
    \n
  • Production line
    • \n
\n

FIX

\n
    \n
  • Nodejs 20.5.1 version pinning with static package fallback
    • \n
\n","title":"Update v4.073","updateavailable":true} Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=info msg="zeroconf server listening on port 42777" Jan 11 00:02:27 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="obtained new client token: AABGr9FjoQu01m/ogH0Ogn0jZKVJXFtlEklukl/Kz4+uekZetwYmIpmxKG/d1kZ7luoc4kfKYUeRXQp/K+7Y/BgKIlOu1hdGn+luMv1I1a7Ocm9T8YixCczUhmvY8dmA40X6QVhh72sUVZYpHaMMGKDfBhLLQkynbKnaFiwi8I8tP1GPlJ1v3Kh9gfitjHwjbn+6m2FOCVV+XE2SC7U6OkMGCUQqU2ZCPN0g4iF+CcsQrCCJoSNAZo/CrQ==" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=debug msg="completed challenge" Jan 11 00:02:27 volumio go-librespot[27529]: time="2026-01-11T00:02:27-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:27 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:27 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:30 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.164.1 Jan 11 00:02:30 volumio avahi-daemon[664]: Registering new address record for 169.254.164.1 on eth0.IPv4. Jan 11 00:02:30 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:30 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:30 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:30 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:30 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:30 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jan 11 00:02:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:30 volumio go-librespot[27570]: go-librespot daemon starting... Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="app state loaded" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=info msg="zeroconf server listening on port 37387" Jan 11 00:02:31 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.164.1 Jan 11 00:02:31 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.164.1 Jan 11 00:02:31 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.164.1 Jan 11 00:02:31 volumio avahi-daemon[664]: Withdrawing address record for 169.254.164.1 on eth0. Jan 11 00:02:31 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:31 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:31 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:31 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:31 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="obtained new client token: AABM+N0TF07Hhy9Bw6mqv323dhg0nkkadNtp63uEk5Exx8R3n4bwvzYv1/LRtx0AOFwJAUVj7vWPnPqBA5GgduPy3p1mSyrfcQwdyivDGkifaG7CtCF+n8Zf+BN78lIQmoOY2IRB5yD2z1Z9hFKPvQ/wd79LbpdXKYgvFRU7OM8y/tF86Mdy0PPHzM8v+bWowfEVG2d1Gz+9zFsawpeFlDe1zCckYdNWjQ+NxauR4O4a5EPJO4TqVal/HA==" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=debug msg="completed challenge" Jan 11 00:02:31 volumio go-librespot[27571]: time="2026-01-11T00:02:31-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:32 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 00:02:32 volumio volumio[26627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 11 00:02:32 volumio volumio[26627]: info: CoreCommandRouter::volumioGetState Jan 11 00:02:32 volumio volumio[26627]: info: CorePlayQueue::getTrack 0 Jan 11 00:02:33 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:33 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 11 00:02:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:34 volumio go-librespot[27594]: go-librespot daemon starting... Jan 11 00:02:34 volumio go-librespot[27595]: time="2026-01-11T00:02:34-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:34 volumio go-librespot[27595]: time="2026-01-11T00:02:34-06:00" level=debug msg="app state loaded" Jan 11 00:02:34 volumio go-librespot[27595]: time="2026-01-11T00:02:34-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:34 volumio go-librespot[27595]: time="2026-01-11T00:02:34-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:34 volumio go-librespot[27595]: time="2026-01-11T00:02:34-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 00:02:34 volumio go-librespot[27595]: time="2026-01-11T00:02:34-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:34-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:35-06:00" level=info msg="zeroconf server listening on port 46405" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:35-06:00" level=debug msg="obtained new client token: AADNLB+uGd67gs59gnYMisFakUwqXgneTV0+OqGjIbkK51eOgLxlvqB5M5iIkDwaKEsU0fq90yyXvY12eaFYoTTu0BfVd9VOfzTvxCinOtf/nP6tkYrAkycyk9dwRkBsMrI8sEQWGrkNVPcmVlx+sDVBoDKN2VQk6dZUh1PmiaOcHKQVCfoAV7n9HUgpVZiPOmrBx1RI5SGg3ObHwK8yHhGrOdEvV7CgHR95vyd5P3URHSCmG8VB7+8=" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:35-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:35-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:35-06:00" level=debug msg="completed challenge" Jan 11 00:02:35 volumio go-librespot[27595]: time="2026-01-11T00:02:35-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:35 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.237.101 Jan 11 00:02:35 volumio avahi-daemon[664]: Registering new address record for 169.254.237.101 on eth0.IPv4. Jan 11 00:02:35 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:35 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:35 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:35 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:36 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.237.101 Jan 11 00:02:36 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.237.101 Jan 11 00:02:36 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.237.101 Jan 11 00:02:36 volumio avahi-daemon[664]: Withdrawing address record for 169.254.237.101 on eth0. Jan 11 00:02:36 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:36 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:36 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:36 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:36 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:36 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:36 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:37 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:37 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:37 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 11 00:02:37 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 11 00:02:37 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 00:02:37 volumio volumio[26627]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 00:02:37 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 00:02:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 11 00:02:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:38 volumio go-librespot[27634]: go-librespot daemon starting... Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="app state loaded" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:38 volumio volumio[26627]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 11 00:02:38 volumio volumio[26627]: info: Completed starting MyVolumio Plugin Jan 11 00:02:38 volumio volumio[26627]: [Metrics] CommandRouter: 62s 39.12ms Jan 11 00:02:38 volumio volumio[26627]: info: CoreCommandRouter::volumiosetStartupVolume Jan 11 00:02:38 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:02:38 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:02:38 volumio volumio[26627]: info: CoreCommandRouter::Close All Modals sent Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:38 volumio volumio[26627]: info: CoreCommandRouter::Close All Modals sent Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=info msg="zeroconf server listening on port 36353" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="obtained new client token: AABUixAtdlCWofosI7DI9x8PfNlj2lUJ5hBNiU/piIEIfTfwY4hK3Tc9gvLxbUhnY0RkTV5lxYeFo9H14ZiiqDNTJWNjMgIF6jOKXbt1l0pnYtJcJ+Q6WyMo3BxPvi1ZdsYj3+2XH8XTvQxgh8lDi0J4fEVEDet5+hK6DPkeiVncPaUYBRMRiM/5QxZduVjR/TWj2IzLkI1Rh2P1qDa6pCvZoy4QeiHhU3DhXTgXmaR6tLM0FZ4kU6UaRA==" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:38 volumio go-librespot[27635]: time="2026-01-11T00:02:38-06:00" level=debug msg="completed challenge" Jan 11 00:02:39 volumio go-librespot[27635]: time="2026-01-11T00:02:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:39 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 11 00:02:39 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 00:02:39 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 11 00:02:39 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:39 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:40 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.102.108 Jan 11 00:02:40 volumio avahi-daemon[664]: Registering new address record for 169.254.102.108 on eth0.IPv4. Jan 11 00:02:40 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:40 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:40 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:40 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:41 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.102.108 Jan 11 00:02:41 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.102.108 Jan 11 00:02:41 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.102.108 Jan 11 00:02:41 volumio avahi-daemon[664]: Withdrawing address record for 169.254.102.108 on eth0. Jan 11 00:02:41 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:41 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:41 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:41 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:41 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jan 11 00:02:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:42 volumio go-librespot[27694]: go-librespot daemon starting... Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="app state loaded" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=info msg="zeroconf server listening on port 45657" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="obtained new client token: AACp5P0o3VsziYV/XWMW4g0MKJJzdrNGkscYLv+p7oLyyxzAINtonlzDfVH1Jiz8v5VnuG+gaVB/VaNrfASoYfY7+H7H1VbdLQf3MCL6xriFvtqOkZnH3fy7qTRfGyOp+1y0JuMkKnRinea+ZtgHN5gGt9Pe0eyaq4RGg4OTJlki9BWEH8iOdMrFBS3LKqHEF3k92/6Q4z4KkfW4PMJWJ2TnTpOmnBUHuS14GozMR8TltL8sQmQFei3puw==" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=debug msg="completed challenge" Jan 11 00:02:42 volumio go-librespot[27695]: time="2026-01-11T00:02:42-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:42 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:42 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:45 volumio volumio[26627]: info: BOOT COMPLETED Jan 11 00:02:45 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:45 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jan 11 00:02:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:45 volumio go-librespot[27703]: go-librespot daemon starting... Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="app state loaded" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=info msg="zeroconf server listening on port 34699" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="obtained new client token: AAC2mbU6ZkaBlX44cc/HVcrta93fN8gWO2zbDTz7T1xmwuEav1pQoEPTEiE+SYx4bYKCKHjWV4TqJcXr2vL+xvrhs+t3pI3peyJaGwJb8IPC2Gp7Qp2stUz+p5CtCwYlrTGGaJnwrJJRMBZa/TwUCQWbhNPVrasjY66rGFcfeaxmw4b3t/WkQdrtl4dbe0hRpJOl+blRWo4bILuUhl8jev3BByX5ACLswovrxIfQzAMJ1Js+L+ibg1+ECg==" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:46 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.64.48 Jan 11 00:02:46 volumio avahi-daemon[664]: Registering new address record for 169.254.64.48 on eth0.IPv4. Jan 11 00:02:46 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=debug msg="completed challenge" Jan 11 00:02:46 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:46 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:46 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:46 volumio go-librespot[27704]: time="2026-01-11T00:02:46-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:47 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.64.48 Jan 11 00:02:47 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.64.48 Jan 11 00:02:47 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.64.48 Jan 11 00:02:47 volumio avahi-daemon[664]: Withdrawing address record for 169.254.64.48 on eth0. Jan 11 00:02:47 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:47 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:47 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:47 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:47 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:48 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:48 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jan 11 00:02:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:49 volumio go-librespot[27763]: go-librespot daemon starting... Jan 11 00:02:49 volumio go-librespot[27764]: time="2026-01-11T00:02:49-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:49 volumio go-librespot[27764]: time="2026-01-11T00:02:49-06:00" level=debug msg="app state loaded" Jan 11 00:02:49 volumio go-librespot[27764]: time="2026-01-11T00:02:49-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:49 volumio go-librespot[27764]: time="2026-01-11T00:02:49-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:49 volumio go-librespot[27764]: time="2026-01-11T00:02:49-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:49 volumio go-librespot[27764]: time="2026-01-11T00:02:49-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:49 volumio go-librespot[27764]: time="2026-01-11T00:02:49-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:50 volumio go-librespot[27764]: time="2026-01-11T00:02:50-06:00" level=info msg="zeroconf server listening on port 46057" Jan 11 00:02:50 volumio go-librespot[27764]: time="2026-01-11T00:02:50-06:00" level=debug msg="obtained new client token: AAAnk/vO2GEW5Gf/yZ7jXboZ55/Qk7tk5/MuQq/UzNjcih9Jde/A10dVvtNonQ343EoPsw36osP6IqymlsqR2yX9oZmYXQLb7vuX1DwxlpOGdqGemicFJEqEasdLDeErDpVj9BJCTaBfzR6IJHaRDzW/hUAJ/RJLk3X8S6fxVmEfdyQbg3M6QafyM96zYg2MMLE0jLvMraiRdG8kdurnTsUA6TNq6p+bP+3QzF1O/963kcaZdk2ey1E=" Jan 11 00:02:50 volumio go-librespot[27764]: time="2026-01-11T00:02:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:50 volumio go-librespot[27764]: time="2026-01-11T00:02:50-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:50 volumio go-librespot[27764]: time="2026-01-11T00:02:50-06:00" level=debug msg="completed challenge" Jan 11 00:02:50 volumio go-librespot[27764]: time="2026-01-11T00:02:50-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:51 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.143.73 Jan 11 00:02:51 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:51 volumio avahi-daemon[664]: Registering new address record for 169.254.143.73 on eth0.IPv4. Jan 11 00:02:51 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:51 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:51 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:51 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:51 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:52 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.143.73 Jan 11 00:02:52 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.143.73 Jan 11 00:02:52 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.143.73 Jan 11 00:02:52 volumio avahi-daemon[664]: Withdrawing address record for 169.254.143.73 on eth0. Jan 11 00:02:52 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:52 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:52 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:52 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:52 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jan 11 00:02:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:53 volumio go-librespot[27803]: go-librespot daemon starting... Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="app state loaded" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=info msg="zeroconf server listening on port 36977" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="obtained new client token: AABr4EQOhze2vu9LGAajI3fRFCq8jfrtzwetu+q1ks97qmoU1JbYzRtVwWIxmuVaVPeF/5rMENAIuxhrURZt7gK2PnaTX0LnyiR+u8q50a1KMp6eTHrPRszALWa3Kc0xI2jI744jN4H7lICquArqh0TZW1fnKUOUhfpTK+uWMRcia9s19krO/iM17Cj+jIz0xHjQJSFPBQaoT0nbJOtvvRAFld2XzeNe55fjLXV6VUnA9KHbww9CH2Sxcg==" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=debug msg="completed challenge" Jan 11 00:02:53 volumio go-librespot[27804]: time="2026-01-11T00:02:53-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:54 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:54 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Jan 11 00:02:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:02:57 volumio go-librespot[27811]: go-librespot daemon starting... Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="app state loaded" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="stored credentials not found" Jan 11 00:02:57 volumio volumio[26627]: info: UPDATER: Scheduling automatic update Jan 11 00:02:57 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Jan 11 00:02:57 volumio volumio[26627]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Jan 11 00:02:57 volumio volumio[26627]: info: UPDATER: Auto update will take place at: Mon Jan 12 2026 04:34:56 GMT-0600 (Central Standard Time) Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=info msg="zeroconf server listening on port 39757" Jan 11 00:02:57 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.213.0 Jan 11 00:02:57 volumio avahi-daemon[664]: Registering new address record for 169.254.213.0 on eth0.IPv4. Jan 11 00:02:57 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:02:57 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:57 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:57 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="obtained new client token: AABbUaAM3MNbhev0ZwDcmsFdNMglMHI0zWMtN5PXvI5UkBC7C96aBTsz8GjiWXchJOBhBYDGl1BiYQb09rEACQmpvYLZynOucuF/p70fKMyNY3odlYbm4/iS6wy7ABJfz/XB52JyWH1kDQZVeienIjjUQ9gZkQxXnQDTsLgYTixAj0GUqMBoSMTdX4LkYuWTOHv6PZbw2rWMfCp3G9ioCIXvhNVssP9UOT06p2Aop4lageULALP9/+82Ag==" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="completed keyexchange" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=debug msg="completed challenge" Jan 11 00:02:57 volumio go-librespot[27812]: time="2026-01-11T00:02:57-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:02:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:02:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:02:57 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.213.0 Jan 11 00:02:57 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:02:57 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:02:58 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.213.0 Jan 11 00:02:58 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.213.0 Jan 11 00:02:58 volumio avahi-daemon[664]: Withdrawing address record for 169.254.213.0 on eth0. Jan 11 00:02:58 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:02:58 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:02:58 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:02:58 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:02:58 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jan 11 00:03:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:00 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:00 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:00 volumio go-librespot[27865]: go-librespot daemon starting... Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="app state loaded" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=info msg="zeroconf server listening on port 35619" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="obtained new client token: AAB9IkNnD73UiOhDVYPObPqq504nVdaX9B/SFuU6lQJzM8UHQEc1RTRktatKr77prwjpVXIhQM7233d4QtTOH3GK+oVumFqJBQIL8HV922MqpCMlu8DlKlxQ1gS/a4h9qr3xvudf+89vrIcrXTxlZF+avIy8pVfLaM7wL0NZc6Vnxg7pZYxsE4JG8j/YvtKe1zpYuVqhokl/nJxbv/F793W74YdPAqtc0BRtnB58osHu+seS/Y0+BBTZWA==" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=debug msg="completed challenge" Jan 11 00:03:01 volumio go-librespot[27866]: time="2026-01-11T00:03:01-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:03 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.132.183 Jan 11 00:03:03 volumio avahi-daemon[664]: Registering new address record for 169.254.132.183 on eth0.IPv4. Jan 11 00:03:03 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:03 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.132.183 Jan 11 00:03:03 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:03 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:04 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.132.183 Jan 11 00:03:04 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.132.183 Jan 11 00:03:04 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:04 volumio avahi-daemon[664]: Withdrawing address record for 169.254.132.183 on eth0. Jan 11 00:03:04 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:04 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:04 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:04 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Jan 11 00:03:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:04 volumio go-librespot[27905]: go-librespot daemon starting... Jan 11 00:03:04 volumio go-librespot[27906]: time="2026-01-11T00:03:04-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:04 volumio go-librespot[27906]: time="2026-01-11T00:03:04-06:00" level=debug msg="app state loaded" Jan 11 00:03:04 volumio go-librespot[27906]: time="2026-01-11T00:03:04-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:04 volumio go-librespot[27906]: time="2026-01-11T00:03:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:04 volumio go-librespot[27906]: time="2026-01-11T00:03:04-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:04 volumio go-librespot[27906]: time="2026-01-11T00:03:04-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:04 volumio go-librespot[27906]: time="2026-01-11T00:03:04-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:05 volumio go-librespot[27906]: time="2026-01-11T00:03:05-06:00" level=info msg="zeroconf server listening on port 35219" Jan 11 00:03:05 volumio go-librespot[27906]: time="2026-01-11T00:03:05-06:00" level=debug msg="obtained new client token: AADR6MmRN/fBChzTeBmSn1Z8m4CL8AQq5iqDWCDUEnwnBoKGAKERpbi/89uQlwn4v8BOJ+lEJFUltZXyk6onyeaHvN6LTn+d4XaxJ34AWqvK6CWb+zAoS7M9QQuoqd8FjHnHSqgP9PDJBjgH4kmQyuDmXBy5cN8jRXabcTYoduIZJxdCIWkvl1sNrAdWOJetKXINv5EJx1Ti5B4Gsxb6pKc/vBwe7vJMGIF+Zy+dyi9UWDEVS9fWITM=" Jan 11 00:03:05 volumio go-librespot[27906]: time="2026-01-11T00:03:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:05 volumio go-librespot[27906]: time="2026-01-11T00:03:05-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:05 volumio go-librespot[27906]: time="2026-01-11T00:03:05-06:00" level=debug msg="completed challenge" Jan 11 00:03:05 volumio go-librespot[27906]: time="2026-01-11T00:03:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:06 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:06 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Jan 11 00:03:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:08 volumio go-librespot[27913]: go-librespot daemon starting... Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="app state loaded" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=info msg="zeroconf server listening on port 34103" Jan 11 00:03:08 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.233.229 Jan 11 00:03:08 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:08 volumio avahi-daemon[664]: Registering new address record for 169.254.233.229 on eth0.IPv4. Jan 11 00:03:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="obtained new client token: AABNBsn6UP9Fr4QtJLFDFbC3C9G6M9mghcKRgItpjAU+o+BEOtp/6YYLmlj9dg9JGIL2mWGWeJvkf4lXiZd642sHfKj5xNjDczC2C3KWs3Iv3K1BlYwQ6nyMpskA+7wXU2I1WqMXSeMUTHOp+HVSlUygVJYPKtNgPVyDTdXyKvjiF0yedpgxmRTvY6V541b+vgFeb4qMS76RaEuGUoMGwYCQv/iEFJJVE+DHPUjxwoYumSyLQyz8RXR42w==" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.233.229 Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=debug msg="completed challenge" Jan 11 00:03:08 volumio go-librespot[27914]: time="2026-01-11T00:03:08-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:09 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.233.229 Jan 11 00:03:09 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.233.229 Jan 11 00:03:09 volumio avahi-daemon[664]: Withdrawing address record for 169.254.233.229 on eth0. Jan 11 00:03:09 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:09 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:09 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:09 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:09 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:09 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:09 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Jan 11 00:03:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:12 volumio go-librespot[27971]: go-librespot daemon starting... Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="app state loaded" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=info msg="zeroconf server listening on port 45259" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="obtained new client token: AADU3BvsTjKYA9Nk0WK8Ow6ikza53nZ38SnoooRGeLBFf6hor2Vke7ArGasNCl8FWNsh5+85BYnl3iCGZVNL34gO0kRKZYvnTnGu7Ih9RLo9QTowj3ZS8glvyie7Lhi3JMwQb2+rSoZCQrMe87L4TIcxNXPRp7L6xMleUFgR4NR1mcO0Tv/4MbH/+5oTDsLqRF1O528fKcrULEVx5hoLfCsoo5zXcgAwnifg9z/J+FlynfBE8Wd2r/oGLQ==" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=debug msg="completed challenge" Jan 11 00:03:12 volumio go-librespot[27972]: time="2026-01-11T00:03:12-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:12 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:12 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:13 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.181.1 Jan 11 00:03:13 volumio avahi-daemon[664]: Registering new address record for 169.254.181.1 on eth0.IPv4. Jan 11 00:03:13 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.181.1 Jan 11 00:03:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.181.1 Jan 11 00:03:14 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.181.1 Jan 11 00:03:14 volumio avahi-daemon[664]: Withdrawing address record for 169.254.181.1 on eth0. Jan 11 00:03:14 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:14 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Jan 11 00:03:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:15 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:15 volumio go-librespot[28011]: go-librespot daemon starting... Jan 11 00:03:15 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="app state loaded" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=info msg="zeroconf server listening on port 33389" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="obtained new client token: AACMVXqO7sAiirLhc1NX5dGVyXInRzssoSSFg8Hwa/V5L23pA33+MxS6JnsbEF0PhDAGYkVUQWyZEpG2QpK+CrA2kLjQZwL01qx7iYW8yPt2T3ay485aGgNDrIXEzKXDgPFtz9cGotO/ZTRvalE6gvt/cGP8ASmCRCwFCK5o+v6s6Ac3Q4h4aztaX/x8A6zJIyFnIkTNUytypUzVvVApTh3ssmaJKqvUf2tiYyPmTWLDZfHNwPqqw7kIuQ==" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=debug msg="completed challenge" Jan 11 00:03:16 volumio go-librespot[28012]: time="2026-01-11T00:03:16-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:18 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.26.7 Jan 11 00:03:18 volumio avahi-daemon[664]: Registering new address record for 169.254.26.7 on eth0.IPv4. Jan 11 00:03:18 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:18 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:18 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:18 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:18 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:18 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.26.7 Jan 11 00:03:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.26.7 Jan 11 00:03:19 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.26.7 Jan 11 00:03:19 volumio avahi-daemon[664]: Withdrawing address record for 169.254.26.7 on eth0. Jan 11 00:03:19 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:19 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Jan 11 00:03:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:19 volumio go-librespot[28065]: go-librespot daemon starting... Jan 11 00:03:19 volumio go-librespot[28066]: time="2026-01-11T00:03:19-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:19 volumio go-librespot[28066]: time="2026-01-11T00:03:19-06:00" level=debug msg="app state loaded" Jan 11 00:03:19 volumio go-librespot[28066]: time="2026-01-11T00:03:19-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:19 volumio go-librespot[28066]: time="2026-01-11T00:03:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:19 volumio go-librespot[28066]: time="2026-01-11T00:03:19-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:19 volumio go-librespot[28066]: time="2026-01-11T00:03:19-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:19 volumio go-librespot[28066]: time="2026-01-11T00:03:19-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:20 volumio go-librespot[28066]: time="2026-01-11T00:03:20-06:00" level=info msg="zeroconf server listening on port 39307" Jan 11 00:03:20 volumio go-librespot[28066]: time="2026-01-11T00:03:20-06:00" level=debug msg="obtained new client token: AAAtV+CagxJ5REP3jDswhH0ymoxj3WFXwnM+MQD9uqp2VH+vLMx4jhJk3nhIyeh3p+qOSfyLRi0BheQz9Q4Kv86i1QjEiUAuJdp20gCgcfC8uAjfaTuQegi0z7EEdE1Es7XeybvQ/yLLj08H0vumhTjqkkZLcsI32xcJRZprzLg521251nfOe89KrLNBZ41LLKCni0fwHPOPy0k5hfmbadS/KtDiMbVwSHMnn6l2mO62n5Nk9glJpug=" Jan 11 00:03:20 volumio go-librespot[28066]: time="2026-01-11T00:03:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:20 volumio go-librespot[28066]: time="2026-01-11T00:03:20-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:20 volumio go-librespot[28066]: time="2026-01-11T00:03:20-06:00" level=debug msg="completed challenge" Jan 11 00:03:20 volumio go-librespot[28066]: time="2026-01-11T00:03:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:22 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:22 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Jan 11 00:03:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:23 volumio go-librespot[28074]: go-librespot daemon starting... Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="app state loaded" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=info msg="zeroconf server listening on port 32939" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="obtained new client token: AADW+9MvE6GXWmADFEaimaYgtMliIgunXz+x7eGSCmBNzLJdfM+fgbdvvc8ZNi/5BngkrdlJeFZnxbQY8YYMuzRuNC4CKzKXMazM8cxi36ZYDfI0fDF6cnliYvmRTUyUDgBxSxWMWyTlanGNO1quwcMLZg3hb778het6w0sS+9hdV/02y/Q+R0nVvBYssCYO9L2HRbrNgzt+FXrZ3yBze5URgg4aFYE7psSyI8CxQ+0Xc+e958PKPp2Z2Q==" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=debug msg="completed challenge" Jan 11 00:03:23 volumio go-librespot[28075]: time="2026-01-11T00:03:23-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:24 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.205.41 Jan 11 00:03:24 volumio avahi-daemon[664]: Registering new address record for 169.254.205.41 on eth0.IPv4. Jan 11 00:03:24 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:24 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:24 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:24 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:25 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:25 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.205.41 Jan 11 00:03:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.205.41 Jan 11 00:03:25 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.205.41 Jan 11 00:03:25 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:25 volumio avahi-daemon[664]: Withdrawing address record for 169.254.205.41 on eth0. Jan 11 00:03:25 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:25 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:25 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:25 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Jan 11 00:03:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:27 volumio go-librespot[28115]: go-librespot daemon starting... Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="app state loaded" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=info msg="zeroconf server listening on port 43697" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="obtained new client token: AABy0/kAuOEEOfnvSaZ6hqu+0kz3WuKyF1rTEL9GE0M79g9daisLWF9Vnsyevy6Ui2sxZMYuGhU/fuXwjSEZRf0Si4ddr6BY5PGJwymjBHf0YF+pNmRy67hr2CdFOmiAHNVaKbTnCEeoqLYZr1wwMuT6PUYuBlkc+lCQskCKQQZDWcUHGj10ozvWJcO1HKb/+5j7zMn+TF04CTIvD6QCWHjpfEDS90Ic47RA/qnl/BAV/ZZ0TRkVDhQ7gA==" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=debug msg="completed challenge" Jan 11 00:03:27 volumio go-librespot[28116]: time="2026-01-11T00:03:27-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:28 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:28 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Jan 11 00:03:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:30 volumio go-librespot[28141]: go-librespot daemon starting... Jan 11 00:03:31 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="app state loaded" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:31 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=info msg="zeroconf server listening on port 40533" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="obtained new client token: AADu6bgDpd1+m1jhSFqKfmnTTLUJQ2iqFgN8gBvNJxeei8Nx90jC4VUoLZvc7cngRNa/5kS3NQsguKC91pN4C1ccmC5sK1Er1PZnbf0lyZIbRQHf2Qq+SFkx9nTxFptMbXajMjrwH1AvcbS4kLIQL4FahipWBsPb+95sk5jjr/BD0UUR3w4kJrtIJa0n23AaDlK717Ot5J8D0XRPOGRybNYyIQeU49MkjCbDDeRmdioTBrEXVc3C9IWZNg==" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=debug msg="completed challenge" Jan 11 00:03:31 volumio go-librespot[28142]: time="2026-01-11T00:03:31-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:31 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.36.192 Jan 11 00:03:31 volumio avahi-daemon[664]: Registering new address record for 169.254.36.192 on eth0.IPv4. Jan 11 00:03:31 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:31 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:31 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:31 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:31 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.36.192 Jan 11 00:03:32 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.36.192 Jan 11 00:03:32 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.36.192 Jan 11 00:03:32 volumio avahi-daemon[664]: Withdrawing address record for 169.254.36.192 on eth0. Jan 11 00:03:32 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:32 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:34 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:34 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Jan 11 00:03:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:34 volumio go-librespot[28181]: go-librespot daemon starting... Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=debug msg="app state loaded" Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:34 volumio go-librespot[28182]: time="2026-01-11T00:03:34-06:00" level=info msg="zeroconf server listening on port 41619" Jan 11 00:03:35 volumio go-librespot[28182]: time="2026-01-11T00:03:35-06:00" level=debug msg="obtained new client token: AABw09tXj9omC9VUns9emLTYfr/TRnKsdMTzZgmw3FH4lwMmKHcC4c0miQvheGFjcKtrkzT+KwPJPKesOtJY6OKltKjrgKIYDSdajT5SAPcO5wI7wzsw0OuOqH/oYSE1THToDzm+0FtHX9JwfnsrNtN/IC2rX1fszyNNXzhDJkTRSbmDgnUUKF/GdIc572V2MjIJk2wZBFoOqeFw8cndAgu2M7F1rnHnwL2a3Yk7cnilgHlJr9az+UE=" Jan 11 00:03:35 volumio go-librespot[28182]: time="2026-01-11T00:03:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:35 volumio go-librespot[28182]: time="2026-01-11T00:03:35-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:35 volumio go-librespot[28182]: time="2026-01-11T00:03:35-06:00" level=debug msg="completed challenge" Jan 11 00:03:35 volumio go-librespot[28182]: time="2026-01-11T00:03:35-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:37 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:37 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:38 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.233.126 Jan 11 00:03:38 volumio avahi-daemon[664]: Registering new address record for 169.254.233.126 on eth0.IPv4. Jan 11 00:03:38 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Jan 11 00:03:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:38 volumio go-librespot[28205]: go-librespot daemon starting... Jan 11 00:03:38 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:38 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:38 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="app state loaded" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:38 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.233.126 Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=info msg="zeroconf server listening on port 45365" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="obtained new client token: AABEIhxSAPiXen0VmtieLZZjlUnRgalXCY82MTbW/x5Oix1niCPUCXPcGj3Fg43pa9RMLJgygo5ZocuJ6pk0hcbWMph+nskKU+F0uOdt259Wa6HIPsLZdT8vm2dlXefdq4fnv3A8J/y6C511k3t0q5yaDcni/ln8JZX0pDorsbFAdmLA/xY0h2lI/rFA3nZAYuN3OMmr3DfJ1guZjZeIlnvuYUI9UOeNW0lmZnFdsAzgNPUto0pl0KxzzQ==" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:38 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.233.126 Jan 11 00:03:38 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.233.126 Jan 11 00:03:38 volumio avahi-daemon[664]: Withdrawing address record for 169.254.233.126 on eth0. Jan 11 00:03:38 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:38 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:38 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:38 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:38 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=debug msg="completed challenge" Jan 11 00:03:38 volumio go-librespot[28206]: time="2026-01-11T00:03:38-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:40 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:40 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Jan 11 00:03:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:41 volumio go-librespot[28243]: go-librespot daemon starting... Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="app state loaded" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=info msg="zeroconf server listening on port 38933" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="obtained new client token: AABdU5Qm/irPMWqD484prBKgZQfifb3rfNqx1EYoKjDO6xFDcKGnQrKwgRpjRT8KGXjwsM9Z2gdYZXrSFsjzVXFUGi5f7KxbT9v3rg5d12URVBlZ6+wDaGWT7T3zYq7keD36FFI+8gMEbfeonP2YxvHuYgevGnmERLZ06beyu+kRhFY67cdZAlIwdRMd9pevxUtD7UiJpuFpIdRRFX7YMxxqFf4pa4AQnItkLYSvd2OazmDuTNwGFUBZ9Q==" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=debug msg="completed challenge" Jan 11 00:03:42 volumio go-librespot[28244]: time="2026-01-11T00:03:42-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:42 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.4.37 Jan 11 00:03:42 volumio avahi-daemon[664]: Registering new address record for 169.254.4.37 on eth0.IPv4. Jan 11 00:03:42 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:42 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:42 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:42 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:43 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:43 volumio volumio[26627]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:43 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.4.37 Jan 11 00:03:44 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.4.37 Jan 11 00:03:44 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.4.37 Jan 11 00:03:44 volumio avahi-daemon[664]: Withdrawing address record for 169.254.4.37 on eth0. Jan 11 00:03:44 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:44 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:44 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:44 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:44 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Jan 11 00:03:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:45 volumio go-librespot[28284]: go-librespot daemon starting... Jan 11 00:03:45 volumio go-librespot[28285]: time="2026-01-11T00:03:45-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:45 volumio go-librespot[28285]: time="2026-01-11T00:03:45-06:00" level=debug msg="app state loaded" Jan 11 00:03:45 volumio go-librespot[28285]: time="2026-01-11T00:03:45-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:45 volumio go-librespot[28285]: time="2026-01-11T00:03:45-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:45 volumio go-librespot[28285]: time="2026-01-11T00:03:45-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:45 volumio go-librespot[28285]: time="2026-01-11T00:03:45-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:45 volumio go-librespot[28285]: time="2026-01-11T00:03:45-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:46 volumio go-librespot[28285]: time="2026-01-11T00:03:46-06:00" level=info msg="zeroconf server listening on port 39463" Jan 11 00:03:46 volumio volumio[26627]: info: Initializing connection to go-librespot Websocket Jan 11 00:03:46 volumio go-librespot[28285]: time="2026-01-11T00:03:46-06:00" level=debug msg="new websocket client" Jan 11 00:03:46 volumio volumio[26627]: info: Connection to go-librespot Websocket established Jan 11 00:03:46 volumio go-librespot[28285]: time="2026-01-11T00:03:46-06:00" level=debug msg="obtained new client token: AAACPzhKlCDTWK8jMNyPGX+Kar11zH/mFSFya1gYrHZWSqQZaDQK3hNS+waVUprwanWTT0Bfv97OryqzCig51mlucZwFtDu85eai5esq688z9SP4abgE18mrK9lNgTnOFuzjQk7y0xy3mMsOGpCMkz+3uuua8ocLXjqDHeT+QhV8kKUC/3jJBybVfkzPLKSr+EoaUFGOih6aKgr6RTi9W4u6+w8FOf/pAojR35Y1IRZCk6khpB2EY0I=" Jan 11 00:03:46 volumio go-librespot[28285]: time="2026-01-11T00:03:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 00:03:46 volumio go-librespot[28285]: time="2026-01-11T00:03:46-06:00" level=debug msg="completed keyexchange" Jan 11 00:03:46 volumio go-librespot[28285]: time="2026-01-11T00:03:46-06:00" level=debug msg="completed challenge" Jan 11 00:03:46 volumio go-librespot[28285]: time="2026-01-11T00:03:46-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 00:03:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 00:03:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 00:03:46 volumio volumio[26627]: info: Connection to go-librespot Websocket closed Jan 11 00:03:48 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.145.92 Jan 11 00:03:48 volumio avahi-daemon[664]: Registering new address record for 169.254.145.92 on eth0.IPv4. Jan 11 00:03:48 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 00:03:48 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:48 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:48 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:49 volumio volumio[26627]: info: Getting Spotify volume Jan 11 00:03:49 volumio volumio[26627]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 00:03:49 volumio volumio[26627]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 00:03:49 volumio volumio[26627]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 11 00:03:49 volumio volumio[26627]: errno: -111, Jan 11 00:03:49 volumio volumio[26627]: code: 'ECONNREFUSED', Jan 11 00:03:49 volumio volumio[26627]: syscall: 'connect', Jan 11 00:03:49 volumio volumio[26627]: address: '127.0.0.1', Jan 11 00:03:49 volumio volumio[26627]: port: 9879, Jan 11 00:03:49 volumio volumio[26627]: response: undefined Jan 11 00:03:49 volumio volumio[26627]: } Jan 11 00:03:49 volumio volumio[26627]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 00:03:49 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.145.92 Jan 11 00:03:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Jan 11 00:03:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:49 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.145.92 Jan 11 00:03:49 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.145.92 Jan 11 00:03:49 volumio avahi-daemon[664]: Withdrawing address record for 169.254.145.92 on eth0. Jan 11 00:03:49 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 00:03:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 00:03:49 volumio go-librespot[28323]: go-librespot daemon starting... Jan 11 00:03:49 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 00:03:49 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:49 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 00:03:49 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=debug msg="app state loaded" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=debug msg="stored credentials not found" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=info msg="zeroconf server listening on port 36093" Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=debug msg="obtained new client token: AADR3Dm9qWNB6zIYX8e25pyVy53Gz2GKVKCLC/mXDaALkZXmu74yQuG6kKHeEjlyHAPi63Pj49m0cGHdO7P+HEZrVxmVu0L3F1XfPp9jjBsgPMnF1eF2DbEU3iHPfp9tJu4RG1ilz/lFSJBvt34FEeiLh7tuRcy/f7bvH7kFwvLgHACZzkxIVdRg/XX+5aDNXlrASN36wxu74Fb7c4rMkGpWkQyvATbyBA9Juh3Jt3PkLyt2D61thvCiIw==" Jan 11 00:03:49 volumio sudo[28364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 00:02' Jan 11 00:03:49 volumio sudo[28364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 00:03:49 volumio go-librespot[28339]: time="2026-01-11T00:03:49-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"