Feb 25 01:59:00 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:00 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:00 volumio sudo[9516]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 25 01:59:00 volumio sudo[9516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 25 01:59:00 volumio sudo[9518]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 25 01:59:00 volumio sudo[9518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 25 01:59:00 volumio sudo[9520]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 25 01:59:00 volumio sudo[9520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 25 01:59:00 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Feb 25 01:59:00 volumio systemd[1]: mpd_monitor.service: Deactivated successfully.
Feb 25 01:59:00 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Feb 25 01:59:01 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Feb 25 01:59:01 volumio sudo[9518]: pam_unix(sudo:session): session closed for user root
Feb 25 01:59:01 volumio sudo[9520]: pam_unix(sudo:session): session closed for user root
Feb 25 01:59:01 volumio sudo[9516]: pam_unix(sudo:session): session closed for user root
Feb 25 01:59:01 volumio mpd_monitor.sh[9524]: MPD Monitor Service: Starting MPD Monitor Service
Feb 25 01:59:01 volumio volumio[9057]: info: Successfully started MPD Monitor
Feb 25 01:59:01 volumio volumio[9057]: info: Successfully started MPD Monitor
Feb 25 01:59:01 volumio volumio[9057]: info: Successfully started MPD Monitor
Feb 25 01:59:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48.
Feb 25 01:59:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:02 volumio go-librespot[9529]: go-librespot daemon starting...
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=debug msg="app state loaded"
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:02 volumio go-librespot[9530]: time="2026-02-25T01:59:02-06:00" level=info msg="zeroconf server listening on port 36489"
Feb 25 01:59:03 volumio go-librespot[9530]: time="2026-02-25T01:59:03-06:00" level=debug msg="obtained new client token: AABzaoXutIrkkALbu8wS7CSOSKEKQLwtjSuULs7kQXiqiy+K8JHkeGHAzy73U2csvLgmBOSr39b0MNgQYcI2LL/wKZlBifQ4ZUVPpgS5XwtciRvWzQvz7AQaMQVobJJoOF6E3ZzLRXx4uWHS21Y9jMJFlrgBoaGfZKxbXUWlahXbVo89pscLMZd70kGKtlUBWTOF7kJ3pMm3vIH/Xyno9dNU9iRD7E5rJ1O/s9uH9eWb61okDYenW9c="
Feb 25 01:59:03 volumio go-librespot[9530]: time="2026-02-25T01:59:03-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 25 01:59:03 volumio go-librespot[9530]: time="2026-02-25T01:59:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 25 01:59:03 volumio go-librespot[9530]: time="2026-02-25T01:59:03-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:03 volumio go-librespot[9530]: time="2026-02-25T01:59:03-06:00" level=debug msg="completed challenge"
Feb 25 01:59:03 volumio go-librespot[9530]: time="2026-02-25T01:59:03-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:03 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:04 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49.
Feb 25 01:59:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:06 volumio go-librespot[9551]: go-librespot daemon starting...
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="app state loaded"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=info msg="zeroconf server listening on port 42377"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="obtained new client token: AABZ6SvkvS8g4DDgiC7feEH8CGManMhdAnb/7OghjYw4uedNaDFjaonp3HLBCkMLQCyRsE+qCOicddmy/sUzNIC3yhmRcEz6byMM9U7YvTknQEUJu3gX/iYU9XEODKXPKZZFwT3XNThhgohFgp/IqM3IniZsjTF05gqkBiNe1V5yKr7aFXql6yppm73FnY8pWDEv+d/h30cog9KFilJOI0JC3BRyZ04V8VwRCMG3qtXMasAdYSigXHvNFw=="
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=debug msg="completed challenge"
Feb 25 01:59:06 volumio go-librespot[9552]: time="2026-02-25T01:59:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:07 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:07 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 25 01:59:07 volumio volumio[9057]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 25 01:59:07 volumio volumio[9057]: info: Adding plugin multiroom to MyMusic Plugins
Feb 25 01:59:07 volumio volumio[9057]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 25 01:59:07 volumio volumio[9057]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 25 01:59:07 volumio volumio[9057]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 25 01:59:07 volumio volumio[9057]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 25 01:59:07 volumio volumio[9057]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 25 01:59:07 volumio volumio[9057]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 25 01:59:08 volumio volumio[9057]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 25 01:59:08 volumio volumio[9057]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 25 01:59:08 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 01:59:08 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 01:59:08 volumio volumio[9057]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 25 01:59:08 volumio volumio[9057]: info: MyVolumio login type: Token
Feb 25 01:59:08 volumio volumio[9057]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 25 01:59:08 volumio volumio[9057]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 25 01:59:09 volumio volumio[9057]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 25 01:59:09 volumio volumio[9057]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 25 01:59:09 volumio volumio[9057]: info: Streaming services startup
Feb 25 01:59:09 volumio volumio[9057]: info: Starting Streaming Daemon
Feb 25 01:59:09 volumio sudo[9561]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 25 01:59:09 volumio sudo[9561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 25 01:59:09 volumio volumio[9057]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 25 01:59:09 volumio sudo[9561]: pam_unix(sudo:session): session closed for user root
Feb 25 01:59:09 volumio volumio[9057]: error: Cannot start Volumio Streaming Daemon
Feb 25 01:59:09 volumio volumio[9057]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 25 01:59:09 volumio volumio[9057]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 25 01:59:09 volumio volumio[9057]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required.
Feb 25 01:59:10 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:10 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50.
Feb 25 01:59:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:10 volumio go-librespot[9568]: go-librespot daemon starting...
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="app state loaded"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=info msg="zeroconf server listening on port 33637"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="obtained new client token: AADWAmT7LYifmJWrgyPU4D0FAE6AmSHrfAC0i+URTPsPtAV6OaJkR4TPRAcp1PMWc5ZFb9Av31FzDmgchFfrOiMSU8Y+A0/ogPEDuywEG4gKqBi3gb1eC6oH3RNRbYttGf8VPWXJnZpyQdqEkhtlwZv3GlDM72YEcM2YBJVdov5Ol66uD5xnsoxMa12medg9NzKfYjhDPQTdecS4ZECPSBS+iTy+X0L1e9lmh0jMZyu3jjr1gBz+qTB6uA=="
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=debug msg="completed challenge"
Feb 25 01:59:10 volumio go-librespot[9569]: time="2026-02-25T01:59:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:13 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:13 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51.
Feb 25 01:59:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:13 volumio go-librespot[9576]: go-librespot daemon starting...
Feb 25 01:59:13 volumio go-librespot[9577]: time="2026-02-25T01:59:13-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:13 volumio go-librespot[9577]: time="2026-02-25T01:59:13-06:00" level=debug msg="app state loaded"
Feb 25 01:59:13 volumio go-librespot[9577]: time="2026-02-25T01:59:13-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:13 volumio go-librespot[9577]: time="2026-02-25T01:59:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=info msg="zeroconf server listening on port 45239"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=debug msg="obtained new client token: AACrZRvsxDVXXQRUWvZ5HPNPQmoyDHf87A8Ti5C9DoQlYU4jEqaiANro8CW6+A8sHBUAmX/LwJp5MwX1W8af/EYO4H+a2gPu+jiLHIMg2IOt7Bck1iRQm/eMKPTtSCqmRtkN56QbFqbw5SzNWu+4t2myjLoujyklSa9lobQe2wuNf5hHXqmPglmWc6uXMZiHYuvT2jUJrrQXbUViTqzPmCpeWQeKRhnDLWo6NX8r5iUP5X9TAS8XiEngeQ=="
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=debug msg="completed challenge"
Feb 25 01:59:14 volumio go-librespot[9577]: time="2026-02-25T01:59:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:16 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:16 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52.
Feb 25 01:59:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:17 volumio go-librespot[9598]: go-librespot daemon starting...
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=debug msg="app state loaded"
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:17 volumio go-librespot[9599]: time="2026-02-25T01:59:17-06:00" level=info msg="zeroconf server listening on port 43607"
Feb 25 01:59:18 volumio go-librespot[9599]: time="2026-02-25T01:59:18-06:00" level=debug msg="obtained new client token: AADMYHcsk+khUSWZUM2uU/gSpwy8j3Wv7PKDDKXoo8j0oKRJKcKdpZNajGL9GYXRt0BBjbODNewEdBZJQe/udh9CpqwCHfXJNBfODSwM22ULePrQCv58JfqAhayCNY5Ykd0ZdX8PME5oooZOO5KWlT/tOkSyv0W5U+YkcHibU270ylssb2IgkeLwcfylN16GfazyrM3KeU8rfr1VxusY6QBtNDb8xPtLHl95uMHW/cKWdY9Y5QMC3FQ="
Feb 25 01:59:18 volumio go-librespot[9599]: time="2026-02-25T01:59:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:18 volumio go-librespot[9599]: time="2026-02-25T01:59:18-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:18 volumio go-librespot[9599]: time="2026-02-25T01:59:18-06:00" level=debug msg="completed challenge"
Feb 25 01:59:18 volumio go-librespot[9599]: time="2026-02-25T01:59:18-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:19 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:19 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:20 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 25 01:59:20 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 01:59:20 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 25 01:59:20 volumio volumio-remote-updater[26837]: Test mode disabled
Feb 25 01:59:20 volumio volumio-remote-updater[26837]: Alpha mode disabled
Feb 25 01:59:20 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled
Feb 25 01:59:20 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 25 01:59:21 volumio volumio[9057]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for CPU utilization spike
\nFix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\nAdd BLE onboarding plugin
\nEnable vc4-kms-v3d for all compatible RPi models
\nUpdate Tidal SDK
\n\n","title":"Update v4.096","updateavailable":true}
Feb 25 01:59:21 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 25 01:59:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53.
Feb 25 01:59:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:21 volumio go-librespot[9613]: go-librespot daemon starting...
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="app state loaded"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=info msg="zeroconf server listening on port 33699"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="obtained new client token: AACLeLaLnL5VKlM8rLhiKKT56CQj5r3NgM08+pAwTW2M2pBXd11M/0vdV1KyWh6Hozc5U0jTsGk7S2LbPJGYAWad+Z96Uu0Go9whPwjk9RA4bUNntHLv/xZbnXMoXp/9mGMtKnVVuTK7RrppcePT7MMDVf93xFIw87ji+yo60rhMjH839jcxAjTPYMstY/Tx/TZhDYdN9CYiXGuphOeM+gWKc97Rx7SCVIW7X8OSujfTJDaWNeBuIF8SZg=="
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=debug msg="completed challenge"
Feb 25 01:59:21 volumio go-librespot[9614]: time="2026-02-25T01:59:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:22 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:22 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:23 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 25 01:59:23 volumio volumio[9057]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Feb 25 01:59:23 volumio volumio[9057]: info: CoreCommandRouter::volumioGetState
Feb 25 01:59:23 volumio volumio[9057]: info: CorePlayQueue::getTrack 0
Feb 25 01:59:25 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:25 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54.
Feb 25 01:59:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:25 volumio go-librespot[9621]: go-librespot daemon starting...
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="app state loaded"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=info msg="zeroconf server listening on port 35741"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="obtained new client token: AAA+Z4wBjvukpmCFKnbYApahqguAOsAfDuup56KPzQg4NlOGP0wYEYMIrJ5AZPzLq57ksbg23Ch0yiSUkChwwtdqEDewiamg89XQUcqsERIh2y2EdH11Ki9UzC9azrbegxaSVIy8Qx8AVxPCbJJQOVhPI4dZi9Ly4r0lMqJLeVyeNNmJ04Qsni43tlljIHoD6bUHbg76uoJ7yKccwZ72SCWl4k34Y1exphzj57uOGfX9lLIwNq+Mi5cHRA=="
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=debug msg="completed challenge"
Feb 25 01:59:25 volumio go-librespot[9622]: time="2026-02-25T01:59:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:28 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:28 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:28 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 01:59:28 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 01:59:28 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 25 01:59:28 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 25 01:59:28 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 25 01:59:28 volumio volumio[9057]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 25 01:59:28 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 25 01:59:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55.
Feb 25 01:59:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:28 volumio go-librespot[9643]: go-librespot daemon starting...
Feb 25 01:59:28 volumio go-librespot[9645]: time="2026-02-25T01:59:28-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:28 volumio go-librespot[9645]: time="2026-02-25T01:59:28-06:00" level=debug msg="app state loaded"
Feb 25 01:59:28 volumio go-librespot[9645]: time="2026-02-25T01:59:28-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:28 volumio go-librespot[9645]: time="2026-02-25T01:59:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:29 volumio volumio[9057]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 25 01:59:29 volumio volumio[9057]: info: Completed starting MyVolumio Plugin
Feb 25 01:59:29 volumio volumio[9057]:  [Metrics]  CommandRouter:  55s 255.83ms 
Feb 25 01:59:29 volumio volumio[9057]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 25 01:59:29 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 01:59:29 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 01:59:29 volumio volumio[9057]: info: CoreCommandRouter::Close All Modals sent
Feb 25 01:59:29 volumio volumio[9057]: info: CoreCommandRouter::Close All Modals sent
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=info msg="zeroconf server listening on port 37135"
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=debug msg="obtained new client token: AACXxkg6EGvL38BXWbztbZY3aqRrHi9GiO+zu50m4xN9icdUJOfEyunNJeL1AEI4aB3T/F/Q2XferaboET8PPmzqXonj49glkRCJmgYB84z4PI0hqhDyqw96HUZYgEcLxwKoZA2rsYNK9tGQ7heG0KANVqHzepMkQmsX0yhBHndtEK+CwSlRXHtOfbiF2Qm5FWR4ylQ4u1qU6en6i8f4zUKs9iSJEqRwy3o1VHU9Ex5Eq6H1Qgee9FNyFg=="
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=debug msg="completed challenge"
Feb 25 01:59:29 volumio go-librespot[9645]: time="2026-02-25T01:59:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:30 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 25 01:59:30 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 25 01:59:30 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 25 01:59:31 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:31 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56.
Feb 25 01:59:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:32 volumio go-librespot[9658]: go-librespot daemon starting...
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=debug msg="app state loaded"
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:32 volumio go-librespot[9659]: time="2026-02-25T01:59:32-06:00" level=info msg="zeroconf server listening on port 45499"
Feb 25 01:59:33 volumio go-librespot[9659]: time="2026-02-25T01:59:33-06:00" level=debug msg="obtained new client token: AABgCpRyPCgOtbjFWdyGpZtgnw6E0aWlSjfYSKhSx1o13y8lW09lcp+/nX9A3tIyUpYsVjdMvej2s/dF9IQ9kkqcJ811JG+e64FWIbrSEo8NGwyzOpdhy7vNERQunFVAqNZqfjWa0pa6VXQ5Kvm7JltgBCYk7bamTbN7cd/8XQnGEafbOUGluAmONzM1E9XLTXBRS1n3SXADj9pMsVToVZounDhBfKb6qveGpQhrDd8HGB+WtR8WcZk="
Feb 25 01:59:33 volumio go-librespot[9659]: time="2026-02-25T01:59:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:33 volumio go-librespot[9659]: time="2026-02-25T01:59:33-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:33 volumio go-librespot[9659]: time="2026-02-25T01:59:33-06:00" level=debug msg="completed challenge"
Feb 25 01:59:33 volumio go-librespot[9659]: time="2026-02-25T01:59:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:34 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:34 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:36 volumio volumio[9057]: info: BOOT COMPLETED
Feb 25 01:59:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57.
Feb 25 01:59:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:36 volumio go-librespot[9680]: go-librespot daemon starting...
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="app state loaded"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=info msg="zeroconf server listening on port 44395"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="obtained new client token: AAAZY1qdr2PgkmWshm9y2KcGSX67s8WrOWKZRSjw2E2blmYiraDIYDfe2NEEzI4JYxZedF0OiqyT72deopfKiV5Q4JCX28bMm+DQh0ZMTjfLql6daaYCgAbwlPfT/8CbHg8+e76Xjp5KorVOdZQSkBKYnGmDrYstIfzc1X0tEkPX0RCfrnKwGcbiEyhf0MOYMqU931y5/XneB6hpWMxzP4AQTNcIV4ImsqU8MWG+yu/+peqEt6tlKPcWxw=="
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=debug msg="completed challenge"
Feb 25 01:59:36 volumio go-librespot[9681]: time="2026-02-25T01:59:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:37 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:37 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:40 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:40 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58.
Feb 25 01:59:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:40 volumio go-librespot[9688]: go-librespot daemon starting...
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="app state loaded"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=info msg="zeroconf server listening on port 43545"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="obtained new client token: AAAn4wnZttNTTRkaQs3Yk6otGGr4a4Wg5ZgMjrFwaqq0yGj+NiRziiwmj7gjckWhFipmRdIfW8KLqWXcHJl/Z21qxGkkpVEeTvvZEASyTQK1US4RM9Dma3CMNEos11Z5ZPuACv1jl6Jaf9zK37AIO2MBrzeU2wzhdLW9RKbGR5GmUywiMS6GVfd2Tteht1zWv+T5qPKu2bMScqwS+Cjh9qhuZGEOXZpugxp22xrS7v2TrznbF90/32qbdg=="
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=debug msg="completed challenge"
Feb 25 01:59:40 volumio go-librespot[9689]: time="2026-02-25T01:59:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:43 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:43 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59.
Feb 25 01:59:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:43 volumio go-librespot[9699]: go-librespot daemon starting...
Feb 25 01:59:43 volumio go-librespot[9700]: time="2026-02-25T01:59:43-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:43 volumio go-librespot[9700]: time="2026-02-25T01:59:43-06:00" level=debug msg="app state loaded"
Feb 25 01:59:43 volumio go-librespot[9700]: time="2026-02-25T01:59:43-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:43 volumio go-librespot[9700]: time="2026-02-25T01:59:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=info msg="zeroconf server listening on port 45171"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=debug msg="obtained new client token: AAAs2s+zSRnQo65XhUT7HNQyMGTsvf+ro7hRhBgSr0SEi1MTApOwJ1blk1l3QdWBZBSNMwRi4yZWH4ueXtr2Q+8SB0YYWX0yXST2tqXyN3lSOo8p6p0wN5hGOTvx9rYNv5c8hQfzkehJ5WWteHE/I9Q5D9b6ZABqPmjmQa4h4Mx6tm+ZAeC3E6VFc9RgKi4czkbM+Y+ocYdnkFZl3pQOG+PLQ0pE1S8Se+Gc4X3Hp/J7Te9CAnz8U4JLlQ=="
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=debug msg="completed challenge"
Feb 25 01:59:44 volumio go-librespot[9700]: time="2026-02-25T01:59:44-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:46 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:46 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60.
Feb 25 01:59:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:47 volumio go-librespot[9722]: go-librespot daemon starting...
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=debug msg="app state loaded"
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:47 volumio go-librespot[9723]: time="2026-02-25T01:59:47-06:00" level=info msg="zeroconf server listening on port 32917"
Feb 25 01:59:48 volumio go-librespot[9723]: time="2026-02-25T01:59:48-06:00" level=debug msg="obtained new client token: AAAUQ3TEq42btpyacnWrVR9SXkMp5gtzFRSNI4D3ac9oVzNtS+kcILQRNUXihlWoM7sAjsT0egvWmU9xK4/GvmdaOjeOAqsZ8CWvEt4TFkeVUrPfpiGytfbUn1rAwSBO7WALSR0QXMdZg7TkQmN+jICSFgiNyJ8WHQwX4j7LAe4r0HvCgSc/nJQlL4uljGxYbkLNma0G6oP/PA6Nb1TbHChKrUI3gvnyXJo/OibInaAJsVg5UtmBldQ="
Feb 25 01:59:48 volumio go-librespot[9723]: time="2026-02-25T01:59:48-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 25 01:59:48 volumio go-librespot[9723]: time="2026-02-25T01:59:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 25 01:59:48 volumio go-librespot[9723]: time="2026-02-25T01:59:48-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:48 volumio go-librespot[9723]: time="2026-02-25T01:59:48-06:00" level=debug msg="completed challenge"
Feb 25 01:59:48 volumio go-librespot[9723]: time="2026-02-25T01:59:48-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:49 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:49 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:50 volumio volumio[9057]: info: UPDATER: Scheduling automatic update
Feb 25 01:59:50 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Feb 25 01:59:50 volumio volumio[9057]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Feb 25 01:59:50 volumio volumio[9057]: info: UPDATER: Auto update will take place at: Thu Feb 26 2026 05:22:55 GMT-0600 (Central Standard Time)
Feb 25 01:59:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61.
Feb 25 01:59:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:51 volumio go-librespot[9731]: go-librespot daemon starting...
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="app state loaded"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=info msg="zeroconf server listening on port 41833"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="obtained new client token: AACaWiIsPG3qOhLgGiRa8eBE+y61Nnt/4HwO5w3DUS8bMjTCEVsENJNmhE9U09jGEpgqUTqkuTaB2OXH6yjdRnxf0FmpiGbj3PxKzs71hHy6rQiWMnOnMGUjUxZPNsyQkt16IQt6qEbSjbuzXKiBJ5tjCZRVqHc+1uOYkAYlteb8cPMNvvfNoY+KwodKqRwn/kP+keQh1S44KSNaIROHQCT8qSp5bIwwIcNQmUwhjRXj4yKASIaVyNsSJg=="
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=debug msg="completed challenge"
Feb 25 01:59:51 volumio go-librespot[9732]: time="2026-02-25T01:59:51-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:52 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:52 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62.
Feb 25 01:59:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:55 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:55 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:55 volumio go-librespot[9739]: go-librespot daemon starting...
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="app state loaded"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=info msg="zeroconf server listening on port 34261"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="obtained new client token: AAACNJmuUQbm7fZOno+eLuaAh5vLixkeicyeaACYbghaA28y65yFIpECvKLlG7ykmW2xXFk3FKL59pfjKp/TccBSaZKChKnp2IxuARgjQGWhsauO95A1eE4ql8WrfuySX5xqsemxjo2F+HtcyJoGvUVzRZsefzi+UM6FvB3QbiBbDc3C0gtlOUAVWxT7O9yygKA6694m5ynIOtelZkvj3MlGQZhv7NZYa8OpO2vuU5n10331E7Ub0N8PtQ=="
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=debug msg="completed challenge"
Feb 25 01:59:55 volumio go-librespot[9740]: time="2026-02-25T01:59:55-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 01:59:58 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 01:59:58 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 01:59:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63.
Feb 25 01:59:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 01:59:58 volumio go-librespot[9761]: go-librespot daemon starting...
Feb 25 01:59:58 volumio go-librespot[9762]: time="2026-02-25T01:59:58-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 01:59:58 volumio go-librespot[9762]: time="2026-02-25T01:59:58-06:00" level=debug msg="app state loaded"
Feb 25 01:59:58 volumio go-librespot[9762]: time="2026-02-25T01:59:58-06:00" level=debug msg="stored credentials not found"
Feb 25 01:59:58 volumio go-librespot[9762]: time="2026-02-25T01:59:58-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=info msg="zeroconf server listening on port 41721"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=debug msg="obtained new client token: AAAbcgBwy7ukBQsppleRfOaEjqNdHAZ15B2wATW3hhgz/i79aABJqiDOmpQPvxWoGXeDF5A9TQpgCbZ77yZGl7haei0Naagn7nYAvHe+wa22u/uBiweBdloJ68AxOl/6AV2UiReZs+BOb1olZgKZB1xeik9CccRLrIoQIzd0cjkctrQfGwM98FLA8y/+8N7LnuzBMUiJl7QBqmx41lFaeQZMTZsNAjch+oBVjoN0pA9qoApCOND6Fvc="
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=debug msg="completed keyexchange"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=debug msg="completed challenge"
Feb 25 01:59:59 volumio go-librespot[9762]: time="2026-02-25T01:59:59-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 01:59:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 01:59:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:01 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:01 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64.
Feb 25 02:00:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:02 volumio go-librespot[9772]: go-librespot daemon starting...
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=debug msg="app state loaded"
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:02 volumio go-librespot[9773]: time="2026-02-25T02:00:02-06:00" level=info msg="zeroconf server listening on port 39605"
Feb 25 02:00:03 volumio go-librespot[9773]: time="2026-02-25T02:00:03-06:00" level=debug msg="obtained new client token: AAB1XAmlUm4f9lfwBugrdOTXfHEKDLuPLxY1APyrIBy7Bb2mVRHAEPhftAGsddfjCjAXtRc+c3VjIgjL9DXM+6ey+FlLHsPHZDjyKK6vfSYWlO8rQ41krynQHqQnv/JhFdM8wlkZK2+8ygSVAxls4udY5KYPT+LQFZUEbSyxvPnRIQtK9hTkmtA9OY3HlcrMnSkYIjDabQvUY2fudYtAryWaRxejiOukKLiITHydFYTgqI8ocxxUua4="
Feb 25 02:00:03 volumio go-librespot[9773]: time="2026-02-25T02:00:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:03 volumio go-librespot[9773]: time="2026-02-25T02:00:03-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:03 volumio go-librespot[9773]: time="2026-02-25T02:00:03-06:00" level=debug msg="completed challenge"
Feb 25 02:00:03 volumio go-librespot[9773]: time="2026-02-25T02:00:03-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:04 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:04 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65.
Feb 25 02:00:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:06 volumio go-librespot[9794]: go-librespot daemon starting...
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="app state loaded"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=info msg="zeroconf server listening on port 36705"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="obtained new client token: AAANzapJOAPvegGiY51rQ4JSyY6WOs8+MAC1IU+NyEaYW8HFpGIrL+kaTIHbuC9h0X0ngQ0H478KDHeOAvxSE0MBAhPXl83//w4d1rlpREyMVho/mLjoi9hq1Bj3oOte1Ak+qzd/Ayfqk1WhtjV2hsIo8R9CCtfwFnu6CMDw+h6ndMTWokWFVgsfm1Ksi9HVfRz2jjXcP16UepyhqA4MqcS0shAbXBNXn9B+bXyqNrZIolpNg0AJvrLXbA=="
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=debug msg="completed challenge"
Feb 25 02:00:06 volumio go-librespot[9795]: time="2026-02-25T02:00:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:07 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:07 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66.
Feb 25 02:00:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:10 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:10 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:10 volumio go-librespot[9804]: go-librespot daemon starting...
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="app state loaded"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=info msg="zeroconf server listening on port 42301"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="obtained new client token: AADuUOL8fmsu46kF2VL6jLtFcNeg+Pvk/T9GuBIW6XMZYfUynT71MLXXpNGBa86nR9hXokiTt+eZkQOxee2/yMVBwFROUnYQoTvsvmUiNy9dOs4HRu5/DFQFE50ViWM3jFY7wWywTwpc7ZBbX/legMl+zLL2XKbaUi3M2ZqePooRPRyo7atN81Gr1b6ajvVsHLlbJnWLVTN+3VZUtkV5qqZX20sNlP5GYPjpfURlrP9+ynTSAGOmp6UZqw=="
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=debug msg="completed challenge"
Feb 25 02:00:10 volumio go-librespot[9805]: time="2026-02-25T02:00:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:13 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:13 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67.
Feb 25 02:00:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:13 volumio go-librespot[9813]: go-librespot daemon starting...
Feb 25 02:00:13 volumio go-librespot[9814]: time="2026-02-25T02:00:13-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:13 volumio go-librespot[9814]: time="2026-02-25T02:00:13-06:00" level=debug msg="app state loaded"
Feb 25 02:00:13 volumio go-librespot[9814]: time="2026-02-25T02:00:13-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:13 volumio go-librespot[9814]: time="2026-02-25T02:00:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=info msg="zeroconf server listening on port 36273"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=debug msg="obtained new client token: AADaBQypczo5m+tIUD5auMcSXY7N58dBn6Ep1XTcMrvmqQWuwes1zGZGdVXyNiwVTY2s4CaGPurHpobchfQh4P3OjC5NhSBDqAhhVghmyvAN8p8X0agF9KIJAyGcdlRLuxFQGHO7AfHYnjHq2D/EPOhyzNA+BzVLYT6h5xt8vW1azmnufoZ4y0dPoRJgeZOIYmy5FRFvlfjBJ2sy210iwHptP8B+j1v5ovpNOfv29XHh5qvGU7pbn4g="
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=debug msg="completed challenge"
Feb 25 02:00:14 volumio go-librespot[9814]: time="2026-02-25T02:00:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:16 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:16 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68.
Feb 25 02:00:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:17 volumio go-librespot[9835]: go-librespot daemon starting...
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=debug msg="app state loaded"
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:17 volumio go-librespot[9836]: time="2026-02-25T02:00:17-06:00" level=info msg="zeroconf server listening on port 40059"
Feb 25 02:00:18 volumio go-librespot[9836]: time="2026-02-25T02:00:18-06:00" level=debug msg="obtained new client token: AAD6XqNq4+uc5fCxcs26JFwY5H9xT3ZgMF41wjbQle7lGzj+jUmYxPpBqwl8mpQiRQ8BOFTxUfsksqw6BiyjXTnk7rLwu9zGn476BFw4zVnJY5I/CL1p0dNjyIutoK9Otve7cWReWQlsTUFHmKG2xAVYzYMa8ELlSbwDb7BVTCQ3OKws2atNh2zL9tIay+VmBksdP5dbdiulQHw4Qc5nQ/P6jpMRbpg87Iy0pcpmE+rDfteZxzx2LMo="
Feb 25 02:00:18 volumio go-librespot[9836]: time="2026-02-25T02:00:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:18 volumio go-librespot[9836]: time="2026-02-25T02:00:18-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:18 volumio go-librespot[9836]: time="2026-02-25T02:00:18-06:00" level=debug msg="completed challenge"
Feb 25 02:00:18 volumio go-librespot[9836]: time="2026-02-25T02:00:18-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:19 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:19 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69.
Feb 25 02:00:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:21 volumio go-librespot[9847]: go-librespot daemon starting...
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="app state loaded"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=info msg="zeroconf server listening on port 38925"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="obtained new client token: AADgBtOKa3hjKXT8o9UhNjeYiO25b747aOQ8XWPJbu5bIUbkdhHFtkmXv/C44pV4Of9OYO1gH5+38+pohsQPy1yPIfQF7E1pRuV7F69dLfJs7pnYk5h6PDXmdDfF5FiXW89aLFHk3xOMIiw9NmMVc7rTWyBc1m2ukREU8dp/hovqqAsyV0ctmPvzkmq6iCI3+1vQuENpSMzEqrgasrgUTuINd0+XbjDzqfsqMA/NIlXATfDLlnnmN6q8pw=="
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=debug msg="completed challenge"
Feb 25 02:00:21 volumio go-librespot[9848]: time="2026-02-25T02:00:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:22 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:22 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70.
Feb 25 02:00:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:25 volumio go-librespot[9856]: go-librespot daemon starting...
Feb 25 02:00:25 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="app state loaded"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:25 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=info msg="zeroconf server listening on port 44941"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="obtained new client token: AACi4P0fhK74vBeK3ypIntC9WbAntK5jl3ij033Xjlv7PMgtQQSkrxB1z31bKS2L50Z1Qpk0iPEDhbMLFWyEWN2ZpNroOLIGhdxNZ0F/f/xVq7JfM3q0Kyc45EFO+9zLtmtucZ4CLd/ZiU7Dc/NLa8mD3cqY4iQH0G/aZdqme9As0N505dJSEbtglv39U3KLNEwuhyhtM1tF1DiaYinLjFTe6Fm9K/rYpWYxHQ0jDQ1PGv4lmRq2wIz+0Q=="
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=debug msg="completed challenge"
Feb 25 02:00:25 volumio go-librespot[9857]: time="2026-02-25T02:00:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:28 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:28 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71.
Feb 25 02:00:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:28 volumio go-librespot[9878]: go-librespot daemon starting...
Feb 25 02:00:28 volumio go-librespot[9879]: time="2026-02-25T02:00:28-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:28 volumio go-librespot[9879]: time="2026-02-25T02:00:28-06:00" level=debug msg="app state loaded"
Feb 25 02:00:28 volumio go-librespot[9879]: time="2026-02-25T02:00:28-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=info msg="zeroconf server listening on port 42807"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=debug msg="obtained new client token: AAA5l96nmVRTZh5EjLjguzT/OmzBCGfh935/av6gWVe6B9Us3A+iWWKPrmY5ddxnk2JIiMey7QU5CEN6cWqeth7v7ZPYRDk0gQQnAZHhtSbSOPFA66HzcAFtIcIeBbb7OVhdsHb6uA6ZxvmkY2qlWwgU6nrLjW1dG+9HDPnceIST/YnOU0hKM/mIRARDKB2oUL/CRXbaQLFDQQbsqwnvVyJMnyRyKDZnzzPiR7f1xhUo+jK25d5ZgXcOnw=="
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=debug msg="completed challenge"
Feb 25 02:00:29 volumio go-librespot[9879]: time="2026-02-25T02:00:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:31 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:31 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72.
Feb 25 02:00:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:32 volumio go-librespot[9887]: go-librespot daemon starting...
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=debug msg="app state loaded"
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:32 volumio go-librespot[9888]: time="2026-02-25T02:00:32-06:00" level=info msg="zeroconf server listening on port 45375"
Feb 25 02:00:33 volumio go-librespot[9888]: time="2026-02-25T02:00:33-06:00" level=debug msg="obtained new client token: AACgkQbjeyyK2zBPvfa+BZg12vR4mgjjCxVQVkMxTu9oKewRRJ3p1y1ETQSnYKwpL5hrY1TKSeM/Sh/zrdRzmGlxiyysYPyOj7Kq3lewQWUfSLOcVJYcPU0v1QPza4vEvZIe9Z82Wc3hmexygLn11cuOH+aDHnKqR6jjPt6TCJnTYik+kUH85eQTwXXZIihp/VjV9YxmGEakXCQi2Jh5nBYfUxg+TX3mhsPmsRSyrUUbpoZdsl1PKu0="
Feb 25 02:00:33 volumio go-librespot[9888]: time="2026-02-25T02:00:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:33 volumio go-librespot[9888]: time="2026-02-25T02:00:33-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:33 volumio go-librespot[9888]: time="2026-02-25T02:00:33-06:00" level=debug msg="completed challenge"
Feb 25 02:00:33 volumio go-librespot[9888]: time="2026-02-25T02:00:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:34 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:34 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73.
Feb 25 02:00:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:36 volumio go-librespot[9895]: go-librespot daemon starting...
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="app state loaded"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=info msg="zeroconf server listening on port 38747"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="obtained new client token: AAB7L4vb1WNpSOGTCPdkG24SDHQCHylyOF4Ld9WT3cVH+V9XH1YBQ/RdUqy/ysOnkyyb9rCU7rQ4VuqVCTenIzylNEWDF/Wy6ofZySK9DNR5yeETC6MOM20qsHC9hH5dD1sYq1WTPTBbG9UTqiQCxqtmL3eeLuiTEbfbQ/mzz3TY85eGgTAl1YnznZk16IrvsTysQeDaDMjTY/Mz+xEYW86d3dPbOSVtASvuCPvW9l7Cx+VFc2yJ8CzeKA=="
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=debug msg="completed challenge"
Feb 25 02:00:36 volumio go-librespot[9896]: time="2026-02-25T02:00:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:37 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:37 volumio volumio[9057]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74.
Feb 25 02:00:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:40 volumio go-librespot[9917]: go-librespot daemon starting...
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="app state loaded"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:40 volumio volumio[9057]: info: Initializing connection to go-librespot Websocket
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="new websocket client"
Feb 25 02:00:40 volumio volumio[9057]: info: Connection to go-librespot Websocket established
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=info msg="zeroconf server listening on port 39891"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="obtained new client token: AACSmXKvpA8oFufT6UNieGWtqvnf94DstnvamKyljtpOAlLxQqdCkt4W4cyBmnwOS5qXrSvyw7AFj92nRmZCYCitT9uvMG8pvodaDIc8PW6YLzSNfapQf1fIKmEIrQeVdNDFqlDuSlAt6//GF6aKj/P4s/g//rOenfdV65f+Psz/EuQghlte7TuIIaujM4WO9VN5Abmy88hAmNR0TUV9tWz+OeAxnJB78iJY5ubwq3GYnPefVRNXVyVgZw=="
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="completed keyexchange"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=debug msg="completed challenge"
Feb 25 02:00:40 volumio go-librespot[9918]: time="2026-02-25T02:00:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 02:00:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 02:00:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 02:00:40 volumio volumio[9057]: info: Connection to go-librespot Websocket closed
Feb 25 02:00:43 volumio volumio[9057]: info: Getting Spotify volume
Feb 25 02:00:43 volumio volumio[9057]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 25 02:00:43 volumio volumio[9057]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 02:00:43 volumio volumio[9057]:     at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 25 02:00:43 volumio volumio[9057]:   errno: -111,
Feb 25 02:00:43 volumio volumio[9057]:   code: 'ECONNREFUSED',
Feb 25 02:00:43 volumio volumio[9057]:   syscall: 'connect',
Feb 25 02:00:43 volumio volumio[9057]:   address: '127.0.0.1',
Feb 25 02:00:43 volumio volumio[9057]:   port: 9879,
Feb 25 02:00:43 volumio volumio[9057]:   response: undefined
Feb 25 02:00:43 volumio volumio[9057]: }
Feb 25 02:00:43 volumio volumio[9057]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 25 02:00:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75.
Feb 25 02:00:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 02:00:43 volumio go-librespot[9940]: go-librespot daemon starting...
Feb 25 02:00:43 volumio go-librespot[9941]: time="2026-02-25T02:00:43-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 02:00:43 volumio go-librespot[9941]: time="2026-02-25T02:00:43-06:00" level=debug msg="app state loaded"
Feb 25 02:00:43 volumio go-librespot[9941]: time="2026-02-25T02:00:43-06:00" level=debug msg="stored credentials not found"
Feb 25 02:00:43 volumio go-librespot[9941]: time="2026-02-25T02:00:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 02:00:44 volumio sudo[9949]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 01:59'
Feb 25 02:00:44 volumio sudo[9949]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"