Sep 01 05:36:00 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:00 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:01 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 05:36:01 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:02 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:02 spla-repro go-librespot[16015]: go-librespot daemon starting... Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="app state loaded" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=info msg="zeroconf server listening on port 37729" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="obtained new client token: AAHiV+z6VJGL5V7bssdmL5qwFYBqsNrBSRC/ZaPerfLAmq1EIEahK6xGapDc7JYKlzhIy3NW4QZeme3R068gXfPZ8QscempXzQ/vkgiWGVgiV0kJDKj5ijyVRKkv0VJXK6qjdGwKbrZnjHT0Mu1V3uLE0LaR3SpQhMl9o6USbFxQPm3UpBkuVGh8g/kEQE0oaPm008ofoYEeQhfOna2SfqafDRi2zvw6swmu3Ay8qis5hqE3FvY1haPXJg==" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=debug msg="completed challenge" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:02 spla-repro go-librespot[16016]: time="2026-09-01T05:36:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:02 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:02 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:03 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:03 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:04 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:36:04 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:04 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:36:04 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:36:05 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 05:36:05 spla-repro volumio[15606]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 01 05:36:05 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:36:05 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:05 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 05:36:05 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:05 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:05 spla-repro go-librespot[16028]: go-librespot daemon starting... Sep 01 05:36:05 spla-repro go-librespot[16029]: time="2026-09-01T05:36:05+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:05 spla-repro go-librespot[16029]: time="2026-09-01T05:36:05+02:00" level=debug msg="app state loaded" Sep 01 05:36:05 spla-repro go-librespot[16029]: time="2026-09-01T05:36:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=info msg="zeroconf server listening on port 41731" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=debug msg="obtained new client token: AAFcwxmyr6vxxnOwNhbsLMBRr7W7JHyEZqz1XeyLyfDEEBIddmT9vYJKVTRpRhUL4W8c41+C7zwWsH+19MsXXB2JXnI0geL/rjtgiAJGYvYMWysko/wbNCwjcppbZxOyLVY9HUine3bgKaxp6saF/nsnqzD5zlvvPeptdwnYrjrmMC4rp6gFHPiMOAoK1e8NB3PNjX1Upd1CLFDHuuw71oetgmva9S1KxtVoxiwkgJAs8rcPOPiINC8=" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=debug msg="completed challenge" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:06 spla-repro go-librespot[16029]: time="2026-09-01T05:36:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:06 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:06 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:06 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:06 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:07 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 05:36:07 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:36:07 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 05:36:07 spla-repro volumio-remote-updater[725]: Test mode disabled Sep 01 05:36:07 spla-repro volumio-remote-updater[725]: Alpha mode disabled Sep 01 05:36:07 spla-repro volumio-remote-updater[725]: Alpha legacy test mode disabled Sep 01 05:36:07 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 01 05:36:07 spla-repro volumio[15606]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 01 05:36:07 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 01 05:36:09 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 01 05:36:09 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:09 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:09 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:09 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:09 spla-repro go-librespot[16046]: go-librespot daemon starting... Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=debug msg="app state loaded" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=info msg="zeroconf server listening on port 36971" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=debug msg="obtained new client token: AAG0CNmDy86khuDeJr7ahTsZJyf5EzuiWdYEuKjt0kxPyNF08229JqPx7utTVq71F69j+KKPlPQhAEZE9pLRk+nh63pTZ+sYtR6xS19iqUcR0AHAzqlVxb+KY1jcS9d2RzTlxGOyo26hamdaUXM4CFdTYXkjbDNm74PlONU8desa03uYKWvOHwdWkeY8lf7i6bSfMZpJQVpI7kuASzpNaAZaCOE4hfPWrsYqPLEC4jrZ81pIwbmkKttG0Q==" Sep 01 05:36:09 spla-repro go-librespot[16047]: time="2026-09-01T05:36:09+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:10 spla-repro go-librespot[16047]: time="2026-09-01T05:36:10+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:10 spla-repro go-librespot[16047]: time="2026-09-01T05:36:10+02:00" level=debug msg="completed challenge" Sep 01 05:36:10 spla-repro go-librespot[16047]: time="2026-09-01T05:36:10+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:10 spla-repro go-librespot[16047]: time="2026-09-01T05:36:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:10 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:10 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 05:36:10 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 05:36:11 spla-repro volumio[15606]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 01 05:36:11 spla-repro volumio[15606]: info: Completed starting MyVolumio Plugin Sep 01 05:36:11 spla-repro volumio[15606]: [Metrics] CommandRouter: 42s 76.00ms Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreCommandRouter::volumiosetStartupVolume Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:36:11 spla-repro volumio[15606]: info: VolumeController:: Setting startup Volume 45 Sep 01 05:36:11 spla-repro volumio[15606]: info: VolumeController::SetAlsaVolume45 Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreCommandRouter::Close All Modals sent Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreCommandRouter::Close All Modals sent Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreStateMachine::pushState Sep 01 05:36:11 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 05:36:11 spla-repro volumio[15606]: info: CoreCommandRouter::volumioPushState Sep 01 05:36:12 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 05:36:12 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 05:36:12 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 05:36:12 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:12 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:13 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 01 05:36:13 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:13 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:13 spla-repro go-librespot[16071]: go-librespot daemon starting... Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="app state loaded" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=info msg="zeroconf server listening on port 38319" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="obtained new client token: AAEJkZeivuH8bdCW/umRbv/HYiQODgkst0CiKEq1hmZn3JIOhMrnbTpbgvRS6BDoDvzVvspCUX4chO669dD71IS+eQQHouFdrlyjZEwkreeqJmOFLjLD1vARoX7BdaUo802VIrQ4RIjKdX91de+VFlAdLUU3vU50PyUfhuTO6EMhw0iBQ0gZRkBGjdcpyMpT4rwg7vrwSCnBzW50WHG//dhq0mID3ot4r5YN1LpNhgh2/+E/IezuJ5Oa6A==" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=debug msg="completed challenge" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:13 spla-repro go-librespot[16072]: time="2026-09-01T05:36:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:13 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:13 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:14 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:36:14 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:15 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:15 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:16 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 01 05:36:16 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:17 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:17 spla-repro go-librespot[16083]: go-librespot daemon starting... Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="app state loaded" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=info msg="zeroconf server listening on port 33157" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="obtained new client token: AAG/7g9UNPP0/3TePxjXRbtpzcYJbuBlcz8wpBmEwWEIDyfZNfUhbS2RMoqsSdWR2bM65a7u9UVoKXqCDlnyxyo2TrqpGMDyLc6yyp82HC0MaWh51WkwhimJ/1zhAD4kpH1oJmA26p8/I3yEpNgAyc9bwOFm4iYu81K3yIwggVjZ786+Y3Np6TQxxry3LXsgOwklllbqjd8D0h7Zrm+l3n8v5sDM5dINSgVjqXa2tdfsyDl0oZRd3K8lww==" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=debug msg="completed challenge" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:17 spla-repro go-librespot[16084]: time="2026-09-01T05:36:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:17 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:17 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:18 spla-repro volumio[15606]: info: BOOT COMPLETED Sep 01 05:36:18 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:18 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:20 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 01 05:36:20 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:20 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:20 spla-repro go-librespot[16093]: go-librespot daemon starting... Sep 01 05:36:20 spla-repro go-librespot[16094]: time="2026-09-01T05:36:20+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:20 spla-repro go-librespot[16094]: time="2026-09-01T05:36:20+02:00" level=debug msg="app state loaded" Sep 01 05:36:20 spla-repro go-librespot[16094]: time="2026-09-01T05:36:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=info msg="zeroconf server listening on port 36235" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=debug msg="obtained new client token: AAF8slc1YxxAC13G+jGy8GcTTy2p7HFfSnvkGrrDMtDFw855TXNL2FSMcFqjU5jv7MHsqulfNtKUkAmF4znPBevOSNgGRIAR2d/PNYrkzFp++dDl1WnmCaY8cAhUkWar4Fb6+3mgo6NfBnuAjN6p9dU9HWYrl7ZQP9+2q1UJw+n4rsIquUwaY9DnD27AyhSbUswSrC5L0NCKgW8En2Nyd6K34L3b/wXduwVEctyfC3jnYcDyUoUg0QU=" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=debug msg="completed challenge" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:21 spla-repro go-librespot[16094]: time="2026-09-01T05:36:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:21 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:21 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:21 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:21 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:24 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 01 05:36:24 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:24 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:36:24 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:24 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:36:24 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:36:24 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:24 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:24 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:24 spla-repro go-librespot[16119]: go-librespot daemon starting... Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=debug msg="app state loaded" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=info msg="zeroconf server listening on port 34413" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:24 spla-repro go-librespot[16120]: time="2026-09-01T05:36:24+02:00" level=debug msg="obtained new client token: AAEQTqWMhDFfhJs0TbgHFICRr8swTuG+PKfSwBVG2BAmwKyhOC98N9Z9dK7AjKtpGaDIOXaCW2v2bi8HHcOHyTEgWjxAaK964D6kvf3+ZluUrcwdjTiSO4DuDeebXK3nsFbz+hPCjNdfBFJfrKGZLW27Z0A3JJz18P/wfZM9RDLRhWCno/vvJet5eYBHwOyyViW03dxBfRG8/30DGH/zueFn0mvi5hgdMxC6PCieNnJEwx8i21iaaJWMdQ==" Sep 01 05:36:25 spla-repro go-librespot[16120]: time="2026-09-01T05:36:25+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:25 spla-repro go-librespot[16120]: time="2026-09-01T05:36:25+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:25 spla-repro go-librespot[16120]: time="2026-09-01T05:36:25+02:00" level=debug msg="completed challenge" Sep 01 05:36:25 spla-repro go-librespot[16120]: time="2026-09-01T05:36:25+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:25 spla-repro go-librespot[16120]: time="2026-09-01T05:36:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:25 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:25 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:27 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:27 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:28 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Sep 01 05:36:28 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:28 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:28 spla-repro go-librespot[16129]: go-librespot daemon starting... Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="app state loaded" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=info msg="zeroconf server listening on port 40927" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="obtained new client token: AAHh0SNYdNszOxYI80V99TzaBVV3olxXBqn7+9vAn52NejMj6GWKv/2G4Prp3p2Szq/3felOlhd5DQvfNxpPzZHhOrX8yXSLqdynunCSuy8OMOcwli2hwV8bytYc4gYfws89aPw58PPaYSPSPgRn8C9AMlDljAjO3NX9LIMmQk522gBCTRbleaAu7OGS32Hk4TiaitFfjyCU4Rw5T+gNkhBO/jSofGZH7VK0PS4+1FQ0zW3LasK2YhW6qw==" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=debug msg="completed challenge" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:28 spla-repro go-librespot[16130]: time="2026-09-01T05:36:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:28 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:28 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:30 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:30 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:31 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Sep 01 05:36:31 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:32 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:32 spla-repro go-librespot[16155]: go-librespot daemon starting... Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="app state loaded" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=info msg="zeroconf server listening on port 41405" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="obtained new client token: AAEd7WnnHWADdwMhqA0b6y/58gh5PpwElM+QWmd07EiD3EbhXly8N6iZyb3bS0n555YFj3vrI0lQ0WMs/nqwDfFRMFcdro/lP4/FyZjVRnOBzbeDg1SIn8dgdPd1JDKqeOOMV9NOv+qsWfV8XN0mL22ntnkVlIejgqBVO0m1SJGBoLMFkwdeZmIHauRxsfbOSX2at/fNE5R1ESxMSKsmDFjb2fBHLpOI5TRXWFPrsywOdREfQPfknXNBzA==" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=debug msg="completed challenge" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:32 spla-repro go-librespot[16156]: time="2026-09-01T05:36:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:32 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:32 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:33 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:33 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:34 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:36:34 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:35 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Sep 01 05:36:35 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:35 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:35 spla-repro go-librespot[16165]: go-librespot daemon starting... Sep 01 05:36:35 spla-repro go-librespot[16166]: time="2026-09-01T05:36:35+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:35 spla-repro go-librespot[16166]: time="2026-09-01T05:36:35+02:00" level=debug msg="app state loaded" Sep 01 05:36:35 spla-repro go-librespot[16166]: time="2026-09-01T05:36:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=info msg="zeroconf server listening on port 36357" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=debug msg="obtained new client token: AAG5yk+hhVvUK1hngJy2mkKSnCuQlEvhCyt1WLPAELQM89ovuPsFKH6BfE4JTPeHX3DDKZOvWVc3jnyOqkdI/rOIu4CICPRnQ4F1PV2ig7ZL6KeByDAXLBoxdOxSoEJ4bLez5pAH5AAhYoKKHBUKFZbxGIthLBL+XW6FZt6MjjVLcHMW3g2aJt+Jrj8MfoCUM71JwUCVSaIvZYPnCjK5taBeqX/56zs7vqRpZbDg6HMKKUm35K8qtBk=" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=debug msg="completed challenge" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:36 spla-repro go-librespot[16166]: time="2026-09-01T05:36:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:36 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:36 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:36 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:36 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:39 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Sep 01 05:36:39 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:39 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:39 spla-repro go-librespot[16176]: go-librespot daemon starting... Sep 01 05:36:39 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:39 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=debug msg="app state loaded" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=info msg="zeroconf server listening on port 45175" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=debug msg="obtained new client token: AAHjwuXbX3tO9/sgyE0IQ0fRuuwPOa8kuziFqMXNm3aC1CIOQbcBFqwRNz+DxGNHFTpdx5Qe2w4ZgGh5hF8qpx2ZkhLecFP20h2Fm/akRuTPc2zKZ8Xqyg8tBw2xWWocoVe98ZenEoCJ2nSsTMdyC3E2hEx/HOG+c23YbRgo3LmD7a8dUL592l0qdj3fQZ8rw1TsPMZbNHNscJDokhURSN0VuQbfXjUl4xjEDQDXMBeHFNMqVNSZobGoAA==" Sep 01 05:36:39 spla-repro go-librespot[16177]: time="2026-09-01T05:36:39+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:40 spla-repro go-librespot[16177]: time="2026-09-01T05:36:40+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:40 spla-repro go-librespot[16177]: time="2026-09-01T05:36:40+02:00" level=debug msg="completed challenge" Sep 01 05:36:40 spla-repro go-librespot[16177]: time="2026-09-01T05:36:40+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:40 spla-repro go-librespot[16177]: time="2026-09-01T05:36:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:40 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:40 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:42 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:42 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:43 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Sep 01 05:36:43 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:43 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:43 spla-repro go-librespot[16202]: go-librespot daemon starting... Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="app state loaded" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=info msg="zeroconf server listening on port 38871" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="obtained new client token: AAGg/N3H8hT0lPrdAbAddDMifSEAaCI08wU5EAt79aoJJdHSS2Z0rgzTC+Pkt+Cj1L3hRhuBQbefYsewB/minL+rPh2Fx6+5CZczFtvz/Ju+vBw0VtuvalUjxTghY3GvpY96XVzGz+KfPnnpc+Jf9oYB0uBCmPhjh015yEsZxyH/bF10c+SXZOvQVU4WCrGUk6D4WcGfWodhOItwEp+VLWc5jP27KdE7VlN7Jfc1867jrMcNuJIaOAybtg==" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=debug msg="completed challenge" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:43 spla-repro go-librespot[16203]: time="2026-09-01T05:36:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:43 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:43 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:44 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:36:44 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:44 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:36:44 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:36:45 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:45 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:46 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Sep 01 05:36:46 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:47 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:47 spla-repro go-librespot[16213]: go-librespot daemon starting... Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="app state loaded" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=info msg="zeroconf server listening on port 33481" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="obtained new client token: AAHrZs0WQcMqSob2tT8oTz2x3JBisv7bvHofO5x9NeODsIkXCe1ECk1z3RRUg1X8tkOzmfddki8P+vPVaBLTST/pfxsFk48FxVHWg7XG1xm/0VLBzlPg5PLT5C35FTE7VbuIf4sUkLIwYfLqcfycNGh1Y6kdpaA16DAglCR8fbc4Um3OIAvvGD+Hg0x+EuPaPdcCsKhAEKlDQa7QahVR2o6WBIEUYxxGLaAC+vAKaY3QVBQgP499XQblVg==" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=debug msg="completed challenge" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:47 spla-repro go-librespot[16214]: time="2026-09-01T05:36:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:47 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:47 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:48 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:48 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:50 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Sep 01 05:36:50 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:50 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:50 spla-repro go-librespot[16223]: go-librespot daemon starting... Sep 01 05:36:50 spla-repro go-librespot[16224]: time="2026-09-01T05:36:50+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:50 spla-repro go-librespot[16224]: time="2026-09-01T05:36:50+02:00" level=debug msg="app state loaded" Sep 01 05:36:50 spla-repro go-librespot[16224]: time="2026-09-01T05:36:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=info msg="zeroconf server listening on port 37771" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=debug msg="obtained new client token: AAGJqLqGqSEQdkwJ0/edv4HXQSQlYG2eQC2n5Q8Q4HV/oaDmWS4AFoX1otmzxlCsgLN7PhxH6Dt7BFskDo7kidn34bWQyGHE1xbFIoC7z6QlNVxvLrbXT04CTmcdg9ALFcWycwlUkOuHBrY7lkCCwHFg2KJ2TiDHVCZnAkJXxX2zWcKGFeL6bX3h5RfvkXQTb1G0KzHiwuDwkNrmziYLYmIJpEvFM/bW9aj2eJocCiu0l3cBsWRd654=" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=debug msg="completed challenge" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:51 spla-repro go-librespot[16224]: time="2026-09-01T05:36:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:51 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:51 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:51 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:51 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:54 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Sep 01 05:36:54 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:54 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:36:54 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:36:54 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:54 spla-repro go-librespot[16247]: go-librespot daemon starting... Sep 01 05:36:54 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:54 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=debug msg="app state loaded" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=info msg="zeroconf server listening on port 34711" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:54 spla-repro go-librespot[16248]: time="2026-09-01T05:36:54+02:00" level=debug msg="obtained new client token: AAHSB2F7GUz+zrKMC8rpdmZlYwGwvAZTi8I/LkQgXr1oRDage6/ef9aDgiR0K5OTE/vd5xLTPfwMjcdZZdNKGtYI/XYHJZkm0wBQprHVUGRc9HqRviVyTKjLofStESmVHZVhaXBhkc6f3BIDb1nuduWqcVOUjVIYF5fhuc/dPLd8GCLVzI1nEFb8eZnACfAEhSryyaBzu6uS/j+j5Vot0BdXujevA/L5r1hFuayj2F/6uaBKze13lhbAvQ==" Sep 01 05:36:55 spla-repro go-librespot[16248]: time="2026-09-01T05:36:55+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:55 spla-repro go-librespot[16248]: time="2026-09-01T05:36:55+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:55 spla-repro go-librespot[16248]: time="2026-09-01T05:36:55+02:00" level=debug msg="completed challenge" Sep 01 05:36:55 spla-repro go-librespot[16248]: time="2026-09-01T05:36:55+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:55 spla-repro go-librespot[16248]: time="2026-09-01T05:36:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:55 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:55 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:36:57 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:36:57 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:36:58 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Sep 01 05:36:58 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:58 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:36:58 spla-repro go-librespot[16257]: go-librespot daemon starting... Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=debug msg="app state loaded" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=info msg="zeroconf server listening on port 35727" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=debug msg="obtained new client token: AAFXFgJMLQZPaGFu+r6DtApXY6LOcqgtokceLoCTnV3LmIRJULNoFbVAIPqDjd/QAqQeAUg+BkBBVRdwfHdCtyZAWNZ/2JN9eUxOkyNwRh3SSEaELg6nZBmbmEJXgPg7+MjXqQ4ag1T6Nv/I6Lv6WRB5gHwKRRPq6dpd1r9T6vE/ex5+wInuFrVabnmHQZUKJI6RnwVo/HIr3JRss16TUO6FQKrNI5Uzn8yxJqfyHFmyeFDzOiz5rQ0vbg==" Sep 01 05:36:58 spla-repro go-librespot[16258]: time="2026-09-01T05:36:58+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:36:59 spla-repro go-librespot[16258]: time="2026-09-01T05:36:59+02:00" level=debug msg="completed keyexchange" Sep 01 05:36:59 spla-repro go-librespot[16258]: time="2026-09-01T05:36:59+02:00" level=debug msg="completed challenge" Sep 01 05:36:59 spla-repro go-librespot[16258]: time="2026-09-01T05:36:59+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:36:59 spla-repro go-librespot[16258]: time="2026-09-01T05:36:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:36:59 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:36:59 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:37:00 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:37:00 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:37:02 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Sep 01 05:37:02 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:37:02 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:37:02 spla-repro go-librespot[16282]: go-librespot daemon starting... Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="app state loaded" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=info msg="zeroconf server listening on port 38815" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="obtained new client token: AAFQphXbF5wlvX/qeqbiTWc5EWMAU+PGNi7U9HlPi5CVrtmQuFpFHOIU/FlliM+aHeuIYWa+E7fKCm0zmYdgGyf49hc2y3qLQ3KJ3C85IDhLvvhuNM8FzXtnmab8dJBd2D60d4+6x7nJxPwyQEJq1HuYIdQ2pUPnjXF3t8Bc7DepVYwMxvZ+dj2znacaOShkrN/mdLJ9sq3ZpQHYcR+BZy0mBdRxECUu5Gg4TQIFIdegMWnXiZ3kMSrU/w==" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="completed keyexchange" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=debug msg="completed challenge" Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:37:02 spla-repro dhcpcd[793]: eth0: failed to renew DHCP, rebinding Sep 01 05:37:02 spla-repro go-librespot[16283]: time="2026-09-01T05:37:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:37:02 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:37:02 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:37:02 spla-repro dhcpcd[793]: eth0: leased 192.168.200.203 for 300 seconds Sep 01 05:37:03 spla-repro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Sep 01 05:37:03 spla-repro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Sep 01 05:37:03 spla-repro systemd[1]: welcome.service: Deactivated successfully. Sep 01 05:37:03 spla-repro systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 01 05:37:03 spla-repro systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 01 05:37:03 spla-repro systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 01 05:37:03 spla-repro welcome[16311]: Resolved ip:[1] 192.168.200.203 Sep 01 05:37:03 spla-repro systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 01 05:37:03 spla-repro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 01 05:37:03 spla-repro volumio[15606]: info: Received Get System Info Sep 01 05:37:03 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 05:37:03 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 05:37:03 spla-repro volumio[15606]: info: Discovery: Getting this device information Sep 01 05:37:03 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:37:03 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:37:03 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 05:37:03 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 01 05:37:03 spla-repro volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 01 05:37:03 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:37:03 spla-repro volumio[15606]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:37:04 spla-repro volumio5-onboarding[4505]: time=2026-09-01T05:37:04.314+02:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 01 05:37:04 spla-repro volumio[15606]: info: CoreCommandRouter::volumioGetState Sep 01 05:37:04 spla-repro volumio[15606]: info: CorePlayQueue::getTrack 0 Sep 01 05:37:04 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:37:04 spla-repro volumio[15606]: info: Listing playlists Sep 01 05:37:05 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Sep 01 05:37:05 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:37:06 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:37:06 spla-repro go-librespot[16316]: go-librespot daemon starting... Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="app state loaded" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=info msg="zeroconf server listening on port 38921" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="obtained new client token: AAG890SQ1kmhqqNTlsIwTKmASjV8ac7KT7fzel29nXB4xK/mqUuruPGREPO6848FIwyFNMRZo9D0XheHlIWrUE5eYA/M+z6eh+uENeNcoH+P7GbOF8jaw8gK3Xoli/wA89OCrEIKH96ypHhyfPPFgsgQaXBb9E1iC0zkakb3nCqtT7OU0y2zXZ6UEsoQg/u5sFn6xtFbjofeD8D3rgmI8sZ0PTBXUesEQ+5VabotnvlKBf+QCvjZ+xoQ/g==" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="completed keyexchange" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="completed challenge" Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:37:06 spla-repro volumio[15606]: info: Initializing connection to go-librespot Websocket Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=debug msg="new websocket client" Sep 01 05:37:06 spla-repro volumio[15606]: info: Connection to go-librespot Websocket established Sep 01 05:37:06 spla-repro go-librespot[16317]: time="2026-09-01T05:37:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:37:06 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:37:06 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:37:06 spla-repro volumio[15606]: info: Connection to go-librespot Websocket closed Sep 01 05:37:09 spla-repro volumio[15606]: info: Getting Spotify volume Sep 01 05:37:09 spla-repro volumio[15606]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 05:37:09 spla-repro volumio[15606]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 05:37:09 spla-repro volumio[15606]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 05:37:09 spla-repro volumio[15606]: errno: -111, Sep 01 05:37:09 spla-repro volumio[15606]: code: 'ECONNREFUSED', Sep 01 05:37:09 spla-repro volumio[15606]: syscall: 'connect', Sep 01 05:37:09 spla-repro volumio[15606]: address: '127.0.0.1', Sep 01 05:37:09 spla-repro volumio[15606]: port: 9879, Sep 01 05:37:09 spla-repro volumio[15606]: response: undefined Sep 01 05:37:09 spla-repro volumio[15606]: } Sep 01 05:37:09 spla-repro volumio[15606]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 05:37:09 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Sep 01 05:37:09 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:37:09 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 05:37:09 spla-repro go-librespot[16337]: go-librespot daemon starting... Sep 01 05:37:09 spla-repro systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Sep 01 05:37:09 spla-repro go-librespot[16339]: time="2026-09-01T05:37:09+02:00" level=info msg="running go-librespot 0.7.1" Sep 01 05:37:09 spla-repro go-librespot[16339]: time="2026-09-01T05:37:09+02:00" level=debug msg="app state loaded" Sep 01 05:37:09 spla-repro go-librespot[16339]: time="2026-09-01T05:37:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=info msg="zeroconf server listening on port 43081" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=debug msg="obtained new client token: AAFhnH7O0nBhTszlQvgizQgKNQ272nyio8FEewmeM7td6ppeSX9mxL1tTMSGBmoWeJ7vbI5hDFV+HbODLqRQk+RF/kID2hDYdRRR7hPozzmoNMUe21Ewj/tukQ4kUtjJFNh3trKkTmhXHn7GMy9I9fuwdpDU3/bwVa7YXTF+MVpHLvFDD02mOnnYitkymMxO8Rtjx5hqZOT1/fuBsUutDmGQqGlGa521C+KQd0xJiQR6MYm5oN2LjnnD5Q==" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=debug msg="completed keyexchange" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=debug msg="completed challenge" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=info msg="authenticated AP" username="4b*********************lb" Sep 01 05:37:10 spla-repro go-librespot[16339]: time="2026-09-01T05:37:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 05:37:10 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:37:10 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 05:37:10 spla-repro sudo[16371]: volumio : unable to resolve host spla-repro: System error Sep 01 05:37:10 spla-repro sudo[16371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 05:36' Sep 01 05:37:10 spla-repro sudo[16371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"