Feb 22 21:23:00 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:00 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41.
Feb 22 21:23:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:02 volumio go-librespot[5052]: go-librespot daemon starting...
Feb 22 21:23:02 volumio go-librespot[5053]: time="2026-02-22T21:23:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:02 volumio go-librespot[5053]: time="2026-02-22T21:23:02-06:00" level=debug msg="app state loaded"
Feb 22 21:23:02 volumio go-librespot[5053]: time="2026-02-22T21:23:02-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:02 volumio go-librespot[5053]: time="2026-02-22T21:23:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 22 21:23:03 volumio volumio[4562]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 22 21:23:03 volumio volumio[4562]: info: Adding plugin multiroom to MyMusic Plugins
Feb 22 21:23:03 volumio volumio[4562]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 22 21:23:03 volumio volumio[4562]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 22 21:23:03 volumio volumio[4562]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 22 21:23:03 volumio volumio[4562]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 22 21:23:03 volumio volumio[4562]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 22 21:23:03 volumio volumio[4562]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-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 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-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 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-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 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-06:00" level=info msg="zeroconf server listening on port 32861"
Feb 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-06:00" level=debug msg="obtained new client token: AADc3hENw74Lzexj7m1ANDeMNVV7yT+zWysoqXGCJYx+9PPdMIl+7jIBJ0ShTJ10/3bKz/dEcsHO/EpJdPOdMKA5zoFfxmV4bgTDuAylRFRm0WAIHJiSXdYr/qToEIHbHpSUSSL23RGSIWefECwWGGNPS6yZbOpC5ZzAHUVRNUON1R/FTp8mSXzxcIQfpFeyQujeWj79XYONWht8I20Ilb+flMkGtoja9gnjYpieqH6RZKn67ibToPnbfA=="
Feb 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23:03-06:00" level=debug msg="completed challenge"
Feb 22 21:23:03 volumio go-librespot[5053]: time="2026-02-22T21:23: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 22 21:23:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:04 volumio volumio[4562]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 22 21:23:04 volumio volumio[4562]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 22 21:23:04 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 21:23:04 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 21:23:04 volumio volumio[4562]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 22 21:23:04 volumio volumio[4562]: info: MyVolumio login type: Token
Feb 22 21:23:04 volumio volumio[4562]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 22 21:23:04 volumio volumio[4562]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 22 21:23:05 volumio volumio[4562]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 22 21:23:05 volumio volumio[4562]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 22 21:23:05 volumio volumio[4562]: info: Streaming services startup
Feb 22 21:23:05 volumio volumio[4562]: info: Starting Streaming Daemon
Feb 22 21:23:05 volumio sudo[5061]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 22 21:23:05 volumio sudo[5061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 22 21:23:05 volumio volumio[4562]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 22 21:23:05 volumio sudo[5061]: pam_unix(sudo:session): session closed for user root
Feb 22 21:23:05 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:05 volumio volumio[4562]: error: Cannot start Volumio Streaming Daemon
Feb 22 21:23:05 volumio volumio[4562]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 22 21:23:05 volumio volumio[4562]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 22 21:23:05 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:05 volumio volumio[4562]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required.
Feb 22 21:23:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42.
Feb 22 21:23:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:06 volumio go-librespot[5067]: go-librespot daemon starting...
Feb 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23:06-06:00" level=debug msg="app state loaded"
Feb 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23:06-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23: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-gew1.spotify.com:80]"
Feb 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23:06-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 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23:06-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 22 21:23:06 volumio go-librespot[5068]: time="2026-02-22T21:23:06-06:00" level=info msg="zeroconf server listening on port 36267"
Feb 22 21:23:07 volumio go-librespot[5068]: time="2026-02-22T21:23:07-06:00" level=debug msg="obtained new client token: AABjpFgCX/KuMs3+YTXfyeRRtPO+Ci4g4GkhJ6QK7tTrOdtIwBoCJ/tCmC5/qNLhAoJiOmdz6+npMBhiJtkZ++8vgdVRdjPA5NuxsGwMdlK3DGjyiJRijO+qzCTnFYcik3JutI+Sihl8FrzWWdohCOfORe9cEPLTzyP/JOUn63SisrLN7NxlKFc0Wpr3UzLZ4MUB1W4sRQckOzft//XziVD2jHK08j22gZp2AUa9V1UL2zsisnSlm4k="
Feb 22 21:23:07 volumio go-librespot[5068]: time="2026-02-22T21:23:07-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 22 21:23:07 volumio go-librespot[5068]: time="2026-02-22T21:23:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 22 21:23:07 volumio go-librespot[5068]: time="2026-02-22T21:23:07-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:07 volumio go-librespot[5068]: time="2026-02-22T21:23:07-06:00" level=debug msg="completed challenge"
Feb 22 21:23:07 volumio go-librespot[5068]: time="2026-02-22T21:23:07-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 22 21:23:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:08 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:08 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43.
Feb 22 21:23:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:10 volumio go-librespot[5077]: go-librespot daemon starting...
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=debug msg="app state loaded"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23: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 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23: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 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23: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 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=info msg="zeroconf server listening on port 44063"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=debug msg="obtained new client token: AABus6oVC/veVksiDDW3z8drg2AldYTTadUQuTnQaxGNc3zHjw4b+AM+UAXB58cRLZKAhL5w5UVFYRQQEtSzlbKF5xAK52oelbmzu4X8p9E6HQpt1SP1nhouldWFqdjmcPEHB3LtIvVk2zjgpgRbx+BunGdTkHlYQzs3KgVY1uGVNWB/E4k01KgESsMRYpO8mprgA0Buv/mcIbvucbVGj70rzd8FHUqsmnjm5rywsxaw2poFXKnZHdTkeQ=="
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23:10-06:00" level=debug msg="completed challenge"
Feb 22 21:23:10 volumio go-librespot[5078]: time="2026-02-22T21:23: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 22 21:23:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:11 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:11 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44.
Feb 22 21:23:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:14 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:14 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:14 volumio go-librespot[5099]: go-librespot daemon starting...
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=debug msg="app state loaded"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-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 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-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 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=info msg="zeroconf server listening on port 46125"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=debug msg="obtained new client token: AACdxMkGKAE3EDSjGjwlEeahR81gDzHe4XtmWKq0YuMt9VvBD8ux1Af0KZk7nOd6kWoPkoS5hG49cZRQxKUVGfMrbOW25SqCO8NPxoyO52Az6YeXHRv1vJg+Oi7fQYZYp3EQsuIs3f+rGMKTz1mn0mXiOes1R4Hy9DOxww5idRPANtcc+lJXADAqnkMzzxjEL1V/0yKMP6FIEf+Dr37PokU65GMtmLS5ZySsVS23ZE5Zilckvfbky9ibsw=="
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23: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 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23:14-06:00" level=debug msg="completed challenge"
Feb 22 21:23:14 volumio go-librespot[5100]: time="2026-02-22T21:23: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 22 21:23:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:17 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 21:23:17 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 21:23:17 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 22 21:23:17 volumio volumio-remote-updater[26837]: Test mode disabled
Feb 22 21:23:17 volumio volumio-remote-updater[26837]: Alpha mode disabled
Feb 22 21:23:17 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled
Feb 22 21:23:17 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 22 21:23:17 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:17 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:17 volumio volumio[4562]: 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 22 21:23:17 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 22 21:23:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45.
Feb 22 21:23:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:17 volumio go-librespot[5114]: go-librespot daemon starting...
Feb 22 21:23:17 volumio go-librespot[5115]: time="2026-02-22T21:23:17-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:17 volumio go-librespot[5115]: time="2026-02-22T21:23:17-06:00" level=debug msg="app state loaded"
Feb 22 21:23:17 volumio go-librespot[5115]: time="2026-02-22T21:23:17-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:17 volumio go-librespot[5115]: time="2026-02-22T21:23:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-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 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-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 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-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 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-06:00" level=info msg="zeroconf server listening on port 41393"
Feb 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-06:00" level=debug msg="obtained new client token: AABPLaw/Q+6/J1gvxIzZ/6Ixis0iU6vulFEY/T8YJpk+Oi6gQ+xA7zJcV3IZBEpiEA0cgawQrPmDuiQQM9QJBx5wEAJF52FLdOPegoT+Yob1di08iF2ju99Ea5p5uWL5AGrkx5W/4g1T8n666M450QbrC/MiiksHg9O5PMjZlxAvN6+Xgd3SQYu5QasYb2vOLb+SAwbGavbAFxrFCd2rl+wi0HjB8UM2lYk56P/D71MYYIGdxdq7jqM1QA=="
Feb 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23:18-06:00" level=debug msg="completed challenge"
Feb 22 21:23:18 volumio go-librespot[5115]: time="2026-02-22T21:23: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 22 21:23:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:19 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 22 21:23:19 volumio volumio[4562]: 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 22 21:23:19 volumio volumio[4562]: info: CoreCommandRouter::volumioGetState
Feb 22 21:23:19 volumio volumio[4562]: info: CorePlayQueue::getTrack 0
Feb 22 21:23:20 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:20 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46.
Feb 22 21:23:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:21 volumio go-librespot[5122]: go-librespot daemon starting...
Feb 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23:21-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23:21-06:00" level=debug msg="app state loaded"
Feb 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23:21-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23: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 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23: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 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23: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 22 21:23:21 volumio go-librespot[5123]: time="2026-02-22T21:23:21-06:00" level=info msg="zeroconf server listening on port 42155"
Feb 22 21:23:22 volumio go-librespot[5123]: time="2026-02-22T21:23:22-06:00" level=debug msg="obtained new client token: AABxqV0eckqSdj+F9rSiqtc/wgAuw4iTEetpeOGlT0zVdGuYFilA9oMW+wGzTNrIJj1xIs7wcD99pQRyo9i0hcKFVm8Tt8yc//Yn+pDYR7low8x4RHpvvLafJgNU7ChjFDyQxmu5+18eLiRHOgA54BADfzNy9/G884Pp0YqvNoHHjPMQe6iGiOzkZj9r0qnJU7qNdS4YVePop9cBn3eWfGW5yZ0O4F6EJwRwkG8Ph1x5nBaJ9I2zPqw="
Feb 22 21:23:22 volumio go-librespot[5123]: time="2026-02-22T21:23:22-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:22 volumio go-librespot[5123]: time="2026-02-22T21:23:22-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:22 volumio go-librespot[5123]: time="2026-02-22T21:23:22-06:00" level=debug msg="completed challenge"
Feb 22 21:23:22 volumio go-librespot[5123]: time="2026-02-22T21:23:22-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 22 21:23:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:23 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:23 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:24 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 21:23:24 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 21:23:24 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 22 21:23:24 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 22 21:23:24 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 22 21:23:24 volumio volumio[4562]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 22 21:23:24 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 21:23:25 volumio volumio[4562]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 22 21:23:25 volumio volumio[4562]: info: Completed starting MyVolumio Plugin
Feb 22 21:23:25 volumio volumio[4562]:  [Metrics]  CommandRouter:  54s 930.37ms 
Feb 22 21:23:25 volumio volumio[4562]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 22 21:23:25 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 21:23:25 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 21:23:25 volumio volumio[4562]: info: CoreCommandRouter::Close All Modals sent
Feb 22 21:23:25 volumio volumio[4562]: info: CoreCommandRouter::Close All Modals sent
Feb 22 21:23:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47.
Feb 22 21:23:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:25 volumio go-librespot[5147]: go-librespot daemon starting...
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=debug msg="app state loaded"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-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 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-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 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=info msg="zeroconf server listening on port 39345"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=debug msg="obtained new client token: AAD0wep0MHnD9eg9GvrzBo5a+boTi3S2BVTbciKwidZcQXeEbfVYICbb0ZMwI2Sk2ZZyfs7YXkI/gp8QnksJnZp33AFvND7GILE5x2VNCBZWr+Xm2qIm/QUj7jZWxk/KaQfk+YFDKI4bwFTndIar1KN9m/VqdxC+eowu6RHbsXDuNpUPKPTUG6nfW4GasnXpdM5v+0c3SbIqnHSzmEY/fnPHfUaHFI5qBMFp9UajDJ0Ze1lzq+zZkGsAUw=="
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23:25-06:00" level=debug msg="completed challenge"
Feb 22 21:23:25 volumio go-librespot[5148]: time="2026-02-22T21:23: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 22 21:23:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:26 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 22 21:23:26 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 21:23:26 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 22 21:23:26 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:26 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48.
Feb 22 21:23:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:29 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:29 volumio go-librespot[5160]: go-librespot daemon starting...
Feb 22 21:23:29 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=debug msg="app state loaded"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-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 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-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 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=info msg="zeroconf server listening on port 40163"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=debug msg="obtained new client token: AADroQIYuv5impSZXPgLQ3Jvp8wAxjCF8dryaGy7V7WmAhr4hUvDj4QdlzlfkKnDXTL8Pj7w1DeQRNp3DGXmzZb5owEr5iQOzPoebbPIsbop3SbKFdV+AOVsJhPLZObx0eglabri4VAmIb4mV2sR2HGKJ4+te4tqHC84pdI3IXmkoSlvFaJc5ZHn1SeidPHe/cxf137/rs0dur/rPbNCimKnk99N6o8c2BLKyMPwCKT5v6AIb49Hgc9BWw=="
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23:29-06:00" level=debug msg="completed challenge"
Feb 22 21:23:29 volumio go-librespot[5161]: time="2026-02-22T21:23: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 22 21:23:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:32 volumio volumio[4562]: info: BOOT COMPLETED
Feb 22 21:23:32 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:32 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49.
Feb 22 21:23:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:32 volumio go-librespot[5182]: go-librespot daemon starting...
Feb 22 21:23:32 volumio go-librespot[5183]: time="2026-02-22T21:23:32-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:32 volumio go-librespot[5183]: time="2026-02-22T21:23:32-06:00" level=debug msg="app state loaded"
Feb 22 21:23:32 volumio go-librespot[5183]: time="2026-02-22T21:23:32-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:32 volumio go-librespot[5183]: time="2026-02-22T21:23:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-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 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-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 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-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 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-06:00" level=info msg="zeroconf server listening on port 37595"
Feb 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-06:00" level=debug msg="obtained new client token: AAC7lNfpZ04jpf6iW8DHQ6fuzGGn86tq1vVaEF4A8wlXQaDrpc370VewDkvBbx5aYpH1C7mva/WeCyNbxuioVxDnNVcVOdgHoiPImrg1IWdLwNjeUNCTskUoyxTbDMDkVuam3Q4/IMKQlLT0VGR7TcX1Fr4tGZDvk3m1QimERqVfjjvbX3HTsG1NqzEAAannTcyPw0DWXfk+NZ05JmN1cYOCP5qEU0+h/a1/F5EXWF52RO6BnG5CphEvpQ=="
Feb 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23:33-06:00" level=debug msg="completed challenge"
Feb 22 21:23:33 volumio go-librespot[5183]: time="2026-02-22T21:23: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 22 21:23:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:35 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:35 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50.
Feb 22 21:23:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:36 volumio go-librespot[5190]: go-librespot daemon starting...
Feb 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23:36-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23:36-06:00" level=debug msg="app state loaded"
Feb 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23:36-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23:36-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 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23:36-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 22 21:23:36 volumio go-librespot[5191]: time="2026-02-22T21:23:36-06:00" level=info msg="zeroconf server listening on port 41159"
Feb 22 21:23:37 volumio go-librespot[5191]: time="2026-02-22T21:23:37-06:00" level=debug msg="obtained new client token: AAAu9enlHH2p8REEc+Tx4Wb60n1GOpzK3JYEuV0GNufvlnXEPnI5eIW6zP3kUD6LKJKyTetGvRxk3ttCiAGxPqmi1brCSXyHQQkeohCtnZqe9ZEqNM46T5QFTc57ssXbiKTizhremG2dBjPdEjGIJ2PvrWL07bE8XKg/B0B7HhVQW7CWlpr5SLDhFOa3w2CblFHBv6Jo1hxoLDBcTsR/Zl0SBOD7QazIqXg3Le/RQfbLCV4lB0tO/GA="
Feb 22 21:23:37 volumio go-librespot[5191]: time="2026-02-22T21:23:37-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:37 volumio go-librespot[5191]: time="2026-02-22T21:23:37-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:37 volumio go-librespot[5191]: time="2026-02-22T21:23:37-06:00" level=debug msg="completed challenge"
Feb 22 21:23:37 volumio go-librespot[5191]: time="2026-02-22T21:23:37-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 22 21:23:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:38 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:38 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51.
Feb 22 21:23:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:40 volumio go-librespot[5203]: go-librespot daemon starting...
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=debug msg="app state loaded"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23: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 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23: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 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23: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 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=info msg="zeroconf server listening on port 35767"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=debug msg="obtained new client token: AAB7+FGUurEBUqTafzXFwYTjJxgN7iA3aITuiWA/xq3aDndi1vZzNmkCWTjI6BTNG3d5tNHvTuaVjcVF74Y2mDe/qmfkC5adpkpD2HvkYlAuq8xIeA4naV9KMwbWjnwlB9vTvUHAraS+jg9CHT8WUYfIUee8TfWB3i3Kkd+YVxQJ2mM+9kZG6O9uArrFNh5SCAM6rw1PWD5Z6uDRyMZdJ/xLB1lVDEfo2RhKlMusNavG9I0K1Ht+i6IJ2A=="
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23:40-06:00" level=debug msg="completed challenge"
Feb 22 21:23:40 volumio go-librespot[5204]: time="2026-02-22T21:23: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 22 21:23:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:41 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:41 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52.
Feb 22 21:23:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:44 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:44 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:44 volumio go-librespot[5226]: go-librespot daemon starting...
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=debug msg="app state loaded"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-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 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-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 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=info msg="zeroconf server listening on port 37987"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=debug msg="obtained new client token: AACx+YX8/0AqConrdHsL3zbggjIcUe2QMykDzueF3HHBPGla4OuuZ7UHHFTcIoxOOe5vMau/7mhT9dyjHvE8RcpQ6NtMdszb1Usc5KWHwwmxUJzw5GBT3qxQmsVD+CpYu+ORYieGVNPlrc78iKWzJJoJHjz1Gzj7nxWe6RoqHXOOCypT1TQV6u8lJ31VgCMVpIy0qQNaxaakieCrxC2SvqMD/G3REvNTTAJFbXBTsFke0BbPXnI0xi24XQ=="
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23:44-06:00" level=debug msg="completed challenge"
Feb 22 21:23:44 volumio go-librespot[5227]: time="2026-02-22T21:23: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 22 21:23:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:47 volumio volumio[4562]: info: UPDATER: Scheduling automatic update
Feb 22 21:23:47 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Feb 22 21:23:47 volumio volumio[4562]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Feb 22 21:23:47 volumio volumio[4562]: info: UPDATER: Auto update will take place at: Mon Feb 23 2026 03:01:51 GMT-0600 (Central Standard Time)
Feb 22 21:23:47 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:47 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53.
Feb 22 21:23:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:47 volumio go-librespot[5234]: go-librespot daemon starting...
Feb 22 21:23:47 volumio go-librespot[5235]: time="2026-02-22T21:23:47-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:47 volumio go-librespot[5235]: time="2026-02-22T21:23:47-06:00" level=debug msg="app state loaded"
Feb 22 21:23:47 volumio go-librespot[5235]: time="2026-02-22T21:23:47-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:47 volumio go-librespot[5235]: time="2026-02-22T21:23:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-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 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-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 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-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 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-06:00" level=info msg="zeroconf server listening on port 39953"
Feb 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-06:00" level=debug msg="obtained new client token: AABKDliCdxfAECQo1oZ6B7yA74YAn//2rZBYJnUvzHK7nReNmEGxvnSTIGhUQZrDTRjMsz6oTUX/l8DQ93Y29Zyzc69XhoX7/aa1hNFE7WiIaPwmCsM89C9OqHM6pv1g8Q6fnHAuRfwXp+3Ojbjo76uO4nS6kDNhzxF20ZiLWGfsWrby2qJjg+I80w1/jWGFcuPOK/DwwLuenPjiCo9TAjXknzC+Ldn1wTSDrtY2r3oABM8mx2KBM3Y="
Feb 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23:48-06:00" level=debug msg="completed challenge"
Feb 22 21:23:48 volumio go-librespot[5235]: time="2026-02-22T21:23: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 22 21:23:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:50 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:50 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54.
Feb 22 21:23:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:51 volumio go-librespot[5243]: go-librespot daemon starting...
Feb 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23:51-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23:51-06:00" level=debug msg="app state loaded"
Feb 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23:51-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23:51-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23:51-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 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23:51-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 22 21:23:51 volumio go-librespot[5244]: time="2026-02-22T21:23:51-06:00" level=info msg="zeroconf server listening on port 34087"
Feb 22 21:23:52 volumio go-librespot[5244]: time="2026-02-22T21:23:52-06:00" level=debug msg="obtained new client token: AABq8Qpac7T5/nES3oYDoqTpZtUOLLczqd8PEPIoKCFJ6a5cV8pVP7xeesAuKmtuJ4VCUxPnHjAiIn9JxXHXy6ERlXKX7nNHIa9VqUV2043HhDvqYz9H5cV3mGJyloUF1j1t5p61ZZ+qNu7g35BEVGXjPh49zIE1Cy0DNDSRlUAG32+QXaLbzItIxie4ZILI8LAtfSUJtvoxBQrZhrMn4cdINIf/pEr3aRl/9QQYofGB5tu1Q2Rzysc="
Feb 22 21:23:52 volumio go-librespot[5244]: time="2026-02-22T21:23:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:52 volumio go-librespot[5244]: time="2026-02-22T21:23:52-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:52 volumio go-librespot[5244]: time="2026-02-22T21:23:52-06:00" level=debug msg="completed challenge"
Feb 22 21:23:52 volumio go-librespot[5244]: time="2026-02-22T21:23:52-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 22 21:23:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:53 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:53 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55.
Feb 22 21:23:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:55 volumio go-librespot[5265]: go-librespot daemon starting...
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=debug msg="app state loaded"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-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 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-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 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=info msg="zeroconf server listening on port 33767"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=debug msg="obtained new client token: AADLK631QwdwYdk7PiJOpgY0sMbcWIJq8myHAS1Nm929RV0zr+z3DpwsC3aue02kDlyuqkBBjC57SA0ebWIqYtKMUIyMyuPM0NfXEH3FTxLxjsYcT7BIRMU3zT8n3ILceZwXSES2hZBd9JdBmRhu3gqoAkCEb5xEuxFilU8rSYl8Kwa443Zh/GIH+Hr2ZVtSoZtEAmnEH660jpkiydVcROQ1AJZOLqSnJFBRNr9gu30j/oN8zNTfbxWTiw=="
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23:55-06:00" level=debug msg="completed challenge"
Feb 22 21:23:55 volumio go-librespot[5266]: time="2026-02-22T21:23: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 22 21:23:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:56 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:56 volumio volumio[4562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:23:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56.
Feb 22 21:23:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:23:59 volumio go-librespot[5276]: go-librespot daemon starting...
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=debug msg="app state loaded"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=debug msg="stored credentials not found"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:23:59 volumio volumio[4562]: info: Initializing connection to go-librespot Websocket
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=debug msg="new websocket client"
Feb 22 21:23:59 volumio volumio[4562]: info: Connection to go-librespot Websocket established
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-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 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-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 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=info msg="zeroconf server listening on port 44167"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=debug msg="obtained new client token: AADqtlOi/mjbMwokUIBsKi/S8f3aS2nk9r3KGpMqRVS54WRtzMrDmcqvyPSgpI9wxS3MQ8jMB7ocpvA+eQvExSWiIWnGqkhgVXnyBuNFhRL4RiUwMiwt7nEEoS1A9rOQf84fpvmMRFTmKVH6MHI2htwbb6SN9aI11s9RLwxoKZ8bCNpAQRMEvc3mLjFDbhPXHx6o3x6k8PboatV7zC1m5/BrR9dXgDEsiYohmDt7nOggXOe6iCGwY+Dg/w=="
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=debug msg="completed keyexchange"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23:59-06:00" level=debug msg="completed challenge"
Feb 22 21:23:59 volumio go-librespot[5277]: time="2026-02-22T21:23: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 22 21:23:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:23:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:23:59 volumio volumio[4562]: info: Connection to go-librespot Websocket closed
Feb 22 21:24:02 volumio volumio[4562]: info: Getting Spotify volume
Feb 22 21:24:02 volumio volumio[4562]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 21:24:02 volumio volumio[4562]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:24:02 volumio volumio[4562]:     at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 22 21:24:02 volumio volumio[4562]:   errno: -111,
Feb 22 21:24:02 volumio volumio[4562]:   code: 'ECONNREFUSED',
Feb 22 21:24:02 volumio volumio[4562]:   syscall: 'connect',
Feb 22 21:24:02 volumio volumio[4562]:   address: '127.0.0.1',
Feb 22 21:24:02 volumio volumio[4562]:   port: 9879,
Feb 22 21:24:02 volumio volumio[4562]:   response: undefined
Feb 22 21:24:02 volumio volumio[4562]: }
Feb 22 21:24:02 volumio volumio[4562]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 21:24:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57.
Feb 22 21:24:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:24:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 21:24:02 volumio go-librespot[5311]: go-librespot daemon starting...
Feb 22 21:24:02 volumio go-librespot[5312]: time="2026-02-22T21:24:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:24:02 volumio go-librespot[5312]: time="2026-02-22T21:24:02-06:00" level=debug msg="app state loaded"
Feb 22 21:24:02 volumio go-librespot[5312]: time="2026-02-22T21:24:02-06:00" level=debug msg="stored credentials not found"
Feb 22 21:24:02 volumio go-librespot[5312]: time="2026-02-22T21:24:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:24:03 volumio sudo[5321]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 21:23'
Feb 22 21:24:03 volumio sudo[5321]: 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"