Feb 25 05:49:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Feb 25 05:49:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:00 volumio go-librespot[18848]: go-librespot daemon starting...
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=debug msg="app state loaded"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-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 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-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 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-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 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=info msg="zeroconf server listening on port 38401"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=debug msg="obtained new client token: AAAwRdyWZZEKInSI2mDxcz5f8YdEZMUwI2UfGVsaoQ3tU/p92qc/FNjLvqsyT77I0byKvvJ98KfKygMKtAFRMrKcLUrd5DNuWM6orESH3W7TAmN+J1VlbqT54jz5TQ7h2jABdapwWHDDzkE0YeBS+r/hkMnjhqtbdXXeGR9OYHRSOstUudA2W31XwhHK4Xu/F8D3Sktd0yNT6r7paYuL/4PUj3PBxMyK86QWUp/DNBaKIxvDai2bd1arOw=="
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-06:00" level=debug msg="completed challenge"
Feb 25 05:49:00 volumio go-librespot[18849]: time="2026-02-25T05:49:00-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 05:49:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:01 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:01 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 25 05:49:01 volumio volumio[18355]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 25 05:49:01 volumio volumio[18355]: info: Adding plugin multiroom to MyMusic Plugins
Feb 25 05:49:01 volumio volumio[18355]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 25 05:49:01 volumio volumio[18355]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 25 05:49:01 volumio volumio[18355]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 25 05:49:01 volumio volumio[18355]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 25 05:49:01 volumio volumio[18355]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 25 05:49:01 volumio volumio[18355]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 25 05:49:02 volumio volumio[18355]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 25 05:49:02 volumio volumio[18355]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 25 05:49:02 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 05:49:02 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 05:49:02 volumio volumio[18355]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 25 05:49:02 volumio volumio[18355]: info: MyVolumio login type: Token
Feb 25 05:49:02 volumio volumio[18355]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 25 05:49:02 volumio volumio[18355]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 25 05:49:03 volumio volumio[18355]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 25 05:49:03 volumio volumio[18355]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 25 05:49:03 volumio volumio[18355]: info: Streaming services startup
Feb 25 05:49:03 volumio volumio[18355]: info: Starting Streaming Daemon
Feb 25 05:49:03 volumio sudo[18857]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 25 05:49:03 volumio sudo[18857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 25 05:49:03 volumio volumio[18355]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 25 05:49:03 volumio sudo[18857]: pam_unix(sudo:session): session closed for user root
Feb 25 05:49:03 volumio volumio[18355]: error: Cannot start Volumio Streaming Daemon
Feb 25 05:49:03 volumio volumio[18355]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 25 05:49:03 volumio volumio[18355]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 25 05:49:03 volumio volumio[18355]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required.
Feb 25 05:49:04 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:04 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Feb 25 05:49:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:04 volumio go-librespot[18865]: go-librespot daemon starting...
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=debug msg="app state loaded"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-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 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-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 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=info msg="zeroconf server listening on port 36275"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=debug msg="obtained new client token: AAD6TYkDy/ZiJp8J8eMAw3UXq93xreJYtLTqQMkD5v+C7ENvW/P7vh+WFc54ShOypuzBqF/hDI195HyrRd0NQvYs2YewjPFH4YnQn172ABo0Shfb7UldGEjNF7+zGJPSabQrS7kJICs8rGEs8J4St52HKd3S+TSucAdwnjFSkOfMISyGTK0ocjPVXslAH34N2GZne+DiGteB/kFIvj3/DgEDHTuCXorOuaVnuuXaegzapIoIXNaPzz/d3w=="
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=debug msg="completed challenge"
Feb 25 05:49:04 volumio go-librespot[18866]: time="2026-02-25T05:49:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:07 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:07 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Feb 25 05:49:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:07 volumio go-librespot[18873]: go-librespot daemon starting...
Feb 25 05:49:07 volumio go-librespot[18874]: time="2026-02-25T05:49:07-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:07 volumio go-librespot[18874]: time="2026-02-25T05:49:07-06:00" level=debug msg="app state loaded"
Feb 25 05:49:07 volumio go-librespot[18874]: time="2026-02-25T05:49:07-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:07 volumio go-librespot[18874]: time="2026-02-25T05:49:07-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-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 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-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 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-06:00" level=info msg="zeroconf server listening on port 41447"
Feb 25 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-06:00" level=debug msg="obtained new client token: AAAgFakD1MoPIxTtwqw4kS+4CGaKjOIXl4SqSiWHwu29sIMlM7NxZODigRra0q4K0KI6ksensReJeyOhFyKocNYnjR0LY+XtJK2SofIKpLtSxBvVr7ZZHxTDB53WPMnvc9pWKXiYzOg6Vsol/Vm8fYyx/1ilFJKZdtvbwLhjD9ST+FunG3ub0gUnBAM9BpiHBYw070Ohuw4LE00m2BE1XPeMl7ZPTd4ZLvlEdJpzZLJbZ146QmDl7/w="
Feb 25 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-06:00" level=debug msg="completed challenge"
Feb 25 05:49:08 volumio go-librespot[18874]: time="2026-02-25T05:49:08-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:10 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:10 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Feb 25 05:49:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:11 volumio go-librespot[18895]: go-librespot daemon starting...
Feb 25 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-06:00" level=debug msg="app state loaded"
Feb 25 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-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 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-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 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-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 05:49:11 volumio go-librespot[18896]: time="2026-02-25T05:49:11-06:00" level=info msg="zeroconf server listening on port 43817"
Feb 25 05:49:12 volumio go-librespot[18896]: time="2026-02-25T05:49:12-06:00" level=debug msg="obtained new client token: AAA/ConEvr0mI4wCSLxZeWozdPBzja/GAPF1L7/jZkqOR1Phn0WXufD4J3ycUf95HxG+25n/Jk4T8JPXtV1DjxOUhcGmQEpFW6lt/cy7IsvkcVqmxImMl4H9Py7IxUuWOScNpbnt6uBE2EQatHPe2PiWcWaI8dwixBGRxi5Mu6u8FU/CGu/AVQB77dIasqP/ttTHDG7cq88R1feVd5vi0fvnr68yW4GonmxO6vx0jZSjIMq2sspdEBg="
Feb 25 05:49:12 volumio go-librespot[18896]: time="2026-02-25T05:49:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:12 volumio go-librespot[18896]: time="2026-02-25T05:49:12-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:12 volumio go-librespot[18896]: time="2026-02-25T05:49:12-06:00" level=debug msg="completed challenge"
Feb 25 05:49:12 volumio go-librespot[18896]: time="2026-02-25T05:49:12-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:13 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:13 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:14 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 25 05:49:14 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 05:49:14 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 25 05:49:14 volumio volumio-remote-updater[26837]: Test mode disabled
Feb 25 05:49:14 volumio volumio-remote-updater[26837]: Alpha mode disabled
Feb 25 05:49:14 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled
Feb 25 05:49:14 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 25 05:49:14 volumio volumio[18355]: 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 05:49:14 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 25 05:49:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Feb 25 05:49:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:15 volumio go-librespot[18910]: go-librespot daemon starting...
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=debug msg="app state loaded"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-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 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-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 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=info msg="zeroconf server listening on port 35537"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=debug msg="obtained new client token: AADUaQLDEUeisPFVexve2cWrdu3PRju0QE5sz8UTspgp0MdVVQ7o4cOK75WbwtdMQ3JRSWkyN23E8mR5tP84VqPmflm4nYq1ahTPHeoxSytP8U107ucXf590L1CG0TexTCKUzhVhWIqWbvLjzJRrJC1uclxl0dPbl8s6InaEQgOIuTqWe8G9RmwvpidUceS199yGRwmCuPRsMdecXJEE64vj2RtpBF7PSnEHnr1/oj4UuN8YO6t3N64ZHA=="
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-06:00" level=debug msg="completed challenge"
Feb 25 05:49:15 volumio go-librespot[18911]: time="2026-02-25T05:49:15-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 05:49:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:16 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:16 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:17 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 25 05:49:17 volumio volumio[18355]: 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 05:49:17 volumio volumio[18355]: info: CoreCommandRouter::volumioGetState
Feb 25 05:49:17 volumio volumio[18355]: info: CorePlayQueue::getTrack 0
Feb 25 05:49:19 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:19 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Feb 25 05:49:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:19 volumio go-librespot[18919]: go-librespot daemon starting...
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=debug msg="app state loaded"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-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 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-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 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=info msg="zeroconf server listening on port 35831"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=debug msg="obtained new client token: AAC5koipntMohQfnRXLJBGPqOZNEY8uiPhPlqMnw4DXrBQCAZu56eGfmJ2ulg/knaaq9j9bbaCQOaRD4XajYLnq0GZGZncWW+NpqVUG8yNw02AX3pUcZyj4898ErfG5E3R/xZQ0WpH6JmJhfBqqFFrkPuCgjaapzovSQiFKHsbTSSmz//Oh7bL4IYeohBvDLJrYsaphk5L0vrmN1LFsyMLWfPF3UQPSZgcJewMIvQ1fn8md+zd4frbdtOA=="
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-06:00" level=debug msg="completed challenge"
Feb 25 05:49:19 volumio go-librespot[18920]: time="2026-02-25T05:49:19-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 05:49:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:22 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:22 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:22 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 05:49:22 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 05:49:22 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 25 05:49:22 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 25 05:49:22 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 25 05:49:22 volumio volumio[18355]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 25 05:49:22 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 25 05:49:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Feb 25 05:49:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:22 volumio go-librespot[18941]: go-librespot daemon starting...
Feb 25 05:49:22 volumio go-librespot[18942]: time="2026-02-25T05:49:22-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:22 volumio go-librespot[18942]: time="2026-02-25T05:49:22-06:00" level=debug msg="app state loaded"
Feb 25 05:49:22 volumio go-librespot[18942]: time="2026-02-25T05:49:22-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:22 volumio go-librespot[18942]: time="2026-02-25T05:49:22-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:23 volumio volumio[18355]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 25 05:49:23 volumio volumio[18355]: info: Completed starting MyVolumio Plugin
Feb 25 05:49:23 volumio volumio[18355]:  [Metrics]  CommandRouter:  55s 223.51ms 
Feb 25 05:49:23 volumio volumio[18355]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 25 05:49:23 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 05:49:23 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 05:49:23 volumio volumio[18355]: info: CoreCommandRouter::Close All Modals sent
Feb 25 05:49:23 volumio volumio[18355]: info: CoreCommandRouter::Close All Modals sent
Feb 25 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-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 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-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 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-06:00" level=info msg="zeroconf server listening on port 37729"
Feb 25 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-06:00" level=debug msg="obtained new client token: AACjqUr2wVkQsYDdyuaEsusxcDbKc3LGtlKXoX8Z4PvowcTiU0jqsGBOybcmZrVwQU50/LqLzU4xvTz+q/ZUvTxZ+8JRwH9byvKUW4kUu4EcG8knvEcweIr63yMz2pue0rzZCd0VZCW/5Vdd6eb3BXZEF591CrrOR6ycU+XP31nofc/Qae13udW6E238EPRzmZkuEjgLfx3qJS+MChF4gu2aFtNPUpDkhGLqNR7v7Uqt2/Ril1TD7i6RqA=="
Feb 25 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-06:00" level=debug msg="completed challenge"
Feb 25 05:49:23 volumio go-librespot[18942]: time="2026-02-25T05:49:23-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:24 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 25 05:49:24 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 25 05:49:24 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 25 05:49:25 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:25 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Feb 25 05:49:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:26 volumio go-librespot[18955]: go-librespot daemon starting...
Feb 25 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-06:00" level=debug msg="app state loaded"
Feb 25 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-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 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-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 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-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 05:49:26 volumio go-librespot[18956]: time="2026-02-25T05:49:26-06:00" level=info msg="zeroconf server listening on port 40515"
Feb 25 05:49:27 volumio go-librespot[18956]: time="2026-02-25T05:49:27-06:00" level=debug msg="obtained new client token: AABtgBjgfVP3tDe+OgGuaN+ME0PlQ0G/k61YvD2zEoI6O20V7TyCbaqBJf9ndjE2OVswpszotnCK1TvDkxnMP312IWrIGuW23XaC8wwW780mBxOZv71jmjneYa00HlTznp3cSNCUmd0ua0mhTklayUUPPm6cY82K7GYXLTzoZHtDTZiIus5o65qhZQfwOJ4Dinr3Bt17zsKlRiLEYbiCzVaZwtM5/4iGHJL2xXEf/nLk9qE2Z25sUWs="
Feb 25 05:49:27 volumio go-librespot[18956]: time="2026-02-25T05:49:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:27 volumio go-librespot[18956]: time="2026-02-25T05:49:27-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:27 volumio go-librespot[18956]: time="2026-02-25T05:49:27-06:00" level=debug msg="completed challenge"
Feb 25 05:49:27 volumio go-librespot[18956]: time="2026-02-25T05:49:27-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:28 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:28 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:30 volumio volumio[18355]: info: BOOT COMPLETED
Feb 25 05:49:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Feb 25 05:49:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:30 volumio go-librespot[18977]: go-librespot daemon starting...
Feb 25 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-06:00" level=debug msg="app state loaded"
Feb 25 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-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 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-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 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-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 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-06:00" level=info msg="zeroconf server listening on port 37465"
Feb 25 05:49:30 volumio go-librespot[18978]: time="2026-02-25T05:49:30-06:00" level=debug msg="obtained new client token: AAAmFX2BGTq8mux0y1qJt2a5hC1LQzxC68kWgkNZL81FHuqy+t84b95Pyi3kYrXT8RTkKvnywTmJYheUy2dHe5qwKYI1lAIlDrd1X3rSVHge06SpOeeL1YMp0/QGftYS0ckb2PijF6K4F4e3juRTQpeYJuhNoFm2vlmSIAo6lIt4GNLusBQfFQU8fMzgULSExPZn4ceX/SqP0YS/gIjN7m1pCiDUcd8FAPR4HgdOctrunY/PdmfJcxSXhQ=="
Feb 25 05:49:31 volumio go-librespot[18978]: time="2026-02-25T05:49:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:31 volumio go-librespot[18978]: time="2026-02-25T05:49:31-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:31 volumio go-librespot[18978]: time="2026-02-25T05:49:31-06:00" level=debug msg="completed challenge"
Feb 25 05:49:31 volumio go-librespot[18978]: time="2026-02-25T05:49:31-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:31 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:31 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:34 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Feb 25 05:49:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:34 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:34 volumio go-librespot[18989]: go-librespot daemon starting...
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="app state loaded"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=info msg="zeroconf server listening on port 33189"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="obtained new client token: AABnlZmjG6ZjfA59tkNICC004Mqs5+ffbpnUrD4dWX8/2dpsSuvfUaFAcGJWGL4redwmTO/aaTzqiU8JrETUm+f7SqXRbwP21tcbjh/TW+rm/LH5INFJc/GUaFGCP7XQ9mOO3p01BpdXJN/qxyJB796p/2kgoxs9UK6q0qZ02HbQ+xyRYtobjcn0CLEzbTakKs6sgDkVLF0typQovpGxADfwt3SF+5aqnmnkrQC1naqcckztou1fbh2a0Q=="
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-06:00" level=debug msg="completed challenge"
Feb 25 05:49:34 volumio go-librespot[18990]: time="2026-02-25T05:49:34-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 05:49:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:37 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:37 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Feb 25 05:49:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:37 volumio go-librespot[18997]: go-librespot daemon starting...
Feb 25 05:49:37 volumio go-librespot[18998]: time="2026-02-25T05:49:37-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:37 volumio go-librespot[18998]: time="2026-02-25T05:49:37-06:00" level=debug msg="app state loaded"
Feb 25 05:49:37 volumio go-librespot[18998]: time="2026-02-25T05:49:37-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:37 volumio go-librespot[18998]: time="2026-02-25T05:49:37-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-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 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-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 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-06:00" level=info msg="zeroconf server listening on port 34745"
Feb 25 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-06:00" level=debug msg="obtained new client token: AABNHLh/b1DJCxXMDDZelSBn5p8FvpasYNmKQwLqHFWQL12NtcN8nH5aMEfLbstT7t3jtUZLd9YIH5RIxQgvYyhqgdGZJ3IQVbgQgooqoXwM0yYinF8osVVGaiyBN7MZLDi3ErcwN5rVG4wTGoyV7+4tKrD0MkDa1JdXHZjcZheVOvE+qoNQkMl94fVQLcCVEeuhAF058LPDHKQ3FGtQdWvhS2oaufwYkd/xzjiMnNCs02Wfe7sMoMs="
Feb 25 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-06:00" level=debug msg="completed challenge"
Feb 25 05:49:38 volumio go-librespot[18998]: time="2026-02-25T05:49:38-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:40 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:40 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Feb 25 05:49:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:41 volumio go-librespot[19019]: go-librespot daemon starting...
Feb 25 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-06:00" level=debug msg="app state loaded"
Feb 25 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-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 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-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 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-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 05:49:41 volumio go-librespot[19020]: time="2026-02-25T05:49:41-06:00" level=info msg="zeroconf server listening on port 46011"
Feb 25 05:49:42 volumio go-librespot[19020]: time="2026-02-25T05:49:42-06:00" level=debug msg="obtained new client token: AAA671kdmZGnFKaUYcyap2ySG9eb5KBkZKCNCSu3pd8w/VAeqNJTC2hUoL0R4//kcCUpsXrwiWgvddFtxeGlyyJABIq0tR8iqMbKda9IpYtO9aH7FF5LpaYbyWqtO4J+rX+vyl2bYyCE4xIOVKvggEHjpP/sEb/hjC9wE+v0OeJTPiQETvW/z7I1GK7vwN9oq9snzNkfRQdA8fikJ9+roPusZDe6BoHaArisrnPtyvnMYnWhDbcNCS4="
Feb 25 05:49:42 volumio go-librespot[19020]: time="2026-02-25T05:49:42-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 05:49:42 volumio go-librespot[19020]: time="2026-02-25T05:49:42-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 25 05:49:42 volumio go-librespot[19020]: time="2026-02-25T05:49:42-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:42 volumio go-librespot[19020]: time="2026-02-25T05:49:42-06:00" level=debug msg="completed challenge"
Feb 25 05:49:42 volumio go-librespot[19020]: time="2026-02-25T05:49:42-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:43 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:43 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:44 volumio volumio[18355]: info: UPDATER: Scheduling automatic update
Feb 25 05:49:44 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Feb 25 05:49:44 volumio volumio[18355]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Feb 25 05:49:44 volumio volumio[18355]: info: UPDATER: Auto update will take place at: Thu Feb 26 2026 03:35:50 GMT-0600 (Central Standard Time)
Feb 25 05:49:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Feb 25 05:49:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:45 volumio go-librespot[19027]: go-librespot daemon starting...
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=debug msg="app state loaded"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-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 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-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 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=info msg="zeroconf server listening on port 38155"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=debug msg="obtained new client token: AACamSlWXK0C3LyRfBk/+5pSStHFSAIrMjO55XNp0C7UHToP5XhKlN4bWjRvA+4H1cUj02vq28L2AgntXTmrO7BzBda3SkVdJw0l/4WxAX6wjPMjP5QTOk2e2E8CDvPsfjumXMhXUwXwZDpk3t8LwjK+Sl86nVKI0N8uJalMM6gwuPasl1HvnK/mCz5azwV0t1eiDxSFaN7Vs+9iuUySN6FnJTFbQU84m3wDw6hJjkpigQAANp805jpdTA=="
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-06:00" level=debug msg="completed challenge"
Feb 25 05:49:45 volumio go-librespot[19028]: time="2026-02-25T05:49:45-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 05:49:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:46 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:46 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Feb 25 05:49:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:49 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:49 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:49 volumio go-librespot[19035]: go-librespot daemon starting...
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=debug msg="app state loaded"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-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 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-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 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=info msg="zeroconf server listening on port 46635"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=debug msg="obtained new client token: AAC2qVBXe+nCJMGluVlak0G/tALFHlGbvRNmE60QEQ0luBZoAgy8ZzE6day/MFxtHlw2FlWj6Tt9H4koKk+esHFGcWRc6+b8LfQYA17pJJvJxty74vqHbAA5ZuutB4Nkk9S99Gz/tzkWooMVOGon+4Gn/1NcwQPAOtq8YlGf16Rk1HCkuyf+X+35NTd1dJC+kRpmHDRlbt70j2VIqUaUW5iD40kNNSZoEvwsrp8lLBXUgd60Flvbd+WV5g=="
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-06:00" level=debug msg="completed challenge"
Feb 25 05:49:49 volumio go-librespot[19036]: time="2026-02-25T05:49:49-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 05:49:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:52 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:52 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Feb 25 05:49:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:52 volumio go-librespot[19057]: go-librespot daemon starting...
Feb 25 05:49:52 volumio go-librespot[19058]: time="2026-02-25T05:49:52-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:52 volumio go-librespot[19058]: time="2026-02-25T05:49:52-06:00" level=debug msg="app state loaded"
Feb 25 05:49:52 volumio go-librespot[19058]: time="2026-02-25T05:49:52-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:52 volumio go-librespot[19058]: time="2026-02-25T05:49:52-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-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 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-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 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-06:00" level=info msg="zeroconf server listening on port 41577"
Feb 25 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-06:00" level=debug msg="obtained new client token: AADEfrSXBtMBZUnJ9AAFLipeq91PLSxH3BeG23DftILhCJILVeKVnUdtFXDwnmw+CNFUWMGQdlvUIfwNy8PeYWHIQPQbkf5wCm4MvKCTXNWkS9x7+S3zkHWAfwimzvpe+N9StX0jjEY6zExQUiMmlemfbJKR07kJ5+EdLDMAyRcI5H6l632C9YFMY2itFRgB3wMIDcbogpXHq6pgVYSQL//AuhbVStc1pLG4usgHFlWSUVgNCU0qkRTd1Q=="
Feb 25 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-06:00" level=debug msg="completed challenge"
Feb 25 05:49:53 volumio go-librespot[19058]: time="2026-02-25T05:49:53-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:55 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:55 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:49:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Feb 25 05:49:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:49:56 volumio go-librespot[19068]: go-librespot daemon starting...
Feb 25 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-06:00" level=debug msg="app state loaded"
Feb 25 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-06:00" level=debug msg="stored credentials not found"
Feb 25 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-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 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-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 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-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 05:49:56 volumio go-librespot[19069]: time="2026-02-25T05:49:56-06:00" level=info msg="zeroconf server listening on port 39037"
Feb 25 05:49:57 volumio go-librespot[19069]: time="2026-02-25T05:49:57-06:00" level=debug msg="obtained new client token: AADo6UYLzLYnXdXg823H7j1Zt1AkE8faNdUnCqrhipPbMo27jB4k2J0Tn2EmyJGgi+krqMLEfEKhnmwfx2SSnGEdkdvkjc9scH10xEhAHM6W0Jwh0IwtjYuyapa6RehX0Z7SBNYIHmGZVtmmqnvHIFmiBfdzLSSgikaADcaSjPxvuqUfmbhaGTydTZtEMuO1D+ypPy5gUDNo7n/38IlUs55rDUNZp8zkZ96PIw4lEuzDIGFx/Sr6qR4="
Feb 25 05:49:57 volumio go-librespot[19069]: time="2026-02-25T05:49:57-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:49:57 volumio go-librespot[19069]: time="2026-02-25T05:49:57-06:00" level=debug msg="completed keyexchange"
Feb 25 05:49:57 volumio go-librespot[19069]: time="2026-02-25T05:49:57-06:00" level=debug msg="completed challenge"
Feb 25 05:49:57 volumio go-librespot[19069]: time="2026-02-25T05:49:57-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:49:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:49:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:49:58 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:49:58 volumio volumio[18355]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:50:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Feb 25 05:50:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:50:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:50:00 volumio go-librespot[19091]: go-librespot daemon starting...
Feb 25 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-06:00" level=debug msg="app state loaded"
Feb 25 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-06:00" level=debug msg="stored credentials not found"
Feb 25 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-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 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-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 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-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 05:50:00 volumio go-librespot[19092]: time="2026-02-25T05:50:00-06:00" level=info msg="zeroconf server listening on port 32953"
Feb 25 05:50:01 volumio go-librespot[19092]: time="2026-02-25T05:50:01-06:00" level=debug msg="obtained new client token: AAAXYmBpjHWnd0f0xaz+X0vmsCPNjZANI0YTJi7WOhZSlR3WdkGnlB+cioBKwVy0CETSwOSHMI76+C4eKUa6dVprcWOTV5+k0lgclD19gVdlHGlAJHVUjzF1Doqusc9QlBA/+Y+Lz9Dz2FVq9zfyvE+ygr4QwPhXXpJ5Ee+3cE4SGTuXvMUcWz8Mz85fuZJmzYM2wJALdkF+7p5Pu+J2c/ZltjAtWwSyi56ZpNY0D3DSpvn8SjmSMos="
Feb 25 05:50:01 volumio go-librespot[19092]: time="2026-02-25T05:50:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:50:01 volumio volumio[18355]: info: Initializing connection to go-librespot Websocket
Feb 25 05:50:01 volumio go-librespot[19092]: time="2026-02-25T05:50:01-06:00" level=debug msg="new websocket client"
Feb 25 05:50:01 volumio go-librespot[19092]: time="2026-02-25T05:50:01-06:00" level=debug msg="completed keyexchange"
Feb 25 05:50:01 volumio go-librespot[19092]: time="2026-02-25T05:50:01-06:00" level=debug msg="completed challenge"
Feb 25 05:50:01 volumio volumio[18355]: info: Connection to go-librespot Websocket established
Feb 25 05:50:01 volumio go-librespot[19092]: time="2026-02-25T05:50:01-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:50:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:50:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:50:01 volumio volumio[18355]: info: Connection to go-librespot Websocket closed
Feb 25 05:50:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Feb 25 05:50:02 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 25 05:50:02 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 25 05:50:04 volumio volumio[18355]: info: Getting Spotify volume
Feb 25 05:50:04 volumio volumio[18355]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 25 05:50:04 volumio volumio[18355]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 05:50:04 volumio volumio[18355]:     at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 25 05:50:04 volumio volumio[18355]:   errno: -111,
Feb 25 05:50:04 volumio volumio[18355]:   code: 'ECONNREFUSED',
Feb 25 05:50:04 volumio volumio[18355]:   syscall: 'connect',
Feb 25 05:50:04 volumio volumio[18355]:   address: '127.0.0.1',
Feb 25 05:50:04 volumio volumio[18355]:   port: 9879,
Feb 25 05:50:04 volumio volumio[18355]:   response: undefined
Feb 25 05:50:04 volumio volumio[18355]: }
Feb 25 05:50:04 volumio volumio[18355]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 25 05:50:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Feb 25 05:50:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:50:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 05:50:04 volumio go-librespot[19136]: go-librespot daemon starting...
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=debug msg="app state loaded"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=debug msg="stored credentials not found"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-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 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-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 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=info msg="zeroconf server listening on port 34963"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=debug msg="obtained new client token: AADUjVowD2Q2aSi5KazNuvPGSXz13e7Wlr/sdZxkQkQ9RJsO5OP1dXGj2/VRYQUGF323AlbG9oAC1fr91LiBJJAEfqCm5KTkfxCXCMw5SlhjSN5Pdc5+asMF7hCeEIcUIbMY6r/jtNBzkhzB+pcHtw06kWQIHXv5CfIV63XfHjGhGCMzSQhKkzHrE2YoqniMG+B6d5XBHNq7DbH2EJQ7XEtywUc+4CvMnlKgzLjxfxoU99Pcqksswakg+w=="
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=debug msg="completed keyexchange"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=debug msg="completed challenge"
Feb 25 05:50:04 volumio go-librespot[19137]: time="2026-02-25T05:50:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 25 05:50:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 05:50:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 05:50:05 volumio sudo[19147]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 05:49'
Feb 25 05:50:05 volumio sudo[19147]: 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"