Dec 08 10:31:00 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:00 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Dec 08 10:31:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:02 volumio go-librespot[4623]: go-librespot daemon starting... Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=debug msg="app state loaded" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-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]" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-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]" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=info msg="zeroconf server listening on port 36239" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=debug msg="obtained new client token: AAC/Rs062bypmO3pOq29x0gv1ootEyEfo/FzsLRdBaO4l84ghtNXi9WCW6Zo9QQ9FVLmh94XoYh56/rJHvmJqLqHwr2OeLk2PjcfgvrRGUUjvuQi0qjWo5qDcV0HMsQZ6INur3uiaLNaiUofuHL3nSN8QT19hj8+elJ/j+vT2PwP7AXy3HGtR6DuFu9IIydzXSFBV2grPyfEWPcR+6i+P/Lsq6o3vEpRUMaVIIl2SBUFzeTytxa8sljQ1Q==" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-06:00" level=debug msg="completed challenge" Dec 08 10:31:02 volumio go-librespot[4624]: time="2025-12-08T10:31:02-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 " Dec 08 10:31:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:03 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:03 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 08 10:31:03 volumio volumio[4155]: info: Adding plugin bluetooth to MyMusic Plugins Dec 08 10:31:03 volumio volumio[4155]: info: Adding plugin multiroom to MyMusic Plugins Dec 08 10:31:03 volumio volumio[4155]: info: Adding plugin metavolumio to MyMusic Plugins Dec 08 10:31:03 volumio volumio[4155]: info: Adding plugin cd_controller to MyMusic Plugins Dec 08 10:31:03 volumio volumio[4155]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 08 10:31:03 volumio volumio[4155]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 08 10:31:03 volumio volumio[4155]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 08 10:31:03 volumio volumio[4155]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 08 10:31:04 volumio volumio[4155]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 08 10:31:04 volumio volumio[4155]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 08 10:31:04 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:04 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:04 volumio volumio[4155]: info: Starting MyVolumio Remote Streaming Endpoints Dec 08 10:31:04 volumio volumio[4155]: info: MyVolumio login type: Token Dec 08 10:31:04 volumio volumio[4155]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 08 10:31:04 volumio volumio[4155]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 08 10:31:05 volumio volumio[4155]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 08 10:31:05 volumio volumio[4155]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 08 10:31:05 volumio volumio[4155]: info: Streaming services startup Dec 08 10:31:05 volumio volumio[4155]: info: Starting Streaming Daemon Dec 08 10:31:05 volumio sudo[4647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 08 10:31:05 volumio sudo[4647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 10:31:05 volumio volumio[4155]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 08 10:31:05 volumio sudo[4647]: pam_unix(sudo:session): session closed for user root Dec 08 10:31:05 volumio volumio[4155]: error: Cannot start Volumio Streaming Daemon Dec 08 10:31:05 volumio volumio[4155]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 08 10:31:05 volumio volumio[4155]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 08 10:31:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Dec 08 10:31:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:05 volumio volumio[4155]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 08 10:31:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:05 volumio go-librespot[4653]: go-librespot daemon starting... Dec 08 10:31:05 volumio go-librespot[4654]: time="2025-12-08T10:31:05-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:05 volumio go-librespot[4654]: time="2025-12-08T10:31:05-06:00" level=debug msg="app state loaded" Dec 08 10:31:05 volumio go-librespot[4654]: time="2025-12-08T10:31:05-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:05 volumio go-librespot[4654]: time="2025-12-08T10:31:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:05 volumio go-librespot[4654]: time="2025-12-08T10:31:05-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]" Dec 08 10:31:05 volumio go-librespot[4654]: time="2025-12-08T10:31:05-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]" Dec 08 10:31:05 volumio go-librespot[4654]: time="2025-12-08T10:31:05-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]" Dec 08 10:31:06 volumio go-librespot[4654]: time="2025-12-08T10:31:06-06:00" level=info msg="zeroconf server listening on port 44061" Dec 08 10:31:06 volumio volumio[4155]: info: MyVolumio token set successfully Dec 08 10:31:06 volumio volumio[4155]: info: MYVOLUMIO: Adding device Dec 08 10:31:06 volumio volumio[4155]: info: MYVOLUMIO: Evaluating Server Dec 08 10:31:06 volumio go-librespot[4654]: time="2025-12-08T10:31:06-06:00" level=debug msg="obtained new client token: AACgUQTXm+ETYp1QIlC+j27Nlr4v3yqQKcnQVLig6ok10BDb9C0GMeAhzjbFX9upmfjnhaMuDFQaflJbpkdU5qQU+ds52Fa39wFczY0ANK+KQ8Fdhl5XSVxbkO1me3RbAFcvJduT5M4pBSrof9PqY0y4NLyLwpQ3eG1730k2EeS7WZPXMJERPFvNmx5oa5PP9s5Yn4kTQ5JE09IIGazXR+PpazCTwxuQnMEarEukNaW5ZL2Y5W7p+NA=" Dec 08 10:31:06 volumio go-librespot[4654]: time="2025-12-08T10:31:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:06 volumio go-librespot[4654]: time="2025-12-08T10:31:06-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:06 volumio go-librespot[4654]: time="2025-12-08T10:31:06-06:00" level=debug msg="completed challenge" Dec 08 10:31:06 volumio go-librespot[4654]: time="2025-12-08T10:31:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 10:31:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:06 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:06 volumio volumio[4155]: info: MyVolumio status changed Dec 08 10:31:06 volumio volumio[4155]: info: Streaming services startup Dec 08 10:31:06 volumio volumio[4155]: info: Starting Streaming Daemon Dec 08 10:31:06 volumio volumio[4155]: info: Removing browser output: myVolumio user plan is not superstar Dec 08 10:31:06 volumio volumio[4155]: info: Removing audio output: Dec 08 10:31:06 volumio volumio[4155]: info: Stoppping Tunnel 1 Dec 08 10:31:06 volumio sudo[4683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 08 10:31:06 volumio sudo[4683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 10:31:06 volumio sudo[4685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 08 10:31:06 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:06 volumio sudo[4685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio sudo[4683]: pam_unix(sudo:session): session closed for user root Dec 08 10:31:06 volumio volumio[4155]: error: Cannot start Volumio Streaming Daemon Dec 08 10:31:06 volumio volumio[4155]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 08 10:31:06 volumio volumio[4155]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 10:31:06 volumio sudo[4685]: pam_unix(sudo:session): session closed for user root Dec 08 10:31:06 volumio volumio[4155]: info: Remote SSH Stopped Dec 08 10:31:06 volumio volumio[4155]: info: Setting Geolocation for MyVolumio to us4 Dec 08 10:31:06 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:06 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:06 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:06 volumio volumio[4155]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 08 10:31:07 volumio volumio[4155]: info: Updating MyVolumio device info Dec 08 10:31:07 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:07 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:07 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:07 volumio volumio[4155]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 08 10:31:09 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:09 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Dec 08 10:31:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:09 volumio go-librespot[4688]: go-librespot daemon starting... Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=debug msg="app state loaded" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-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]" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-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]" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-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]" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=info msg="zeroconf server listening on port 36737" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=debug msg="obtained new client token: AABLfUzBjRQYsYoilZJ+aUFkd2Uj2GlyNGtDW9SX6RcuPbIZNypEegZRDugU2GyBw1wZXLmUXHVTZqrVdTgQ1N801f6/gkra513SoS1Aoug+76Vb0RnAC4fGIrFCs8sDUQmJ0SQVvCI1d/lCWGh89RJJp4t+b2kklYpWt508ytyZf0OOnzetfdXuzikDYeiHR036MTXQq3wkqxV11URAp5hF1sNKZVztLt441xEtUKow1sEPNxUQj3vzWQ==" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:09 volumio go-librespot[4689]: time="2025-12-08T10:31:09-06:00" level=debug msg="completed challenge" Dec 08 10:31:10 volumio go-librespot[4689]: time="2025-12-08T10:31: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 " Dec 08 10:31:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:10 volumio volumio[4155]: info: MYVOLUMIO: Adding device Dec 08 10:31:10 volumio volumio[4155]: info: MYVOLUMIO: Evaluating Server Dec 08 10:31:11 volumio volumio[4155]: info: Setting Geolocation for MyVolumio to us4 Dec 08 10:31:11 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:11 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:11 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:11 volumio volumio[4155]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 08 10:31:12 volumio volumio[4155]: info: Updating MyVolumio device info Dec 08 10:31:12 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:12 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:12 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:12 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:12 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:12 volumio volumio[4155]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 08 10:31:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Dec 08 10:31:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:13 volumio go-librespot[4714]: go-librespot daemon starting... Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=debug msg="app state loaded" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-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]" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-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]" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-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]" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=info msg="zeroconf server listening on port 40477" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=debug msg="obtained new client token: AAAo4WkJW14S2zJXNK3W1ectWECvtI+WXk0Zwgq7IIVfvFks+m8C5dAyiEOFhBYtwgez4MbOqAeKAgBuXQ7YtJlslJp9p/LTGb4GfWFPFDvqg+1QqFcjoDPoVB4XmphfilBztDP0jbNNKHlUflwVmf+o5lwTeeC4oPZrRiRgquUppD23RVDmPbJ7+xOj/SiMvFyjnO4qX7Uk4fAzJOgQ7YcUYsWsFMYSGbm7V5jib7jdBczkwqvgAMFrdA==" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-06:00" level=debug msg="completed challenge" Dec 08 10:31:13 volumio go-librespot[4715]: time="2025-12-08T10:31:13-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 " Dec 08 10:31:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:15 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:15 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:16 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 10:31:16 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:16 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 08 10:31:16 volumio volumio-remote-updater[9752]: Test mode disabled Dec 08 10:31:16 volumio volumio-remote-updater[9752]: Alpha mode disabled Dec 08 10:31:16 volumio volumio-remote-updater[9752]: Alpha legacy test mode disabled Dec 08 10:31:16 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 08 10:31:16 volumio volumio[4155]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

