Oct 11 23:08:01 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 23:08:01 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 23:08:01 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 23:08:01 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 11 23:08:01 spla-repro volumio-remote-updater[719]: Test mode disabled Oct 11 23:08:01 spla-repro volumio-remote-updater[719]: Alpha mode disabled Oct 11 23:08:01 spla-repro volumio-remote-updater[719]: Alpha legacy test mode disabled Oct 11 23:08:01 spla-repro volumio[10787]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 11 23:08:01 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 23:08:02 spla-repro nmbd[1030]: [2026/10/11 23:08:02.140664, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 11 23:08:02 spla-repro nmbd[1030]: register_name_response: Answer name <00> differs from question name <00>. Oct 11 23:08:02 spla-repro nmbd[1030]: [2026/10/11 23:08:02.145270, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 11 23:08:02 spla-repro nmbd[1030]: register_name_response: Answer name <03> differs from question name <03>. Oct 11 23:08:02 spla-repro nmbd[1030]: [2026/10/11 23:08:02.145623, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 11 23:08:02 spla-repro nmbd[1030]: register_name_response: Answer name <20> differs from question name <20>. Oct 11 23:08:02 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:02 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:02 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 11 23:08:02 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:02 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:02 spla-repro go-librespot[11262]: go-librespot daemon starting... Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+02:00" level=debug msg="app state loaded" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08: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-gae2.spotify.com:80]" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+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]" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+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]" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+02:00" level=info msg="zeroconf server listening on port 32901" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:02 spla-repro go-librespot[11263]: time="2026-10-11T23:08:02+02:00" level=debug msg="obtained new client token: AAE8GoLqB2cINVqJmrWy5wahrV43CXTVSbdbKfiv71QQztEUNPmQLDbO3QqnrZJaT1sBCwLrRZpWqtcqsI1akSFQOflLH7tp9nn/mtsQ7n9k9tk35r9rycSaKlh8abanWLZ1bxZyhKO/iSjCvm0fgKhf9hGcY6AsuBSkE8jcDlH9WZw9zCeERkSJIYQUnp7hd8Tadjgp4ttNdOfS+qlAkxUcOGp7vunuW954MEuhmCnrZ2wMM5Ws1y3uWg==" Oct 11 23:08:03 spla-repro go-librespot[11263]: time="2026-10-11T23:08:03+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:03 spla-repro go-librespot[11263]: time="2026-10-11T23:08:03+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:03 spla-repro go-librespot[11263]: time="2026-10-11T23:08:03+02:00" level=debug msg="completed challenge" Oct 11 23:08:03 spla-repro go-librespot[11263]: time="2026-10-11T23:08:03+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:03 spla-repro go-librespot[11263]: time="2026-10-11T23:08:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:03 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:03 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:03 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 23:08:03 spla-repro volumio[10787]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 58 Oct 11 23:08:03 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:03 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:04 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:04 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:04 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:08:04 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:08:05 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:05 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:06 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 11 23:08:06 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:06 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:06 spla-repro go-librespot[11274]: go-librespot daemon starting... Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08:06+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08:06+02:00" level=debug msg="app state loaded" Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08:06+02:00" level=info msg="zeroconf server listening on port 46761" Oct 11 23:08:06 spla-repro go-librespot[11275]: time="2026-10-11T23:08:06+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:07 spla-repro go-librespot[11275]: time="2026-10-11T23:08:07+02:00" level=debug msg="obtained new client token: AAG9H0Y7/sZT/4HgpRduzaSvSQifYkwLyttk5Pg3+tgAx00caBXwvP9I5gVyvN+ipvAXlEen0oLHn6/o49MuxuFFIq6I1qEj6aPOCTMTr8Rkx58p7czOyNsrau2X8uTrma431ql6iLbuZ3OJ+abEiPHeCU/vv2amIJF44/wW8mzg6dsfhEdnJvLIhIKxAkSlbtyzrFnIrIJdvBiHTOtMFqJIYOZleaw+6Va86k75UExI5O3NBtwEbvzI0g==" Oct 11 23:08:07 spla-repro go-librespot[11275]: time="2026-10-11T23:08:07+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:07 spla-repro go-librespot[11275]: time="2026-10-11T23:08:07+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:07 spla-repro go-librespot[11275]: time="2026-10-11T23:08:07+02:00" level=debug msg="completed challenge" Oct 11 23:08:07 spla-repro go-librespot[11275]: time="2026-10-11T23:08:07+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:07 spla-repro go-librespot[11275]: time="2026-10-11T23:08:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:07 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:07 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 23:08:08 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 23:08:08 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:08 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:10 spla-repro volumio[10787]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 11 23:08:10 spla-repro volumio[10787]: info: Completed starting MyVolumio Plugin Oct 11 23:08:10 spla-repro volumio[10787]: [Metrics] CommandRouter: 52s 466.66ms Oct 11 23:08:10 spla-repro volumio[10787]: info: CoreCommandRouter::volumiosetStartupVolume Oct 11 23:08:10 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 23:08:10 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 23:08:10 spla-repro volumio[10787]: info: CoreCommandRouter::Close All Modals sent Oct 11 23:08:10 spla-repro volumio[10787]: info: CoreCommandRouter::Close All Modals sent Oct 11 23:08:10 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 11 23:08:10 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:10 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:10 spla-repro go-librespot[11285]: go-librespot daemon starting... Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+02:00" level=debug msg="app state loaded" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+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]" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+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]" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+02:00" level=info msg="zeroconf server listening on port 32929" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:10 spla-repro go-librespot[11286]: time="2026-10-11T23:08:10+02:00" level=debug msg="obtained new client token: AAHLI+43W8+VIHHQddGfrwXaYXvndCzyMLdjOAxp9XwgRtywvm+1qr4JYFGW8dxIZgfYsVnrn3VI3iUrEyrg8EFCeNctRIvFsZyhORHo+PTBjkZzWOBM/ng9Xif8YGMRSKT3Yscmr/sWD7xqEtIEsHrI3vgNiWBU2pTWvQR5EvFM+PxFWqfj4MpEVqaTLL1J1RXWb5BDLW4VM3Jz27DDSAGiEOmz6BmOR/wcCQAhR0hu63nzj4+P85GonQ==" Oct 11 23:08:11 spla-repro go-librespot[11286]: time="2026-10-11T23:08:11+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:11 spla-repro go-librespot[11286]: time="2026-10-11T23:08:11+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:11 spla-repro go-librespot[11286]: time="2026-10-11T23:08:11+02:00" level=debug msg="completed challenge" Oct 11 23:08:11 spla-repro go-librespot[11286]: time="2026-10-11T23:08:11+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:11 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 11 23:08:11 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 23:08:11 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 11 23:08:11 spla-repro go-librespot[11286]: time="2026-10-11T23:08:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:11 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:11 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:11 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:11 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:14 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:14 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:14 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 11 23:08:14 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:14 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:14 spla-repro go-librespot[11309]: go-librespot daemon starting... Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+02:00" level=debug msg="app state loaded" Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+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]" Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+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]" Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+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]" Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+02:00" level=info msg="zeroconf server listening on port 39135" Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:14 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:14 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:14 spla-repro go-librespot[11310]: time="2026-10-11T23:08:14+02:00" level=debug msg="obtained new client token: AAHGBr1Rv+v7QMnElKcvEixYz0rAsb4iX/kJqqy2ZP7UDNuabfn/9VsJmYT6ZR/8ZrUK1He+Gqgxvm59gWsjywyy/kA2D4nrwX1565Kj/h5iRW/X4uaPZpLXKVT6eE+ZeCgJ7b2s+zz7hxwnjeN+cXXzG9Q/3enx0S4fTQJgo1Kr5yR1740RRlJE889qtnWslQNyB8ZF8pjwBr8Y8k/H/tHbgjBOyHGtyLIREDrJSvboN56A9KLzdLjMbw==" Oct 11 23:08:15 spla-repro go-librespot[11310]: time="2026-10-11T23:08:15+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:15 spla-repro go-librespot[11310]: time="2026-10-11T23:08:15+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:15 spla-repro go-librespot[11310]: time="2026-10-11T23:08:15+02:00" level=debug msg="completed challenge" Oct 11 23:08:15 spla-repro go-librespot[11310]: time="2026-10-11T23:08:15+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:15 spla-repro go-librespot[11310]: time="2026-10-11T23:08:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:15 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:15 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:17 spla-repro volumio[10787]: info: BOOT COMPLETED Oct 11 23:08:17 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:17 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:18 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 11 23:08:18 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:18 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:18 spla-repro go-librespot[11320]: go-librespot daemon starting... Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+02:00" level=debug msg="app state loaded" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+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]" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+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]" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+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]" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+02:00" level=info msg="zeroconf server listening on port 34417" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:18 spla-repro go-librespot[11321]: time="2026-10-11T23:08:18+02:00" level=debug msg="obtained new client token: AAFjOngLazWuhh4AGgJDcWLVCFqx3TUsCRAbLUvKTFLdz0NhdzqROsGPjlc3DJ/etuD/NNG36CuwAxaF61XgK07qKOJpK8wk4jE6ut0Ig94qf6gjIJZ/36D6VO297ayBxnz4bq4JH2lsinKagjn73YYMzWl2eP5Cdy2AqeKf2yszfEuXQGYBPmTbd6/reqpJ8CjqXp2Pxg7QS0ZwoLysiMR7n4B/wmSmGFCx5YzdggnLulYWb0uQjIGwvQ==" Oct 11 23:08:19 spla-repro go-librespot[11321]: time="2026-10-11T23:08:19+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 11 23:08:19 spla-repro go-librespot[11321]: time="2026-10-11T23:08:19+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 11 23:08:19 spla-repro go-librespot[11321]: time="2026-10-11T23:08:19+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:19 spla-repro go-librespot[11321]: time="2026-10-11T23:08:19+02:00" level=debug msg="completed challenge" Oct 11 23:08:19 spla-repro go-librespot[11321]: time="2026-10-11T23:08:19+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:19 spla-repro go-librespot[11321]: time="2026-10-11T23:08:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:19 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:19 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:20 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:20 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:22 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 11 23:08:22 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:22 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:22 spla-repro go-librespot[11344]: go-librespot daemon starting... Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+02:00" level=debug msg="app state loaded" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+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]" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+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]" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+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]" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+02:00" level=info msg="zeroconf server listening on port 43971" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+02:00" level=debug msg="obtained new client token: AAF+HwyV4NaPsysOyG0lPCQzehO6YztzswlBcJGFN0HOjjllWzp2OwwOkTUYIH48926uxQ/vLa2IdMKyZBFMW0xqqRnNglpvkAVoWPKo8aLo5S8RMokIveX4brKSqt1PlZIIXwUa9nIOI489a6X7ejVJe4iIhKzB6adYmoB2GDamnqTSVUOuYjOm3zX64MzbM96OpAC8+wYA86a7fjZYe8GrdI5EIyMBK3LJc91TeMOO7ekIla85Ph/1FA==" Oct 11 23:08:22 spla-repro go-librespot[11345]: time="2026-10-11T23:08:22+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:23 spla-repro go-librespot[11345]: time="2026-10-11T23:08:23+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:23 spla-repro go-librespot[11345]: time="2026-10-11T23:08:23+02:00" level=debug msg="completed challenge" Oct 11 23:08:23 spla-repro go-librespot[11345]: time="2026-10-11T23:08:23+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:23 spla-repro go-librespot[11345]: time="2026-10-11T23:08:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:23 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:23 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:23 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:23 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:24 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:24 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:24 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:08:24 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:08:26 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:26 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:26 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 11 23:08:26 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:26 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:26 spla-repro go-librespot[11354]: go-librespot daemon starting... Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+02:00" level=debug msg="app state loaded" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+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]" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+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]" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+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]" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+02:00" level=info msg="zeroconf server listening on port 34893" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:26 spla-repro go-librespot[11355]: time="2026-10-11T23:08:26+02:00" level=debug msg="obtained new client token: AAHHKbzgdKprypu1auNLHY2iMwXCw9PiEWv3Im5HRV5F4xlyoJW7lTZ6dl3m2B4bOGC7ngtPYhSTyHJcfGoNpWwDVcDu4IkrSOw0J51wUnxcYraoUtOp7KDIEi87p0jFpSD02eJL8k9kdXHSFCGUGwwC+33uuBsBAKju+PqmOKtZsjcWgMqXhzTs/jdxrv6q6i8V5WrlIIlyBr2trreU7skv/eGJYAuUp/ifJWCV8WwutPAyiChEQC0pLw==" Oct 11 23:08:27 spla-repro go-librespot[11355]: time="2026-10-11T23:08:27+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:27 spla-repro go-librespot[11355]: time="2026-10-11T23:08:27+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:27 spla-repro go-librespot[11355]: time="2026-10-11T23:08:27+02:00" level=debug msg="completed challenge" Oct 11 23:08:27 spla-repro go-librespot[11355]: time="2026-10-11T23:08:27+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:27 spla-repro go-librespot[11355]: time="2026-10-11T23:08:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:27 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:27 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:29 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:29 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:30 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 11 23:08:30 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:30 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:30 spla-repro go-librespot[11364]: go-librespot daemon starting... Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+02:00" level=debug msg="app state loaded" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+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]" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+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]" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+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]" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+02:00" level=info msg="zeroconf server listening on port 41065" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+02:00" level=debug msg="obtained new client token: AAE3YL5uoFrlQ1cqvEFYjbLHxHb5acjBt+68Aa1QOyIqe0CN+bZcaAs46djgzecUSIaBOuMQz04DVboYMubmmTaAyD++jpA/6OCh6ePmSYUUeL5HSwp7CwTvTKMIfxkBR74DnNMSXkPXyIKXGmD1vUbe/EpY+YLnTh/zf1DtUXKw3ZFXY481Zw8odwB4kERnoH2aW647t8PvD46lr1dftdxPntAttUrGzKzutK+Yrj+8sRkFxcPejaRjYw==" Oct 11 23:08:30 spla-repro go-librespot[11365]: time="2026-10-11T23:08:30+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:31 spla-repro go-librespot[11365]: time="2026-10-11T23:08:31+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:31 spla-repro go-librespot[11365]: time="2026-10-11T23:08:31+02:00" level=debug msg="completed challenge" Oct 11 23:08:31 spla-repro go-librespot[11365]: time="2026-10-11T23:08:31+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:31 spla-repro go-librespot[11365]: time="2026-10-11T23:08:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:31 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:31 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:32 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:32 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:34 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 11 23:08:34 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:34 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:34 spla-repro go-librespot[11391]: go-librespot daemon starting... Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+02:00" level=debug msg="app state loaded" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+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]" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+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]" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+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]" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+02:00" level=info msg="zeroconf server listening on port 35767" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+02:00" level=debug msg="obtained new client token: AAHmSwJYR6lV36NVAcisNYTVf+/3pfo/pRDcJVseZSNyfsiTXmF8nJNWwtCk1qznC09FzSCLr6GtSuMCKV/i37hbgGXunrXsOjihrSUQMZ63V+rKJ9ZkDU67iCVpR2og97GFzHG7hDxIlqqA11Jqaacu+wo5KEYy1YtYQ0lxnO709kevOIAgGYXtUG3X5QWuURAu7VnR07Cu2eR4BoBxcFs9/n0L31lh3RhDl3Xe0M0PJsOGb2PGTYL5Aw==" Oct 11 23:08:34 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:34 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:34 spla-repro go-librespot[11392]: time="2026-10-11T23:08:34+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:35 spla-repro go-librespot[11392]: time="2026-10-11T23:08:35+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:35 spla-repro go-librespot[11392]: time="2026-10-11T23:08:35+02:00" level=debug msg="completed challenge" Oct 11 23:08:35 spla-repro go-librespot[11392]: time="2026-10-11T23:08:35+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:35 spla-repro go-librespot[11392]: time="2026-10-11T23:08:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:35 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:35 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:35 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:35 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:38 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 11 23:08:38 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:38 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:38 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:38 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:38 spla-repro go-librespot[11401]: go-librespot daemon starting... Oct 11 23:08:38 spla-repro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 11 23:08:38 spla-repro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 11 23:08:38 spla-repro systemd[1]: welcome.service: Deactivated successfully. Oct 11 23:08:38 spla-repro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 11 23:08:38 spla-repro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 11 23:08:38 spla-repro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+02:00" level=debug msg="app state loaded" Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:38 spla-repro welcome[11410]: Resolved ip:[1] 192.168.200.203 Oct 11 23:08:38 spla-repro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 11 23:08:38 spla-repro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+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]" Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+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]" Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+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]" Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+02:00" level=info msg="zeroconf server listening on port 46181" Oct 11 23:08:38 spla-repro go-librespot[11404]: time="2026-10-11T23:08:38+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:39 spla-repro go-librespot[11404]: time="2026-10-11T23:08:39+02:00" level=debug msg="obtained new client token: AAEqaQ4tqrTjjNspCA81msTV6GRsl9jCozOQ+n4tvLNZlssjbiuAdSmce4r+n1nd6PDqZ2rZ0FKD1FuALUZ3iuKvukAIeFeI0DhUh4+KUIINmP6dMKVqTOGFG7gFAdK8/PyUfpuasQlHvlEoy7GD8Vvryrg0GwqI08hDO4EtgZ1HCvz/eaL11L80o5EX34oNb7PQmrRRC8eqAKkvb64AOc+yEwj94Q95mfVAZcsFJ4EDoxI9RI4sD8Q=" Oct 11 23:08:39 spla-repro go-librespot[11404]: time="2026-10-11T23:08:39+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:39 spla-repro go-librespot[11404]: time="2026-10-11T23:08:39+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:39 spla-repro go-librespot[11404]: time="2026-10-11T23:08:39+02:00" level=debug msg="completed challenge" Oct 11 23:08:39 spla-repro go-librespot[11404]: time="2026-10-11T23:08:39+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:39 spla-repro go-librespot[11404]: time="2026-10-11T23:08:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:39 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:39 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:39 spla-repro volumio[10787]: info: Received Get System Info Oct 11 23:08:39 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:08:39 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:08:39 spla-repro volumio[10787]: info: Discovery: Getting this device information Oct 11 23:08:39 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:39 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:39 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:08:39 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 23:08:39 spla-repro volumio[10787]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 23:08:40 spla-repro volumio5-onboarding[6116]: time=2026-10-11T23:08:40.502+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 11 23:08:41 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:41 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:42 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 11 23:08:42 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:42 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:42 spla-repro go-librespot[11449]: go-librespot daemon starting... Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+02:00" level=debug msg="app state loaded" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+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]" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+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]" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+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]" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+02:00" level=info msg="zeroconf server listening on port 34801" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+02:00" level=debug msg="obtained new client token: AAGagCqnAaSw0DDA5FZXBpdCcPElpW0O1siS+f6bmIjDCQH25alSPTxTY9hmhV/iMVl9u9brFHpB+Lshr1tcXaAOp2VzI6cMDyPNS85Ahz40rbRbnMtyega94WfpttyF+qRaY4w19dOxTZJKYPjhTXCS3qvTlU8x8A3Su8Lre/PT0fVJ+6+UpAzzBZO8/o1sf2RWSQmuoD+c9d78TC0dl1d7W2xADvzLnY+Es50B4bc4ZiewZZL2u4xvMw==" Oct 11 23:08:42 spla-repro go-librespot[11450]: time="2026-10-11T23:08:42+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:43 spla-repro go-librespot[11450]: time="2026-10-11T23:08:43+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:43 spla-repro go-librespot[11450]: time="2026-10-11T23:08:43+02:00" level=debug msg="completed challenge" Oct 11 23:08:43 spla-repro go-librespot[11450]: time="2026-10-11T23:08:43+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:43 spla-repro go-librespot[11450]: time="2026-10-11T23:08:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:43 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:43 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:44 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:44 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:44 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:44 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:44 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:08:44 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:08:46 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 11 23:08:46 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:46 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:46 spla-repro go-librespot[11459]: go-librespot daemon starting... Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+02:00" level=debug msg="app state loaded" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+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]" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+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]" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+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]" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+02:00" level=info msg="zeroconf server listening on port 42963" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+02:00" level=debug msg="obtained new client token: AAHFIgRHHuaboJCPAIcU4a+Ww/FZ6HQ2XaUk3mJ2W0H9GdCPnpizYFe5/PcDX5oAYSz/JpRHb+DhY2wdiswjFcHVhboWv1ZziX/Uc5cU+mc5Qiz8wyp5J/1vsFdGesRNbwYw9o8v+/Iyml6lBEdsCoOcvmFYj6kWxri03Y7IJLCc89Iu0JFrZ43g8MasFDNaMsVwSNLK3lHMLWWJtF6ZxuUxImBeRqCnXaTaX8cSlyQPpzOcdrl5jjimQg==" Oct 11 23:08:46 spla-repro go-librespot[11460]: time="2026-10-11T23:08:46+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:47 spla-repro go-librespot[11460]: time="2026-10-11T23:08:47+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:47 spla-repro go-librespot[11460]: time="2026-10-11T23:08:47+02:00" level=debug msg="completed challenge" Oct 11 23:08:47 spla-repro go-librespot[11460]: time="2026-10-11T23:08:47+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:47 spla-repro go-librespot[11460]: time="2026-10-11T23:08:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:47 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:47 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:47 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:47 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:50 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 11 23:08:50 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:50 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:50 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:50 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:50 spla-repro go-librespot[11471]: go-librespot daemon starting... Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+02:00" level=debug msg="app state loaded" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+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]" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+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]" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+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]" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+02:00" level=info msg="zeroconf server listening on port 37291" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:50 spla-repro go-librespot[11472]: time="2026-10-11T23:08:50+02:00" level=debug msg="obtained new client token: AAF0pwsPs0B1TyxbnqrcXT2AwNDC8bHs0tAU0XselBgCJc+agZuEwpQZgbSRaB5EoXKN87I40NCKAYOWzlwuzKxjh9C/4PnqrzUrgP0HaxGQ66MJfzm2BDaX/fB53b/FS1F6sN86GSgDFyCKLMNnXSM7Zk+8EGYz/FLLJH5fJVvtkpE2J1J9YTWAefspD//C3FN7iCSDuwnrtP/a65dCifh/lQmqIK9GeIef8v+7UHdYxrE5nTwebulKfA==" Oct 11 23:08:51 spla-repro go-librespot[11472]: time="2026-10-11T23:08:51+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:51 spla-repro go-librespot[11472]: time="2026-10-11T23:08:51+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:51 spla-repro go-librespot[11472]: time="2026-10-11T23:08:51+02:00" level=debug msg="completed challenge" Oct 11 23:08:51 spla-repro go-librespot[11472]: time="2026-10-11T23:08:51+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:51 spla-repro go-librespot[11472]: time="2026-10-11T23:08:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:51 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:51 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:53 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:53 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:54 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 11 23:08:54 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:54 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:54 spla-repro go-librespot[11496]: go-librespot daemon starting... Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+02:00" level=debug msg="app state loaded" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08: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-gew4.spotify.com:80]" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+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]" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+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]" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+02:00" level=info msg="zeroconf server listening on port 35779" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+02:00" level=debug msg="obtained new client token: AAG7+RFqZZ6aOaw1ejS8xe52J+AtzeWg6JfrAwDJOIbzU6wczJ4XByvLEtGKyVYbMN0PkmrxeK83xNS5fysgVBEM+6h6u8/U7KTSATh47pliZID23n+6k6C/Aq9uAo1VQ0Bznu/ANyENO8OlOS8j/c4zZEdE1FjWM+o/m4SNWhAy6nHTbm/l4IAzZXa0yJDu116CFXV9v6uAFCVOTduluEurt+9gmSxM6eAUjCH63jmXw1saNc6flv+Gsg==" Oct 11 23:08:54 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:08:54 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:08:54 spla-repro go-librespot[11497]: time="2026-10-11T23:08:54+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 11 23:08:55 spla-repro go-librespot[11497]: time="2026-10-11T23:08:55+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 11 23:08:55 spla-repro go-librespot[11497]: time="2026-10-11T23:08:55+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:55 spla-repro go-librespot[11497]: time="2026-10-11T23:08:55+02:00" level=debug msg="completed challenge" Oct 11 23:08:55 spla-repro go-librespot[11497]: time="2026-10-11T23:08:55+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:55 spla-repro go-librespot[11497]: time="2026-10-11T23:08:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:55 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:55 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:56 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:56 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:08:58 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 11 23:08:58 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:58 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:08:58 spla-repro go-librespot[11507]: go-librespot daemon starting... Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=debug msg="app state loaded" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08: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-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=info msg="zeroconf server listening on port 41373" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=debug msg="obtained new client token: AAEitjC5PZyFRqdZDdGKu1u6Y3phP39NrFxQvVA4Ba0iiQLjLnZX41moeM7eLMrzSSGQjxjCDoxJA8r7ZITqTOyUIQbt9AFnZ9CksR1loRtSOUo4zKpuLSYWt83ROtILDIDG7F/NejIkQrJv08m9KAjnVotywMMO/cqg6am+5jdCbAWw0wkoFUXPyY7rwgaAKRS5bCj2cNtOLhQhbjY4asCGDBwFRASIpgBkVoFWO4t7cjMFEyiLJMgzjw==" Oct 11 23:08:58 spla-repro go-librespot[11508]: time="2026-10-11T23:08:58+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:08:59 spla-repro go-librespot[11508]: time="2026-10-11T23:08:59+02:00" level=debug msg="completed keyexchange" Oct 11 23:08:59 spla-repro go-librespot[11508]: time="2026-10-11T23:08:59+02:00" level=debug msg="completed challenge" Oct 11 23:08:59 spla-repro go-librespot[11508]: time="2026-10-11T23:08:59+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:08:59 spla-repro go-librespot[11508]: time="2026-10-11T23:08:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:08:59 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:08:59 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:08:59 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:08:59 spla-repro volumio[10787]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:09:02 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 11 23:09:02 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:09:02 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:09:02 spla-repro go-librespot[11532]: go-librespot daemon starting... Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=debug msg="app state loaded" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:09:02 spla-repro volumio[10787]: info: Initializing connection to go-librespot Websocket Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=debug msg="new websocket client" Oct 11 23:09:02 spla-repro volumio[10787]: info: Connection to go-librespot Websocket established Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+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]" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+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]" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=info msg="zeroconf server listening on port 36791" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=debug msg="obtained new client token: AAHH05VKeQJP92/UgvHAjrgJfPae4+cJzJYK7xqL92hvMoAJRkcG9VbjWIh6uRZHooKPbeMT+vch8+ghMCXqUiVlfq/BgghxygufmjcthGu07wnNgkN4wvR2F3QdlgQuIPeSw+G8hjszpdQXTEPq7kUhfMa0VmASAG1wTSM4uKVQX8jFQgZrOUemi7LvQvBQXrrvE9bX3Mq14W7u5px3IYtg11bvPffUa/TNidn4iAJULtDxIgVe0O9UqQ==" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=debug msg="completed keyexchange" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=debug msg="completed challenge" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 11 23:09:02 spla-repro go-librespot[11533]: time="2026-10-11T23:09:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 23:09:02 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 23:09:02 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 23:09:02 spla-repro volumio[10787]: info: Connection to go-librespot Websocket closed Oct 11 23:09:04 spla-repro volumio[10787]: info: CoreCommandRouter::volumioGetState Oct 11 23:09:04 spla-repro volumio[10787]: info: CorePlayQueue::getTrack 0 Oct 11 23:09:04 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:09:04 spla-repro volumio[10787]: info: Listing playlists Oct 11 23:09:05 spla-repro volumio[10787]: info: Getting Spotify volume Oct 11 23:09:05 spla-repro volumio[10787]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:09:05 spla-repro volumio[10787]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 23:09:05 spla-repro volumio[10787]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 11 23:09:05 spla-repro volumio[10787]: errno: -111, Oct 11 23:09:05 spla-repro volumio[10787]: code: 'ECONNREFUSED', Oct 11 23:09:05 spla-repro volumio[10787]: syscall: 'connect', Oct 11 23:09:05 spla-repro volumio[10787]: address: '127.0.0.1', Oct 11 23:09:05 spla-repro volumio[10787]: port: 9879, Oct 11 23:09:05 spla-repro volumio[10787]: response: undefined Oct 11 23:09:05 spla-repro volumio[10787]: } Oct 11 23:09:05 spla-repro volumio[10787]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:09:06 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 11 23:09:06 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:09:06 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 23:09:06 spla-repro go-librespot[11556]: go-librespot daemon starting... Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09:06+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09:06+02:00" level=debug msg="app state loaded" Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 23:09:06 spla-repro sudo[11567]: volumio : unable to resolve host spla-repro: System error Oct 11 23:09:06 spla-repro sudo[11567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 23:08' Oct 11 23:09:06 spla-repro sudo[11567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09: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]" Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09: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]" Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09: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]" Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09:06+02:00" level=info msg="zeroconf server listening on port 40029" Oct 11 23:09:06 spla-repro go-librespot[11557]: time="2026-10-11T23:09:06+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" 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"