ANNOUNCEMENT

\n
    \n
  • Production line
    • \n
\n

FIX

\n
    \n
  • Nodejs 20.5.1 version pinning with static package fallback
    • \n
\n","title":"Update v4.073","updateavailable":true} Dec 08 10:31:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Dec 08 10:31:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:16 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 08 10:31:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:17 volumio go-librespot[4743]: go-librespot daemon starting... Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=debug msg="app state loaded" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-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]" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-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]" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=info msg="zeroconf server listening on port 42357" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=debug msg="obtained new client token: AAB/aLcT2uSTO6+E8UiXF7K3OQRRtx5mXqFlk+9frtKfiDDfufNiqv/A7XWJIjyPTJRYTg9kJKOtKpHHkDSIvYb/Nf/SqfJXkpbkUljvKqJDiuwxMvB/s2soy7JwHfu9f43Ri03pUqd004NB6HZ4ScgkAK7tTBSBOHd3o/clPKeDJdt/h+4yLj9ybaCsgKQJnJM9cW4ST5fYq1jdpfHt0m7JgwBMMpJrcEFwa00mHnI2umtpLORz6C7/og==" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-06:00" level=debug msg="completed challenge" Dec 08 10:31:17 volumio go-librespot[4744]: time="2025-12-08T10:31:17-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 " Dec 08 10:31:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:18 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:18 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:19 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 08 10:31:19 volumio volumio[4155]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 08 10:31:19 volumio volumio[4155]: info: CoreCommandRouter::volumioGetState Dec 08 10:31:19 volumio volumio[4155]: info: CorePlayQueue::getTrack 0 Dec 08 10:31:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Dec 08 10:31:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:20 volumio go-librespot[4751]: go-librespot daemon starting... Dec 08 10:31:20 volumio go-librespot[4752]: time="2025-12-08T10:31:20-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:20 volumio go-librespot[4752]: time="2025-12-08T10:31:20-06:00" level=debug msg="app state loaded" Dec 08 10:31:20 volumio go-librespot[4752]: time="2025-12-08T10:31:20-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:20 volumio go-librespot[4752]: time="2025-12-08T10:31:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31: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]" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31: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]" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31: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]" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31:21-06:00" level=info msg="zeroconf server listening on port 35993" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31:21-06:00" level=debug msg="obtained new client token: AADBYaTCDGYZ/rVcgjpVPJjvXgnHtiEV6bPnZPCEk2yEcS5xmDtrPvwvRQsWmhnhrRNevK6X77RJErm98UafYeQ0dqWSMmVz7DekeWIjtotASMHiyDlnchg8igmsMjAsuQVI2Y5OnuWX+c1v0MgyfvmSAcWtDBHOVhjF2rMsPEq3sSLaPhNjFkvwg3/ED8FGPHWs1N8gfQQBFG46n6WGQyBFuRBGsV5Ocl4jPO4zjHivuc4nwVcXGoY=" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31:21-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31:21-06:00" level=debug msg="completed challenge" Dec 08 10:31:21 volumio go-librespot[4752]: time="2025-12-08T10:31:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 10:31:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:21 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:21 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:24 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 10:31:24 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 10:31:24 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 08 10:31:24 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 08 10:31:24 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 08 10:31:24 volumio volumio[4155]: info: CoreCommandRouter::volumioGetBrowseSources Dec 08 10:31:24 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 10:31:24 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:24 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Dec 08 10:31:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:24 volumio go-librespot[4773]: go-librespot daemon starting... Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=debug msg="app state loaded" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-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]" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-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]" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-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]" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=info msg="zeroconf server listening on port 38687" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=debug msg="obtained new client token: AADI2ZDuFr/Nne50hLrr7Z6+44ZV9vm2qwZGpm0EQomtPr5C+XABGlsfmWpVRYqbtjJ6aulHNK6U2SJjHQtZlOUd/oF8Xp2Luzgtls+ASxk/jxIVxEfKpEWH+DNlZpZYt1QGBYKzonBmgGMAlI15jB6uynvpSej05Xy2WEf9TCWElnuFLXHx0ttJxQ3qdtsQdECRU2vhakprq2b2J+pTw4RHENQWDo2GEJPAy9XDQxxuKX733FF0810zew==" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:24 volumio go-librespot[4774]: time="2025-12-08T10:31:24-06:00" level=debug msg="completed challenge" Dec 08 10:31:25 volumio go-librespot[4774]: time="2025-12-08T10:31: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 " Dec 08 10:31:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:25 volumio volumio[4155]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 08 10:31:25 volumio volumio[4155]: info: Completed starting MyVolumio Plugin Dec 08 10:31:25 volumio volumio[4155]: [Metrics] CommandRouter: 54s 94.63ms Dec 08 10:31:25 volumio volumio[4155]: info: CoreCommandRouter::volumiosetStartupVolume Dec 08 10:31:25 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 10:31:25 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 10:31:25 volumio volumio[4155]: info: CoreCommandRouter::Close All Modals sent Dec 08 10:31:25 volumio volumio[4155]: info: CoreCommandRouter::Close All Modals sent Dec 08 10:31:26 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 08 10:31:26 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 08 10:31:26 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 08 10:31:27 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:27 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Dec 08 10:31:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:28 volumio go-librespot[4788]: go-librespot daemon starting... Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=debug msg="app state loaded" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-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]" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-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]" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-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]" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=info msg="zeroconf server listening on port 44067" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=debug msg="obtained new client token: AADSfbqFNlWu8UZ4uyh8deVrcVn2ussl6RQadvuMHVvrKAjSWCbXlI59wHBB+c3IwboBU+bDJA1p9/UkLnMQKWEn0in4RKRJrkTmL7YWqooXV/xZ+MAR0UHd7903u1dop3TvoYzyCNzegWDTxZRxCq03EsMocSv5HWg3KxPGgtpvI0gxse5+O3wzeu2pHoSIOWqMQFyNquN9HulkE9tn7rHHX77NBWDod+UCGPioS9atYpkMNyAqSNAEzg==" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-06:00" level=debug msg="completed challenge" Dec 08 10:31:28 volumio go-librespot[4789]: time="2025-12-08T10:31:28-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 " Dec 08 10:31:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:30 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:30 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Dec 08 10:31:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:31 volumio go-librespot[4796]: go-librespot daemon starting... Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=debug msg="app state loaded" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-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]" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-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]" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=info msg="zeroconf server listening on port 40183" Dec 08 10:31:32 volumio volumio[4155]: info: BOOT COMPLETED Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=debug msg="obtained new client token: AABS9y1Yri7LzJCEJfjuJkiwiA2eLKSl/tYb9ueRGYNV6BSqgwW52MQIeFuRXPY+LjrFRQcfOJut507ksgfov5yDrMB2ztO130C2vUqKvm+YWHGkEXiWgf1c+0bld0mYFh8xD1B/i6YUip1Enu/o+Oc5hFQP8PENjWmYQWe0agk0pra5ugFyHReCfmc6S4PxpvFpEbU4heeViYO6kGmYihfF0JEvzbiZgyD7nElQdqo1csZGgllndygsDw==" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-06:00" level=debug msg="completed challenge" Dec 08 10:31:32 volumio go-librespot[4797]: time="2025-12-08T10:31:32-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 " Dec 08 10:31:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:33 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:33 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Dec 08 10:31:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:35 volumio go-librespot[4821]: go-librespot daemon starting... Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-06:00" level=debug msg="app state loaded" Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-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]" Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-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]" Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-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]" Dec 08 10:31:35 volumio go-librespot[4822]: time="2025-12-08T10:31:35-06:00" level=info msg="zeroconf server listening on port 36359" Dec 08 10:31:36 volumio go-librespot[4822]: time="2025-12-08T10:31:36-06:00" level=debug msg="obtained new client token: AACarnr5AoyuCDP3yIr2J2U4jDfv/a73KwepkdGm2AXA2zNWNuVkK3yxst4dAhZ8lzgJmhkfRPhksYyEHNBKoprD1LlVmkF67en4FUUsOcL31ivOjcdW/IG9W3PXeM7gN8+e4RgTnukd5CR1c9JZgwkjuW/R7uPnI+3t61W7iF4tmvrUeIEIfcjiNsEMDScGUVW3jhO04SjsYQgso3CxVjfT1pnONUfnkCSzWB0yExNkdZ2puj8nPKM=" Dec 08 10:31:36 volumio go-librespot[4822]: time="2025-12-08T10:31:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:36 volumio go-librespot[4822]: time="2025-12-08T10:31:36-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:36 volumio go-librespot[4822]: time="2025-12-08T10:31:36-06:00" level=debug msg="completed challenge" Dec 08 10:31:36 volumio go-librespot[4822]: time="2025-12-08T10:31:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 10:31:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:36 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:36 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Dec 08 10:31:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:39 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:39 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:39 volumio go-librespot[4829]: go-librespot daemon starting... Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=debug msg="app state loaded" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-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]" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-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]" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-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]" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=info msg="zeroconf server listening on port 42811" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=debug msg="obtained new client token: AACOwrZpsxwHikmszQlbYTakckZ8ZC9elF8404sdOoaG0G3U2+d9gIO93bKwMFU3OJsdtF/iV8ZtAnCQ3XYj+ZmeA+++7fN7eau45JkE5ARlGaYTGv9i9Ey2WMPEE0XbOu9TnZu0UPoLR/pf998W87M/qSN4NmBy5ma183iJ4GKNn9188/2WnwsgUdBrdnq1cnvncG2uDf3zk0O/bWYCgpN+/tFTxqr2UlEMWvMWxGFXFX2Z3CoKXgS0lQ==" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:39 volumio go-librespot[4830]: time="2025-12-08T10:31:39-06:00" level=debug msg="completed challenge" Dec 08 10:31:40 volumio go-librespot[4830]: time="2025-12-08T10:31: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 " Dec 08 10:31:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:42 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:42 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Dec 08 10:31:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:43 volumio go-librespot[4837]: go-librespot daemon starting... Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=debug msg="app state loaded" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-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]" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-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]" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-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]" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=info msg="zeroconf server listening on port 44005" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=debug msg="obtained new client token: AACC+G7Ezqt4XBuHrQHosc6r2xpt2mB/KAXuDCquK6M/PQ1bDtLAok3ir3otVOBxmWpTCyTUA0l61vcGlwiVygjvUjsDUYHlokSkmBD8hSpf0blPnLuijthzrlO58kJD+W4kghYA3wbiHxAyjH9EtiGhUjpqoTDZGK0Xo+Hgp8xAqKzZCi2Sp0wc/V0eV9yepde468a6tjJsExti8/ADtF4iSCeWHdZJSC4YO8yjFzwTik5Y86t3YLHHkA==" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-06:00" level=debug msg="completed challenge" Dec 08 10:31:43 volumio go-librespot[4838]: time="2025-12-08T10:31:43-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 " Dec 08 10:31:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:45 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:45 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:46 volumio volumio[4155]: info: UPDATER: Scheduling automatic update Dec 08 10:31:46 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Dec 08 10:31:46 volumio volumio[4155]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Dec 08 10:31:46 volumio volumio[4155]: info: UPDATER: Auto update will take place at: Tue Dec 09 2025 03:31:55 GMT-0600 (Central Standard Time) Dec 08 10:31:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Dec 08 10:31:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:46 volumio go-librespot[4860]: go-librespot daemon starting... Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=debug msg="app state loaded" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-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]" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-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]" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=info msg="zeroconf server listening on port 42425" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=debug msg="obtained new client token: AAB04IaONZu3LTePDMdjRiNUiV7Vce9AxXs8kNK1FeTHIUEZonlZWEaW8NXiTY+9wiKjhRbEXUmD/7PEMbqeQ5TZUO+s7Dg5SkgUEIlFHJ4pFcvyxvZDtF2JYkehfih7aEPiH6ckNV5IQagQQOuPPLXKmXsclnDpjdpnWuyvvuTlKLNRfdj2dS9unRri0J+bG1HnMtSSKZBMQ5NJagw118Q9CKsQBIA2m7aC0tUEY7H64vxxAmBGBkjuqw==" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-06:00" level=debug msg="completed challenge" Dec 08 10:31:47 volumio go-librespot[4861]: time="2025-12-08T10:31:47-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 " Dec 08 10:31:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:48 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:48 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Dec 08 10:31:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:50 volumio go-librespot[4868]: go-librespot daemon starting... Dec 08 10:31:50 volumio go-librespot[4869]: time="2025-12-08T10:31:50-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:50 volumio go-librespot[4869]: time="2025-12-08T10:31:50-06:00" level=debug msg="app state loaded" Dec 08 10:31:50 volumio go-librespot[4869]: time="2025-12-08T10:31:50-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:50 volumio go-librespot[4869]: time="2025-12-08T10:31:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:50 volumio go-librespot[4869]: time="2025-12-08T10:31:50-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]" Dec 08 10:31:50 volumio go-librespot[4869]: time="2025-12-08T10:31:50-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]" Dec 08 10:31:50 volumio go-librespot[4869]: time="2025-12-08T10:31:50-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]" Dec 08 10:31:51 volumio go-librespot[4869]: time="2025-12-08T10:31:51-06:00" level=info msg="zeroconf server listening on port 41965" Dec 08 10:31:51 volumio go-librespot[4869]: time="2025-12-08T10:31:51-06:00" level=debug msg="obtained new client token: AAC9Bscq4lXXS3NMOe/yzH/7Nhb0/QoU/cLobbA10huE8DNPC1KrLQfIbejI5sMQ5V7v98fXGNUCgQhtUFNu8AaKKrUPe6BxHCfZFsmppXkTQkNk+X7m8I4GOJQjej+ymJcZNQ4HC9N9YaLMrekyDX1Yz0TrzlCPBPI0KO4tqh+ujFsBhRchh/i6rp/2hmNPz/OMckN1okwrtaDaln5ns2U8bMaKMH33aB9xmohMdhxUza10PFXM4G4=" Dec 08 10:31:51 volumio go-librespot[4869]: time="2025-12-08T10:31:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:51 volumio go-librespot[4869]: time="2025-12-08T10:31:51-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:51 volumio go-librespot[4869]: time="2025-12-08T10:31:51-06:00" level=debug msg="completed challenge" Dec 08 10:31:51 volumio go-librespot[4869]: time="2025-12-08T10:31:51-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 10:31:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:51 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:51 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Dec 08 10:31:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:54 volumio go-librespot[4890]: go-librespot daemon starting... Dec 08 10:31:54 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:54 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=debug msg="app state loaded" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-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]" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-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]" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-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]" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=info msg="zeroconf server listening on port 45617" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=debug msg="obtained new client token: AAASqYM0JZLMhl9lPZfNBohNtvs1xCjBYNjMlrhspkwrOmB9E0efyVdGPIit4IgMOhH60T/4pE02K3/iJCuOa9nz5aHneRI3EziQxmQMYdNM2lF3aKefiKlH5KI0nEpL1grqEHzl/q+eLcpRG10YbS3hGoIMCwZv7l2PcVd3JNnnqh2+u0KSWtqKEr2mqQJP3/lmyT08TwK+VfNtQTyN1Sdoc0X9Eci40+7ya3DJxQIrYGt7VcQh7xamJQ==" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-06:00" level=debug msg="completed challenge" Dec 08 10:31:54 volumio go-librespot[4891]: time="2025-12-08T10:31:54-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 " Dec 08 10:31:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:31:57 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:31:57 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:31:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Dec 08 10:31:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:31:58 volumio go-librespot[4901]: go-librespot daemon starting... Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=debug msg="app state loaded" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=debug msg="stored credentials not found" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-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]" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-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]" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-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]" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=info msg="zeroconf server listening on port 40529" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=debug msg="obtained new client token: AABNtZupXRsRkVQXSjaALJ0SeOXKWK2+rgL8stTQKx7TVRZmKfukAzU2Lmx3s6Akw6xNICcPhKmw/Zr0QaE2KoQJk3luZfJ0GmD/dwQ/ttqOBkBOAILBtM3MptDiLqyrf5X0rJu7tFqjwpldov5vqSiaj5GfDlFcnWnZGTjzCW0wKDJ1ozQBNz0GRMoTy2VW6pMgnkxrHJe30+gqQCrP25u/6XmMx0aOQhSAodIQUsSnn/jNipXwREZcVg==" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=debug msg="completed keyexchange" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-06:00" level=debug msg="completed challenge" Dec 08 10:31:58 volumio go-librespot[4902]: time="2025-12-08T10:31:58-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 " Dec 08 10:31:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:31:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:32:00 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:00 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Dec 08 10:32:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:01 volumio go-librespot[4909]: go-librespot daemon starting... Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="app state loaded" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="stored credentials not found" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=info msg="zeroconf server listening on port 39115" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="obtained new client token: AAA9NBS0uBwSOEie5Hgwj0SMU6B8Ocdt6vK58dJWfBuXxVdVGVpQWcU724bEkiRe7xoRwCb0/Ga2l3CNB+cwTLS7PI41NpZzoVc6jvJrSKQYNRhyI9kLU9XNj+BcIzz+xfx8RoI40w+QxuyKL9Nv6u6bhqgVeMzrXcoJ7PIHxuY1/U0xq4Pp6fH+u3a5aW1moqz0rhCKTZFWRsP9vRRQUBC3gkCzyf5E6AwD9R7GXIM7y5sFsXjNGbYKeA==" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="completed keyexchange" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-06:00" level=debug msg="completed challenge" Dec 08 10:32:02 volumio go-librespot[4910]: time="2025-12-08T10:32:02-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 " Dec 08 10:32:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:32:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:32:03 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:03 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Dec 08 10:32:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:05 volumio go-librespot[4931]: go-librespot daemon starting... Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-06:00" level=debug msg="app state loaded" Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-06:00" level=debug msg="stored credentials not found" Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-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]" Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-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]" Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-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]" Dec 08 10:32:05 volumio go-librespot[4932]: time="2025-12-08T10:32:05-06:00" level=info msg="zeroconf server listening on port 35347" Dec 08 10:32:06 volumio go-librespot[4932]: time="2025-12-08T10:32:06-06:00" level=debug msg="obtained new client token: AACDJCoY2iFGrqzfz3gja8dKyh29GCZhSGObgIvPThIo2mnR77nqraOJreWy7ZNV3C16YugGqLE/QAD8e06jqwk/lZKkUAJyUwYRnM3vivxb64LObCNnF1vc4PtTfgrV79lgvzewpgrJA3lw4t74rq82VmLWWyGvGbfpbJFlrzkCq2JP/+nFrpNNLCGEFAX/FSeSl6VinSvmNvKFWsqvsb43ved6prMZ9BKha0KJIWJDwpa93KBbDJU=" Dec 08 10:32:06 volumio go-librespot[4932]: time="2025-12-08T10:32:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:32:06 volumio go-librespot[4932]: time="2025-12-08T10:32:06-06:00" level=debug msg="completed keyexchange" Dec 08 10:32:06 volumio go-librespot[4932]: time="2025-12-08T10:32:06-06:00" level=debug msg="completed challenge" Dec 08 10:32:06 volumio go-librespot[4932]: time="2025-12-08T10:32:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 10:32:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:32:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:32:06 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:06 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Dec 08 10:32:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:09 volumio go-librespot[4940]: go-librespot daemon starting... Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=debug msg="app state loaded" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=debug msg="stored credentials not found" Dec 08 10:32:09 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:32:09 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-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]" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-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]" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-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]" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=info msg="zeroconf server listening on port 45047" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=debug msg="obtained new client token: AADkPPFpTZKcR+dq1Uar7qIhcGxqwtXHaARkGYDY1VerWM/BH5XmQmFmXllJaSfdXYncTHlxiPw8QbIXxIP44y//L3ESgtyDokG09gxwbJbGJB4lFbxoQUmFoE4r2oLM0bp0440HdCubcLhY2eEfd9FRWjWwNcyuN93A0EvVyn+JI36wz26w/icnrtlEgv1zoMwFb6DptT/YEiD2Uf8dE/oZkYXnSKGoGUxBacQJYh75g+T1ENpyv3NiKg==" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=debug msg="completed keyexchange" Dec 08 10:32:09 volumio go-librespot[4941]: time="2025-12-08T10:32:09-06:00" level=debug msg="completed challenge" Dec 08 10:32:10 volumio go-librespot[4941]: time="2025-12-08T10:32: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 " Dec 08 10:32:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:32:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:32:12 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:12 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Dec 08 10:32:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:13 volumio go-librespot[4948]: go-librespot daemon starting... Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=debug msg="app state loaded" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=debug msg="stored credentials not found" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-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]" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-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]" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-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]" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=info msg="zeroconf server listening on port 35179" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=debug msg="obtained new client token: AAB5ZVANOPIQcZ3tSwKDUuCkz4N2Ro/oW0CqF6CP7EkGJ2wipvmeUTP0mTKXvLGEYm/Kw+yGsTB4PTudpXub48C2FDPMqg4L9Sf6cEkIiFlLhM97KwAKDnjReE61ET1YJAaSzjih/AgBuwlNarBX8mjtkWBsenggXmrZKeyffYYJXbkOWnXHk11FYERvB0YM+gx6GpLeY0eBlG/PnX+2bkqVCAD5quotuhtvWizItIS2aM3Jy4oMfBUDZg==" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=debug msg="completed keyexchange" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-06:00" level=debug msg="completed challenge" Dec 08 10:32:13 volumio go-librespot[4949]: time="2025-12-08T10:32:13-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 " Dec 08 10:32:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:32:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:32:15 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:15 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Dec 08 10:32:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:16 volumio go-librespot[4973]: go-librespot daemon starting... Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=debug msg="app state loaded" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=debug msg="stored credentials not found" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-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]" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-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]" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=info msg="zeroconf server listening on port 45859" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=debug msg="obtained new client token: AAA0GBXMxlCCEG/R1MW+ykBh6X0NJVZ96IUAZPq+s1ANBGlrLzGkmcHL262GP4ByrvSmW+bJQp2tAhBQRONdIfWcSIt/oNKYmFtB3Kie8gmx0aeBpkzlnAIW2LfRDcHiJfSOo/qZOcaTejyyM+Lc5uhrWeq8CJuTfzWdZW1Kj7hS/T1aTc/5x4nSEHWsUySVpiqx8tu29ij+bF7yajYESz3RPMaGFQ34SyrX5T9Jv1eVSOK5hEIiWdhVRQ==" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=debug msg="completed keyexchange" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-06:00" level=debug msg="completed challenge" Dec 08 10:32:17 volumio go-librespot[4974]: time="2025-12-08T10:32:17-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 " Dec 08 10:32:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:32:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:32:18 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:18 volumio volumio[4155]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Dec 08 10:32:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:20 volumio go-librespot[4981]: go-librespot daemon starting... Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=debug msg="app state loaded" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=debug msg="stored credentials not found" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32: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]" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32: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]" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32: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]" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=info msg="zeroconf server listening on port 38569" Dec 08 10:32:21 volumio volumio[4155]: info: Initializing connection to go-librespot Websocket Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=debug msg="new websocket client" Dec 08 10:32:21 volumio volumio[4155]: info: Connection to go-librespot Websocket established Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=debug msg="obtained new client token: AAA1P/0AKXKpM9r5Gby67l934pLMlpzT5IOINPcGuOJUov1W32GPBq06YbpJHjTp+UP5hny93fy0GlQ+68zuS1m3iIfrOy18B5jr4LkkW95W2tibWwKrIaL6cEc4TZ16/mZyLy+sgNlwsbMYKy4Q+GsSsIrd3UN7tyed9Ev48neaw9T15Vzzs1JnPNDugxUZlebWNC6PGm4gWMIDUmrFjq7hKmuQ8OWVohMJFszSMcHKm1PxvMBLm43foQ==" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=debug msg="completed keyexchange" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=debug msg="completed challenge" Dec 08 10:32:21 volumio go-librespot[4982]: time="2025-12-08T10:32:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 10:32:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 10:32:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 10:32:21 volumio volumio[4155]: info: Connection to go-librespot Websocket closed Dec 08 10:32:24 volumio volumio[4155]: info: Getting Spotify volume Dec 08 10:32:24 volumio volumio[4155]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 10:32:24 volumio volumio[4155]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 10:32:24 volumio volumio[4155]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 08 10:32:24 volumio volumio[4155]: errno: -111, Dec 08 10:32:24 volumio volumio[4155]: code: 'ECONNREFUSED', Dec 08 10:32:24 volumio volumio[4155]: syscall: 'connect', Dec 08 10:32:24 volumio volumio[4155]: address: '127.0.0.1', Dec 08 10:32:24 volumio volumio[4155]: port: 9879, Dec 08 10:32:24 volumio volumio[4155]: response: undefined Dec 08 10:32:24 volumio volumio[4155]: } Dec 08 10:32:24 volumio volumio[4155]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 10:32:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Dec 08 10:32:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 10:32:25 volumio go-librespot[5015]: go-librespot daemon starting... Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32:25-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32:25-06:00" level=debug msg="app state loaded" Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32:25-06:00" level=debug msg="stored credentials not found" Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32:25-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32: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]" Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32: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]" Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32: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]" Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32:25-06:00" level=info msg="zeroconf server listening on port 44829" Dec 08 10:32:25 volumio sudo[5025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-08 10:31' Dec 08 10:32:25 volumio sudo[5025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 10:32:25 volumio go-librespot[5016]: time="2025-12-08T10:32:25-06:00" level=debug msg="obtained new client token: AACvG/nuoXMH1PmVe6dcZX4uySX4DEvWWCxNkQD8K36VH2VEnNARGSYXgG0YCh2j6YLVqGq4J1l9+/rdy+ZzwrxqdXN99BZ78VS2IQKlumzlvB/h2lRXUdsVHXNQ9g0QNW36GL3DdZVw+ECx4r23OLf3xScfh6LqdfZ243rRu5esXkasvmUq7JsFy9KKhPj4/oFgJg5W/lSrnKfSjyLgQ2ODsGUeDAON5Rb44c6vFjtUzjbvZAckXANtQw==" 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"