Dec 29 14:02:00 volumio sudo[26193]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:00 volumio mpd[26127]: 2025-12-29T14:02:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 29 14:02:00 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Dec 29 14:02:00 volumio sudo[26067]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:00 volumio sudo[25978]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:00 volumio sudo[25988]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:00 volumio volumio[25761]: error: MPD error: The expression evaluated to a falsy value:
Dec 29 14:02:00 volumio volumio[25761]: assert.ok(self.idling)
Dec 29 14:02:00 volumio volumio[25761]: error: The expression evaluated to a falsy value:
Dec 29 14:02:00 volumio volumio[25761]: assert.ok(self.idling)
Dec 29 14:02:00 volumio volumio[25761]: error: MPD error: The expression evaluated to a falsy value:
Dec 29 14:02:00 volumio volumio[25761]: assert.ok(self.idling)
Dec 29 14:02:00 volumio volumio[25761]: error: The expression evaluated to a falsy value:
Dec 29 14:02:00 volumio volumio[25761]: assert.ok(self.idling)
Dec 29 14:02:00 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:00 volumio volumio[25761]: error: updateQueue error: null
Dec 29 14:02:00 volumio volumio[25761]: info: MPD running with PID26127
Dec 29 14:02:00 volumio volumio[25761]: ,establishing connection
Dec 29 14:02:00 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:00 volumio volumio[25761]: error: updateQueue error: null
Dec 29 14:02:00 volumio volumio[25761]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 29 14:02:00 volumio sudo[26200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 29 14:02:00 volumio sudo[26200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:00 volumio sudo[26200]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:01 volumio volumio[25761]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 29 14:02:01 volumio sudo[26203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 29 14:02:01 volumio sudo[26203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:01 volumio sudo[26203]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:01 volumio volumio[25761]: info: Completed starting Core Plugins
Dec 29 14:02:01 volumio volumio[25761]: info: -------------------------------------------
Dec 29 14:02:01 volumio volumio[25761]: info: ----- MyVolumio plugins startup ----
Dec 29 14:02:01 volumio volumio[25761]: info: -------------------------------------------
Dec 29 14:02:01 volumio volumio[25761]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 29 14:02:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Dec 29 14:02:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:01 volumio go-librespot[26205]: go-librespot daemon starting...
Dec 29 14:02:01 volumio go-librespot[26206]: time="2025-12-29T14:02:01-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:01 volumio go-librespot[26206]: time="2025-12-29T14:02:01-06:00" level=debug msg="app state loaded"
Dec 29 14:02:01 volumio go-librespot[26206]: time="2025-12-29T14:02:01-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:01 volumio go-librespot[26206]: time="2025-12-29T14:02:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02: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-gew4.spotify.com:80]"
Dec 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02:02-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 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02:02-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 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02:02-06:00" level=info msg="zeroconf server listening on port 37789"
Dec 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02:02-06:00" level=debug msg="obtained new client token: AAAjfcjBQWOI9wkJFozFMmSm2asy3VkJcFTXteFZFyIEig8kr+If4htrR2o6/1o87QHdSv/gUjQ1xkYkoBAe7Izl8zJ+EjrF74Xd+dA3EfTIx3rZ353kMuJaPVnSl/mWKW1yZZyqVP2LXXDqg/q5RR9OwwRhbLNi9Jo3RaD5ZAIPX/xMy030AsB/f/xC8e5QfPyra2X07/iwnV02NPWSoRj3LbjrsFi0kyMg2nhHXLwsrY0rrm8vlBc="
Dec 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02:02-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02:02-06:00" level=debug msg="completed challenge"
Dec 29 14:02:02 volumio go-librespot[26206]: time="2025-12-29T14:02: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 29 14:02:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:03 volumio sudo[26214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 29 14:02:03 volumio sudo[26214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:03 volumio sudo[26216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 29 14:02:03 volumio sudo[26216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:03 volumio sudo[26218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 29 14:02:03 volumio sudo[26218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:03 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 29 14:02:03 volumio systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 29 14:02:03 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 29 14:02:03 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:03 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:03 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 29 14:02:03 volumio sudo[26214]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:03 volumio sudo[26218]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:03 volumio sudo[26216]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:03 volumio mpd_monitor.sh[26222]: MPD Monitor Service: Starting MPD Monitor Service
Dec 29 14:02:03 volumio volumio[25761]: info: Successfully started MPD Monitor
Dec 29 14:02:03 volumio volumio[25761]: info: Successfully started MPD Monitor
Dec 29 14:02:03 volumio volumio[25761]: info: Successfully started MPD Monitor
Dec 29 14:02:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Dec 29 14:02:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:05 volumio go-librespot[26227]: go-librespot daemon starting...
Dec 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02:05-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02:05-06:00" level=debug msg="app state loaded"
Dec 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02:05-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02: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-gew4.spotify.com:80]"
Dec 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02:05-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 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02:05-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 29 14:02:05 volumio go-librespot[26228]: time="2025-12-29T14:02:05-06:00" level=info msg="zeroconf server listening on port 38989"
Dec 29 14:02:06 volumio go-librespot[26228]: time="2025-12-29T14:02:06-06:00" level=debug msg="obtained new client token: AACvQHWbz+66qjvm4GdcXHlgQ6Sd00xdR7rL4muStc4wAZ6dNKhleL2dY9KmYYtbZRWUBnMMeq9pdOM5BHb8KjKxQdGGts1++baspz+Y+CMRjtnr3F2dM/r03wP7lcrfoVo4mHw8WdcPc4nng0sdKgUBpOaA2BqbySUHkvWvMWVVm6pDAoTCgcDLG0OG/gsjlJJ8fpX0cr5cdlDXeIsacTrK/4S9+wf8ZzJz/dHB7Chl8/cpFshEnbI="
Dec 29 14:02:06 volumio go-librespot[26228]: time="2025-12-29T14:02:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:06 volumio go-librespot[26228]: time="2025-12-29T14:02:06-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:06 volumio go-librespot[26228]: time="2025-12-29T14:02:06-06:00" level=debug msg="completed challenge"
Dec 29 14:02:06 volumio go-librespot[26228]: time="2025-12-29T14:02: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 29 14:02:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:06 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:06 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Dec 29 14:02:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:09 volumio go-librespot[26249]: go-librespot daemon starting...
Dec 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-06:00" level=debug msg="app state loaded"
Dec 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 29 14:02:09 volumio volumio[25761]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 29 14:02:09 volumio volumio[25761]: info: Adding plugin multiroom to MyMusic Plugins
Dec 29 14:02:09 volumio volumio[25761]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 29 14:02:09 volumio volumio[25761]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 29 14:02:09 volumio volumio[25761]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 29 14:02:09 volumio volumio[25761]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 29 14:02:09 volumio volumio[25761]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 29 14:02:09 volumio volumio[25761]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-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 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-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 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-06:00" level=info msg="zeroconf server listening on port 43795"
Dec 29 14:02:09 volumio kernel: hwmon hwmon1: Undervoltage detected!
Dec 29 14:02:09 volumio go-librespot[26250]: time="2025-12-29T14:02:09-06:00" level=debug msg="obtained new client token: AACsUngEBlgIlYRlTZQQHEMeeF36HuO3u/GN2cK5nTuKLI5q7/K1EKDTkSIkpksSvhMe8M5VGOZ/rlAvWQgm1NkTuvtpj+BR0eOJlAj1acdmwPKR8p9l01roikzQXD9YaORPP+4Ezkq+S+hyimP+r3JX1E6ymtoDvP7iNaayBRzOseeqKsD2SKwioMhRq3Nv6zu8yt48ItYhtHQQfLXH/THnbUVQdR1K/a7oFhB35qe/7aPKHm3Ub4OC2Q=="
Dec 29 14:02:10 volumio go-librespot[26250]: time="2025-12-29T14:02:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:10 volumio go-librespot[26250]: time="2025-12-29T14:02:10-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:10 volumio go-librespot[26250]: time="2025-12-29T14:02:10-06:00" level=debug msg="completed challenge"
Dec 29 14:02:10 volumio go-librespot[26250]: time="2025-12-29T14:02: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 29 14:02:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:11 volumio volumio[25761]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 29 14:02:11 volumio volumio[25761]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 29 14:02:11 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:11 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:11 volumio volumio[25761]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 29 14:02:11 volumio volumio[25761]: info: MyVolumio login type: Token
Dec 29 14:02:11 volumio volumio[25761]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 29 14:02:11 volumio volumio[25761]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 29 14:02:12 volumio volumio[25761]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 29 14:02:12 volumio volumio[25761]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 29 14:02:12 volumio volumio[25761]: info: Streaming services startup
Dec 29 14:02:12 volumio volumio[25761]: info: Starting Streaming Daemon
Dec 29 14:02:12 volumio sudo[26260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 29 14:02:12 volumio sudo[26260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:12 volumio volumio[25761]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 29 14:02:12 volumio sudo[26260]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:12 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:12 volumio volumio[25761]: error: Cannot start Volumio Streaming Daemon
Dec 29 14:02:12 volumio volumio[25761]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 29 14:02:12 volumio volumio[25761]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 29 14:02:12 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:12 volumio volumio[25761]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 29 14:02:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Dec 29 14:02:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:13 volumio go-librespot[26266]: go-librespot daemon starting...
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=debug msg="app state loaded"
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:13 volumio volumio[25761]: info: MyVolumio token set successfully
Dec 29 14:02:13 volumio volumio[25761]: info: MYVOLUMIO: Adding device
Dec 29 14:02:13 volumio volumio[25761]: info: MYVOLUMIO: Evaluating Server
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-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 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-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 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=info msg="zeroconf server listening on port 38981"
Dec 29 14:02:13 volumio volumio[25761]: info: MyVolumio status changed
Dec 29 14:02:13 volumio volumio[25761]: info: Streaming services startup
Dec 29 14:02:13 volumio volumio[25761]: info: Starting Streaming Daemon
Dec 29 14:02:13 volumio volumio[25761]: info: Removing browser output: myVolumio user plan is not superstar
Dec 29 14:02:13 volumio volumio[25761]: info: Removing audio output:
Dec 29 14:02:13 volumio volumio[25761]: info: Stoppping Tunnel 1
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=debug msg="obtained new client token: AAARRpIvFTobamlYU6Y6N7xkB9wh1GTNZFquTckZRpnGrCja/klcAEBTKOcf3hqO5WQQpIVCrEFmxeclUQ4TimJkpX4bM2SgCgelUP1tnmcx7r0fHiIRkJtVKS5aBR43htr6VnVWK9QDTBAtUu0N3n7quHfzFFE0Sqb/gljXIV3SYASwiEXqwRlr0g+DTUi65mv4Gk/qd22Qz+34IRioy6DV2fURXV8x1BildsuVOTJ+v94Lx5DQMIkLkg=="
Dec 29 14:02:13 volumio sudo[26295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 29 14:02:13 volumio sudo[26295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:13 volumio sudo[26297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 29 14:02:13 volumio sudo[26297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:13 volumio sudo[26295]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:13 volumio volumio[25761]: error: Cannot start Volumio Streaming Daemon
Dec 29 14:02:13 volumio volumio[25761]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 29 14:02:13 volumio volumio[25761]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 29 14:02:13 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 29 14:02:13 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 29 14:02:13 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 29 14:02:13 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 29 14:02:13 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 29 14:02:13 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 29 14:02:13 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 29 14:02:13 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 29 14:02:13 volumio sudo[26297]: pam_unix(sudo:session): session closed for user root
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02:13-06:00" level=debug msg="completed challenge"
Dec 29 14:02:13 volumio volumio[25761]: info: Remote SSH Stopped
Dec 29 14:02:13 volumio go-librespot[26267]: time="2025-12-29T14:02: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 29 14:02:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:13 volumio volumio[25761]: info: Setting Geolocation for MyVolumio to us1
Dec 29 14:02:13 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:13 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:13 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:14 volumio kernel: hwmon hwmon1: Voltage normalised
Dec 29 14:02:14 volumio volumio[25761]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 29 14:02:14 volumio volumio[25761]: info: Updating MyVolumio device info
Dec 29 14:02:14 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:14 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:14 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:15 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:15 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:15 volumio volumio[25761]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 29 14:02:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Dec 29 14:02:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:17 volumio go-librespot[26301]: go-librespot daemon starting...
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=debug msg="app state loaded"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-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 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-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 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=info msg="zeroconf server listening on port 41393"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=debug msg="obtained new client token: AADQ/k9yk0AcQhSnUXvfXp50sJveWADERnywOwN3i6DVDqYzNB5VQKaqyJAlMObdaVJ7/eLzGrJm6y83HJ9dA6U/tu6taPPgyKHQU6Q8MZiq7P+QY2OAwd7YW/uSys9PMy7ahclkmfNgoAtMElhSwrb3Byaxwl12oUavV4WEBbazVMyXdf3zNF+cFdMKIfmxFqG+YhkhEldgtgM5dW4+wu1oxP+JhDPWSiQmK9l0JZobIka+YcPJ2BaQ9w=="
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02:17-06:00" level=debug msg="completed challenge"
Dec 29 14:02:17 volumio go-librespot[26302]: time="2025-12-29T14:02: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 29 14:02:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:18 volumio volumio[25761]: info: MYVOLUMIO: Adding device
Dec 29 14:02:18 volumio volumio[25761]: info: MYVOLUMIO: Evaluating Server
Dec 29 14:02:18 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:18 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:18 volumio volumio[25761]: info: Setting Geolocation for MyVolumio to us1
Dec 29 14:02:18 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:18 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:18 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:18 volumio volumio[25761]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 29 14:02:19 volumio volumio[25761]: info: Updating MyVolumio device info
Dec 29 14:02:19 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:19 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:19 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:19 volumio volumio[25761]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 29 14:02:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Dec 29 14:02:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:20 volumio go-librespot[26341]: go-librespot daemon starting...
Dec 29 14:02:20 volumio go-librespot[26342]: time="2025-12-29T14:02:20-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:20 volumio go-librespot[26342]: time="2025-12-29T14:02:20-06:00" level=debug msg="app state loaded"
Dec 29 14:02:20 volumio go-librespot[26342]: time="2025-12-29T14:02:20-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:20 volumio go-librespot[26342]: time="2025-12-29T14:02:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=info msg="zeroconf server listening on port 39893"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=debug msg="obtained new client token: AAB8BRuxDxFkDpmAgPFYgaYoNU0v4tey45CxK4LQC1Ns6XrCWaPGFAQ33zdL31+EgsRJVwOOXaLIEYllM7nnDa98ZCoi1kF88fNbgrCqUQE24EOo2y8xVbsQW/ab09m+V5NVwYFoO36oGBAXbRT1kHQqeRLgV2nHz5xUw4axLjTyoOwbPu5q7hnhY7CR07hMsNee533yYz1ADebDrNM/rc/62fDTafG008KubNcHchr6N7ZTjVgdbYY="
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-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"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02:21-06:00" level=debug msg="completed challenge"
Dec 29 14:02:21 volumio go-librespot[26342]: time="2025-12-29T14:02: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 29 14:02:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:21 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:21 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:23 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 29 14:02:23 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:23 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 29 14:02:23 volumio volumio-remote-updater[742]: Test mode disabled
Dec 29 14:02:23 volumio volumio-remote-updater[742]: Alpha mode disabled
Dec 29 14:02:23 volumio volumio-remote-updater[742]: Alpha legacy test mode disabled
Dec 29 14:02:23 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 29 14:02:23 volumio volumio[25761]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"ANNOUNCEMENT
\n\n- Production line
\n
\nFIX
\n\n- Nodejs 20.5.1 version pinning with static package fallback
\n
\n","title":"Update v4.073","updateavailable":true}
Dec 29 14:02:23 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 29 14:02:24 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:24 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Dec 29 14:02:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:24 volumio go-librespot[26356]: go-librespot daemon starting...
Dec 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-06:00" level=debug msg="app state loaded"
Dec 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-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 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-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 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-06:00" level=info msg="zeroconf server listening on port 33849"
Dec 29 14:02:24 volumio go-librespot[26357]: time="2025-12-29T14:02:24-06:00" level=debug msg="obtained new client token: AAAJ2hzJfjLXZz5za3ujgi4+z9RhSPiK5ceKsdEbmxLeLca07IXzfzqrEuF8hyhuVIjByWLgikRj4AnsbS3MA/0/6HWVlYiPkWaF03LAV7MH/p5y6CVXVlRFU9uG5grrF/CIrnR265AltefKu4uhP5aG0KgNcaWlD0+ibAM3MNSH9vpMuLZYSgnSJ/IhJJMYteQaQ09oOqjW60Cqs9E/eMDbM+9lSEIX1p57OVS76MrmVEd1/IwBDzx/WQ=="
Dec 29 14:02:25 volumio go-librespot[26357]: time="2025-12-29T14:02:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:25 volumio go-librespot[26357]: time="2025-12-29T14:02:25-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:25 volumio go-librespot[26357]: time="2025-12-29T14:02:25-06:00" level=debug msg="completed challenge"
Dec 29 14:02:25 volumio go-librespot[26357]: time="2025-12-29T14:02: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 29 14:02:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:26 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 29 14:02:26 volumio volumio[25761]: 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 29 14:02:26 volumio volumio[25761]: info: CoreCommandRouter::volumioGetState
Dec 29 14:02:26 volumio volumio[25761]: info: CorePlayQueue::getTrack 0
Dec 29 14:02:27 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:27 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Dec 29 14:02:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:28 volumio go-librespot[26365]: go-librespot daemon starting...
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=debug msg="app state loaded"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-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 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-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 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=info msg="zeroconf server listening on port 34911"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=debug msg="obtained new client token: AAC5WGFMY9WDIe1cdCJU6wpbqq1g6EqZE7QCyEOELLNJslXVTaiV/Tnvm/NRsVQsJ6sysQnkFZb5GI1J+QVcFEgjidTC9YoxGJlaxNpNBZAa20q7qL3MS51JA7n0asV0Pb1oh35D6UzvTVg+gdmKfNNcl/BkgA2/FieG0VuL0laOf9HpCQVzX28+Z7k/8u2hoD9KIqaflRApFZmCSyOpuEG+i4rEWHhLTomgyFvrceoOJxbuZ7z05DSP8Q=="
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02:28-06:00" level=debug msg="completed challenge"
Dec 29 14:02:28 volumio go-librespot[26366]: time="2025-12-29T14:02: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 29 14:02:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:30 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:30 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:31 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 29 14:02:31 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 29 14:02:31 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 29 14:02:31 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 29 14:02:31 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 29 14:02:31 volumio volumio[25761]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 29 14:02:31 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 29 14:02:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Dec 29 14:02:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:32 volumio go-librespot[26387]: go-librespot daemon starting...
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=debug msg="app state loaded"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-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 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-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 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=info msg="zeroconf server listening on port 43171"
Dec 29 14:02:32 volumio volumio[25761]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 29 14:02:32 volumio volumio[25761]: info: Completed starting MyVolumio Plugin
Dec 29 14:02:32 volumio volumio[25761]: [Metrics] CommandRouter: 55s 837.87ms
Dec 29 14:02:32 volumio volumio[25761]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 29 14:02:32 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 29 14:02:32 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 29 14:02:32 volumio volumio[25761]: info: CoreCommandRouter::Close All Modals sent
Dec 29 14:02:32 volumio volumio[25761]: info: CoreCommandRouter::Close All Modals sent
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=debug msg="obtained new client token: AAAgYlz+6pWABY6CS/88cFzLRH5bhW2hr8GJfe5QbZCmwhQVN3pZ9F9FeXY4HSGMtTzn0JuSz+fZZtirxrLxsRqlo4tFmTLPE/iyTTlg+8/7FJiLYvBakCeAHWZjB+iOQVTD9IBY0VcAveOnMv89z+rORMYOIgcetWKOzMXiHuXxsWpnw2UaVyxjb825306vENihJW9CvIMtmabTE3ve0jiUvBtjRSmc5tExjaq7NPjqL01p19bk/dAFAw=="
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02:32-06:00" level=debug msg="completed challenge"
Dec 29 14:02:32 volumio go-librespot[26388]: time="2025-12-29T14:02: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 29 14:02:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:33 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 29 14:02:33 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 29 14:02:33 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 29 14:02:33 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:33 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Dec 29 14:02:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:35 volumio go-librespot[26401]: go-librespot daemon starting...
Dec 29 14:02:35 volumio go-librespot[26402]: time="2025-12-29T14:02:35-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:35 volumio go-librespot[26402]: time="2025-12-29T14:02:35-06:00" level=debug msg="app state loaded"
Dec 29 14:02:35 volumio go-librespot[26402]: time="2025-12-29T14:02:35-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:35 volumio go-librespot[26402]: time="2025-12-29T14:02:35-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-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 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-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 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-06:00" level=info msg="zeroconf server listening on port 43685"
Dec 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-06:00" level=debug msg="obtained new client token: AADt2F4+wzhd18jzCl39+iQnfLgPRE9Y/fWDQWZz/+iU+qMM8A9p/3O1DQFXBTBZJ1PvrOIVSvjTI65lMAHrtqkrRuevm/Prc72MH2vfxVUyrwN3xzVBNdIvxAUJ5r1iMQfbw3bRc6lcvEJDHT228SjvLNsfER38RDZCsucsSEKsdyxCr76eDgJX4Y4Kedbg8ANXjoOoEejxqlCAoyQyNSzFuHLK7q7rcuuJ1QUjmUzWxeN1FYjTN0A="
Dec 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02:36-06:00" level=debug msg="completed challenge"
Dec 29 14:02:36 volumio go-librespot[26402]: time="2025-12-29T14:02: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 29 14:02:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:36 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:36 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:39 volumio volumio[25761]: info: BOOT COMPLETED
Dec 29 14:02:39 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:39 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23.
Dec 29 14:02:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:39 volumio go-librespot[26423]: go-librespot daemon starting...
Dec 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-06:00" level=debug msg="app state loaded"
Dec 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-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 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-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 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-06:00" level=info msg="zeroconf server listening on port 38459"
Dec 29 14:02:39 volumio go-librespot[26424]: time="2025-12-29T14:02:39-06:00" level=debug msg="obtained new client token: AAA2hw1aSCl3HOMZc3/WyYxW+EAm1nEsOcooF9uZo1CW9wmY3OlyyDWbh/5jnZGXCVZC5BpiiDIDIPxFV5swR4ZhapCUul0NW4Pij3o2RxZmVtajwglpm8ve6ExQnXR9NYJMrA0NWDdtq4N+Ul7WvCiKcG8/ISUdulhjWqKAE/Ua/CNeC6YrfGFrdwI6e9RZh1wwf4NIS4uihwDXozeIhZB/V5glTw1jEj/vMju5cKCzDz8oC3iAHOLBow=="
Dec 29 14:02:40 volumio go-librespot[26424]: time="2025-12-29T14:02:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:40 volumio go-librespot[26424]: time="2025-12-29T14:02:40-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:40 volumio go-librespot[26424]: time="2025-12-29T14:02:40-06:00" level=debug msg="completed challenge"
Dec 29 14:02:40 volumio go-librespot[26424]: time="2025-12-29T14:02: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 29 14:02:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:42 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:42 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Dec 29 14:02:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:43 volumio go-librespot[26431]: go-librespot daemon starting...
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=debug msg="app state loaded"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-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 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-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 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=info msg="zeroconf server listening on port 43065"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=debug msg="obtained new client token: AAAwQLCfui9bhwPyJ92rDgj82nzDBjxFyZOAojvaH+EbnObuKJtgInKSCTdwyva6gcretugAtJy4vUyLQ/z/XBXzPzgXx2Tv1agGwjK5wYiH+rdddnP7yA6nbfndSKtKaZ6YXJ7w/f3TVs3tQIf+0lUKizuDSOVmVczd42FQqsynOTYlaX0mzNwk60AIQQILeRIpO9beU7Bghcmo97+4SIEpULY2qqoYl92RzPLSLMtEzdfJzOIGHG/r3A=="
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02:43-06:00" level=debug msg="completed challenge"
Dec 29 14:02:43 volumio go-librespot[26432]: time="2025-12-29T14:02: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 29 14:02:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:45 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:45 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Dec 29 14:02:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:47 volumio go-librespot[26443]: go-librespot daemon starting...
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=debug msg="app state loaded"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-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 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-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 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=info msg="zeroconf server listening on port 40453"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=debug msg="obtained new client token: AADbLz2+VysYrVpdt5Vse7aSnP4dQqnR+Yxb/n7dAzH3UI8/ADg5zUxuRsaKRe1F/nOsgr+TkDLq+6PZ5eLLWHMW4P9vRA16+mozBJbetSCufOtFKFuzAtnJQ4mJAIIfuuK0ZJED6T2lhCN9Up4caEZoTqbJBQ6afz5Hmpig1/GmZ+J6MXfPPoFLiSo6daiGJP5BBkd1f2HUyLuUFQhfcmnmimKjFbuQJeUfTrCe8tjDrq9ZeiPMJuKtCw=="
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-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"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02:47-06:00" level=debug msg="completed challenge"
Dec 29 14:02:47 volumio go-librespot[26444]: time="2025-12-29T14:02: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 29 14:02:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:48 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:48 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Dec 29 14:02:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:50 volumio go-librespot[26465]: go-librespot daemon starting...
Dec 29 14:02:50 volumio go-librespot[26466]: time="2025-12-29T14:02:50-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:50 volumio go-librespot[26466]: time="2025-12-29T14:02:50-06:00" level=debug msg="app state loaded"
Dec 29 14:02:50 volumio go-librespot[26466]: time="2025-12-29T14:02:50-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:50 volumio go-librespot[26466]: time="2025-12-29T14:02:50-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=info msg="zeroconf server listening on port 45491"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=debug msg="obtained new client token: AAD8hgmBqAMYWgF8deMdY/pfo+7n4PhdPYSujXNgsz7Ug6qAyQJdqYgE4SoxI0cUu0bdU5X8mR+0KXCDRgpv56DmNcpU0gMxeJ3hBqor//nMfKPycAQ0Lx8dIUyXvnK6Ueq/6SoSWb79YBrziA62Yi9FCUmntxWB2edlJATa+5zINSkw4rXkm3+Y+qRI8cS0L9K/U3sJA4Q1muaTatp6m8+X1+/JetYvhMzKlwaceI3vR5iaYQZOSjs="
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02:51-06:00" level=debug msg="completed challenge"
Dec 29 14:02:51 volumio go-librespot[26466]: time="2025-12-29T14:02: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 29 14:02:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:51 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:51 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:53 volumio volumio[25761]: info: UPDATER: Scheduling automatic update
Dec 29 14:02:53 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 29 14:02:53 volumio volumio[25761]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 29 14:02:53 volumio volumio[25761]: info: UPDATER: Auto update will take place at: Tue Dec 30 2025 05:42:25 GMT-0600 (Central Standard Time)
Dec 29 14:02:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Dec 29 14:02:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:54 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:54 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:54 volumio go-librespot[26473]: go-librespot daemon starting...
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-06:00" level=debug msg="app state loaded"
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-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 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-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 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-06:00" level=info msg="zeroconf server listening on port 34143"
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-06:00" level=debug msg="obtained new client token: AADVE7wSMrfzCZKSehzbQ43vSOQhqUJw3VsSrHM40zfyXim15ztJrIuPIic0f64yZ2YbYCLEgkGyuzfeWGqlN0/GMs1QM75Sd0b3DFo0HQXMiY8mOfaNt6lTn2Wb+SVQ0oqPXJF5VCpC/FkUtjvvlBbfnAoXnQZzkckRuMpeal9UQsrT9aW2RLd/l2XizygKn69QVDG6ZlKgVGcVpvn7Amq7XzoWLbxxdQt5Zc/CDPdV5Qemf6j7lLSqCw=="
Dec 29 14:02:54 volumio go-librespot[26474]: time="2025-12-29T14:02:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:55 volumio go-librespot[26474]: time="2025-12-29T14:02:55-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:55 volumio go-librespot[26474]: time="2025-12-29T14:02:55-06:00" level=debug msg="completed challenge"
Dec 29 14:02:55 volumio go-librespot[26474]: time="2025-12-29T14:02:55-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 29 14:02:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:02:57 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:02:57 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:02:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28.
Dec 29 14:02:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:02:58 volumio go-librespot[26483]: go-librespot daemon starting...
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=debug msg="app state loaded"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=debug msg="stored credentials not found"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-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 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-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 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=info msg="zeroconf server listening on port 44565"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=debug msg="obtained new client token: AADhdb0M+SJMrTAgDeY+VRRO0ZoTQ+kHbaYsD4cHXc8kw6O3Qm7v44LWEsHFzPN5H5GjEIdMBDW3PmNh70qIM/muRsmX7vI7STMa33Sr3o1bypvRJ9eltQOnEq2uTAO6MFWUaX4RSkRi/kmZaSKtIv3W1R4T26VEz95VatiffKWOJJCVlhS65Iv7OYq/r176DeIdqwxaqEeYlNJLTxl/nHocVVsu6gHINghBCSqRrxBHzlT6YnV4I408fQ=="
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=debug msg="completed keyexchange"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02:58-06:00" level=debug msg="completed challenge"
Dec 29 14:02:58 volumio go-librespot[26484]: time="2025-12-29T14:02: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 29 14:02:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:02:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:00 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:00 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29.
Dec 29 14:03:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:02 volumio go-librespot[26505]: go-librespot daemon starting...
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=debug msg="app state loaded"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03: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 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03: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 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03: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 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=info msg="zeroconf server listening on port 36445"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=debug msg="obtained new client token: AADUk+wLljdu8lA+/AIKUyR9syXJld722aMz4tp/K7+HJQee5rOpp404RZPSIqxqmuMzRSrxV/vrJH2+Vi9h8oSA11SeklvDYSIFEsvkUqHe1HFmtAUgEpTuwoOYIaligC/JG2sku1mFP5XKKMJZ0EwQxUKIAs801NAdhByOyOqN8Ai2ySzHiVJNS3thKionxtVFY90iGL9v7/yFfNLy/2QyzviWK/mFA3Brli8kG3AFSXo9WHAaTcDhIQ=="
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03:02-06:00" level=debug msg="completed challenge"
Dec 29 14:03:02 volumio go-librespot[26506]: time="2025-12-29T14:03: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 29 14:03:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:03 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:03 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30.
Dec 29 14:03:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:05 volumio go-librespot[26517]: go-librespot daemon starting...
Dec 29 14:03:05 volumio go-librespot[26518]: time="2025-12-29T14:03:05-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:05 volumio go-librespot[26518]: time="2025-12-29T14:03:05-06:00" level=debug msg="app state loaded"
Dec 29 14:03:05 volumio go-librespot[26518]: time="2025-12-29T14:03:05-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:05 volumio go-librespot[26518]: time="2025-12-29T14:03:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=info msg="zeroconf server listening on port 43487"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=debug msg="obtained new client token: AAAmtjNEXSl6ejbjQ64zpJOHyAhsE7koY4ukUVCiRhED22tKE2DWZSrv3iAFM/2BG+xVIlnUR8kvLFHf/bQ1K+BThTEyiAiahdJ5ySornLLw4ITg8ZCeE5mN35VhZgo/aGrm7lGe6BwYO/7mHwjr05u60qXvL40czU8DiNv73wDptvwhB9oztHoLKKgWU/xt9Smxu9VG0aaJ06VkEdStfdPvwzXx1+79tHFssZqik7nIcnUtUe7OUwQ="
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03:06-06:00" level=debug msg="completed challenge"
Dec 29 14:03:06 volumio go-librespot[26518]: time="2025-12-29T14:03: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 29 14:03:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:06 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:06 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31.
Dec 29 14:03:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:09 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:09 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:09 volumio go-librespot[26539]: go-librespot daemon starting...
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-06:00" level=debug msg="app state loaded"
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-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 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-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 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-06:00" level=info msg="zeroconf server listening on port 33567"
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-06:00" level=debug msg="obtained new client token: AADVVsAP1MtdnUBHUSl6bs5/ATt592Ki6dVj0nuxSUaqRZB6TQHfCsKUw+ZsSmnwJNWMQ9gRGlLAIYPcU3qMJ8t8jOEPbD/aYx00cqnfqHuPdC2k+BGe+ootB6xi7I/sm4HUcd8JB9RwEycvvNWWVhkmPkVBwuwQPope/4ie62a0QhikBX3NtTW2VUEXdTmG/umP+P8bBsKDijqaDlQezXmowVqI0qJbdcZ1v24UIHQh996JS9FC2L7Irw=="
Dec 29 14:03:09 volumio go-librespot[26540]: time="2025-12-29T14:03:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:10 volumio go-librespot[26540]: time="2025-12-29T14:03:10-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:10 volumio go-librespot[26540]: time="2025-12-29T14:03:10-06:00" level=debug msg="completed challenge"
Dec 29 14:03:10 volumio go-librespot[26540]: time="2025-12-29T14:03: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 29 14:03:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:12 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:12 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32.
Dec 29 14:03:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:13 volumio go-librespot[26547]: go-librespot daemon starting...
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=debug msg="app state loaded"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03: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 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03: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 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03: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 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=info msg="zeroconf server listening on port 39399"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=debug msg="obtained new client token: AAA3BtNY3C2Yacentle6ZL1AzTH+0LTUae2XLI8ULH1ciRN76Bs+684cCryX9R7gEUxPs3rB1zA7bRcDz7wBRSVsnKX85HRQZzjMhQzJtpcaKcCQ6JBgNvAzFTiB2CtjOpOlxqvTZPbEnQN/bBSR+ks1O4rwwhnmA6zFI53xXkhRRnfmwmIonI/4yBqZjf3vPcLV+9SqRfsb6jk+M2xhHgv7iY77jdl9dxMIX2c6kp2HbyV9eyqV3UmENQ=="
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03:13-06:00" level=debug msg="completed challenge"
Dec 29 14:03:13 volumio go-librespot[26548]: time="2025-12-29T14:03: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 29 14:03:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:15 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:15 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33.
Dec 29 14:03:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:17 volumio go-librespot[26555]: go-librespot daemon starting...
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="app state loaded"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=info msg="zeroconf server listening on port 42591"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="obtained new client token: AABqjvk5rleKlVIoRGFzyWNhLWMFGi4RO7Ozip3BltkcguYPDoolgtJO1lRcp7bXSBjAtEcVqt3EBxVy5nKcYoL1aaVq/LgODBQ/vFrDPydVfOQKgCcypu4UlPH9osf33xdexCw1oN0GiouFivsc/2RyVpZK3/37xoVIV9jvccvSiA5MfzfnX+umOFcGJlHdoe3OVOAzUPt5N0Fd341j05Gp4xyL3TisAXzzlOmqUvjYRtdfwd4Wv5SoDQ=="
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03:17-06:00" level=debug msg="completed challenge"
Dec 29 14:03:17 volumio go-librespot[26556]: time="2025-12-29T14:03: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 29 14:03:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:18 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:18 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34.
Dec 29 14:03:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:20 volumio go-librespot[26578]: go-librespot daemon starting...
Dec 29 14:03:20 volumio go-librespot[26579]: time="2025-12-29T14:03:20-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:20 volumio go-librespot[26579]: time="2025-12-29T14:03:20-06:00" level=debug msg="app state loaded"
Dec 29 14:03:20 volumio go-librespot[26579]: time="2025-12-29T14:03:20-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:20 volumio go-librespot[26579]: time="2025-12-29T14:03:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03: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-gew1.spotify.com:80]"
Dec 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03:21-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 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03:21-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 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03:21-06:00" level=info msg="zeroconf server listening on port 35477"
Dec 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03:21-06:00" level=debug msg="obtained new client token: AAD/JqtROuGS8tgnXji7juN6Pe3ZTjD/1nLVzaN+bweWpIe32bJ8D801uYLAkbFHjVBUw1/oadIanpXYzHVv/b5Fu0QUU25T8XkJB925W9Oh/T0zTwxyTTRJdoEO6LDcdtoHZ2ZZuyIWscy3TxcdA/NOZ3J9V3SMLxC1kAhsD7gyP6KQuZRW/Gf5es6QXmESwtDP+/+UNBcrUDRjgO04m3XaMuC4Hk148xGt3UP+Rdj/76LQE866BAM="
Dec 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03:21-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03:21-06:00" level=debug msg="completed challenge"
Dec 29 14:03:21 volumio go-librespot[26579]: time="2025-12-29T14:03: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 29 14:03:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:21 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:21 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35.
Dec 29 14:03:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:24 volumio go-librespot[26589]: go-librespot daemon starting...
Dec 29 14:03:24 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:24 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03:24-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03:24-06:00" level=debug msg="app state loaded"
Dec 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03:24-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03: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 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03: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 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03: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 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03:24-06:00" level=info msg="zeroconf server listening on port 46731"
Dec 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03:24-06:00" level=debug msg="obtained new client token: AADVs37ZtaATBgPpRf87EAXsQTI6rn8Er4/tf6l+7i+5ARN4zBjXT4/FAYOM9cbnBMK9iX6bPuiZTkZa63aYLeJaxz2wAXktUCMBvIvYa/hQeidvw9zvay3v2IBhjiwMN6dTjEOMmQB+hk7JUINdiczmLMTb96oZ0p4kOB4pOio85ue3dUaUiFAYzQHp5BVfJSFg04yGvqCceuD+PG7namQPhLEA0Ku6aUTdn+A3q14w4qUqm3WKGe5Bfw=="
Dec 29 14:03:24 volumio go-librespot[26590]: time="2025-12-29T14:03:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:25 volumio go-librespot[26590]: time="2025-12-29T14:03:25-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:25 volumio go-librespot[26590]: time="2025-12-29T14:03:25-06:00" level=debug msg="completed challenge"
Dec 29 14:03:25 volumio go-librespot[26590]: time="2025-12-29T14:03: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 29 14:03:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:27 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:27 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36.
Dec 29 14:03:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:28 volumio go-librespot[26598]: go-librespot daemon starting...
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=debug msg="app state loaded"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-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 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-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 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=info msg="zeroconf server listening on port 46667"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=debug msg="obtained new client token: AAAWYUxPKpgGy/BGvbyExNbDRjGzSE97YxdW8ZJDY1oTibCuyCOVXZ4Kf2wgpo7pW6K09vTBGGIpMeEaCdWoDpCe/cofn7jvxdlOkr1LJxyUsz+52m88gLsvRvhVRh8MYzKLv0gD705WIMHwman2tgafOpL7WEphIUv/yG8XK/Cw2IDemG8b5+EA0xba98J/iUjCWARWjsCKmeoIPMbMiR74Vy8XAte9aTUzr9WSWXIl1JHw2YyoPHypBg=="
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03:28-06:00" level=debug msg="completed challenge"
Dec 29 14:03:28 volumio go-librespot[26599]: time="2025-12-29T14:03: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 29 14:03:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:30 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:30 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37.
Dec 29 14:03:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:32 volumio go-librespot[26620]: go-librespot daemon starting...
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=debug msg="app state loaded"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-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 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-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 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=info msg="zeroconf server listening on port 42837"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=debug msg="obtained new client token: AACQjPu+J4Ou5oxvm7j2LvBmnPqGeWaSfhJKEz+gTW/sstYzRZnqQl3ri0v9ksjrMz9he8bry1dCURWJyPJg2aiFgKBs0BdexpEhyozfO2gyvHNChCBVw45ag71YdmKp/VAoKcnpF7xYQmUYyXoHZsCZGWcgV6nDMFWBZQ3TDz2KKGYYROZum21zkWNtr5vtnv0Uqp2PVh/KjJ4UxW4V0XS9kpyv2sGDp34eSdiMgTIi2CFiomZOKbDnLA=="
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03:32-06:00" level=debug msg="completed challenge"
Dec 29 14:03:32 volumio go-librespot[26621]: time="2025-12-29T14:03: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 29 14:03:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:33 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:33 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38.
Dec 29 14:03:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:35 volumio go-librespot[26628]: go-librespot daemon starting...
Dec 29 14:03:35 volumio go-librespot[26629]: time="2025-12-29T14:03:35-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:35 volumio go-librespot[26629]: time="2025-12-29T14:03:35-06:00" level=debug msg="app state loaded"
Dec 29 14:03:35 volumio go-librespot[26629]: time="2025-12-29T14:03:35-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:35 volumio go-librespot[26629]: time="2025-12-29T14:03:35-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=info msg="zeroconf server listening on port 39359"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=debug msg="obtained new client token: AAATT76LigMkwMlhNdGxlUUl+LuHwtkqH1o5ugJ4Q4J0Pbm5engh/xBl1cTfg/4Kfw3fIn0XSZmL0tKx5QOIJLIoSh7fvz78GjUyI2TvjD3m9/Uuqb4WTbsyHQA/+RJMrqhboi/cbNqjqRP/7tvqgXJUU7I6S8nepzAeYViGUgY6Gp3Itb/35SdiinYHeUpsYwA0Ccl5MfEcIPgthz5xLu8yi9FyTQLwN/X9NuSWe7hJpyKOZFiPPgw="
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-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"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:80"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03:36-06:00" level=debug msg="completed challenge"
Dec 29 14:03:36 volumio go-librespot[26629]: time="2025-12-29T14:03: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 29 14:03:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:36 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:36 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39.
Dec 29 14:03:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:39 volumio go-librespot[26650]: go-librespot daemon starting...
Dec 29 14:03:39 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:39 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-06:00" level=debug msg="app state loaded"
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-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 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-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 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-06:00" level=info msg="zeroconf server listening on port 42999"
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-06:00" level=debug msg="obtained new client token: AABjkKxiyCXU5IM20vXIz47kM3PmTNB+rngdRxYhR4UiRsdEUZv7GoFYiQr6eMK4l/Sxg/qv3j1QY1/W2+dMOZsTzUOn2I0wT2yTy3YNDvaxBGsxBqoNobX6dJPdJwPS/RX3DssoMWgVFsRpSOyHqm8xfg0XEJqzxZUOSV+OKqoqV4h0aglAwUv/8Ap4BW5iak1pCqyQPR2fAdV+kypW+Q+sOWu6yKNGSEm+kmGBugN7G81tKg80CR949A=="
Dec 29 14:03:39 volumio go-librespot[26651]: time="2025-12-29T14:03:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:40 volumio go-librespot[26651]: time="2025-12-29T14:03:40-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:40 volumio go-librespot[26651]: time="2025-12-29T14:03:40-06:00" level=debug msg="completed challenge"
Dec 29 14:03:40 volumio go-librespot[26651]: time="2025-12-29T14:03: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 29 14:03:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:42 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:42 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40.
Dec 29 14:03:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:43 volumio go-librespot[26658]: go-librespot daemon starting...
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=debug msg="app state loaded"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-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 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-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 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=info msg="zeroconf server listening on port 42471"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=debug msg="obtained new client token: AACZebdCLjk/Bw3okixIKiy5Tdj3LPURaWt3azQX77hvsRulLE6sqqxVWH393asgylO+kBlPouxFaEbuGXROvE02rgPK8cJaA45HEddzllsaNDyPuQJdTfHU//iLf84an6iW/UgoN6mGzbavdsaBvRrOkxqhbVW2U8nNRrtxLXFo8LqKseEROXYcbxrXZGK6P8O64hL7a1Nsw5PdITI3oBQKhfYz1u17Eyvx3mXEJ9C/1CpSTb5dg4wKog=="
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03:43-06:00" level=debug msg="completed challenge"
Dec 29 14:03:43 volumio go-librespot[26659]: time="2025-12-29T14:03: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 29 14:03:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:45 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:45 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41.
Dec 29 14:03:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:47 volumio go-librespot[26670]: go-librespot daemon starting...
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=debug msg="app state loaded"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03: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 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03: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 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03: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 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=info msg="zeroconf server listening on port 45283"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=debug msg="obtained new client token: AAAMRM2fcAy5dxQz8+3spos3dfL/oHLmdsqOFqyZwUt+JiODtpLpZnurQ3NsiwPLep9jhA5dPG3uXGInT62iRZ0C2mkqNWqo2hXcDjSGjL5kNXxdvN/td71+e17VYpbP2q9yYyfbGGyJW/uqq0BJPcxOsSHXBitXyt/120QUWehZaidJ21UFTl+8T2ioNMuIZgwftZhrDkLN4ckC3VYptF2okf/eX98eIcelwL8NIb9U3XDmzUPtcGo4Fw=="
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03:47-06:00" level=debug msg="completed challenge"
Dec 29 14:03:47 volumio go-librespot[26671]: time="2025-12-29T14:03: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 29 14:03:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:48 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:48 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42.
Dec 29 14:03:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:50 volumio go-librespot[26692]: go-librespot daemon starting...
Dec 29 14:03:50 volumio go-librespot[26693]: time="2025-12-29T14:03:50-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:50 volumio go-librespot[26693]: time="2025-12-29T14:03:50-06:00" level=debug msg="app state loaded"
Dec 29 14:03:50 volumio go-librespot[26693]: time="2025-12-29T14:03:50-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:50 volumio go-librespot[26693]: time="2025-12-29T14:03:50-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=info msg="zeroconf server listening on port 37893"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=debug msg="obtained new client token: AADGWPlw+bprIpOHui/+PX8u70L5P7q139YiFUCY3+ql5OoVmiudc2+sgRI4F1Q0qxLFJSZ5HsCS6xAosQ/hmsGuoxeGUfMaIkdlUU1VK64rXu3KkgMOiLd4szmgcgyqg1LB8PC4mX1ki2QnKSA28+4UPwIucQROq98LYr1p2rM+wrPt7ZsIsYcPTLdkmUyV4lH88B6S/fGXH0m9Vc4ZlA0pxJl7+dW+QP0dII8nX9SDxLtiD0OTeaU="
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03:51-06:00" level=debug msg="completed challenge"
Dec 29 14:03:51 volumio go-librespot[26693]: time="2025-12-29T14:03: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 29 14:03:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:51 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:51 volumio volumio[25761]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43.
Dec 29 14:03:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:54 volumio go-librespot[26700]: go-librespot daemon starting...
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=debug msg="app state loaded"
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:54 volumio volumio[25761]: info: Initializing connection to go-librespot Websocket
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=debug msg="new websocket client"
Dec 29 14:03:54 volumio volumio[25761]: info: Connection to go-librespot Websocket established
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-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 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-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 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=info msg="zeroconf server listening on port 34159"
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=debug msg="obtained new client token: AAB7IVoHs+20PzDs+El/GGQzjwCcDUH9m1qf4K5ZZkHBcg2T9kxoAfsfnV5Msku/xgjtFHAXDgAei8kTVUXpQR9ViLWDbogPkawlH40Sg+WevMZgnTtOZfhpOCyVCcdCPwGYGY9fdsWxJVc9Z49FAB0lYoYmYDiQAY6/dj8n8aMnAiLF4AqiGgYP3H8zsYgrDOZiaYsbd1paHqkdcHAVrFLKFM1Ux1xj6ABAVp5gG+QK4pvNz8Wfj7ze/w=="
Dec 29 14:03:54 volumio go-librespot[26701]: time="2025-12-29T14:03:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 29 14:03:55 volumio go-librespot[26701]: time="2025-12-29T14:03:55-06:00" level=debug msg="completed keyexchange"
Dec 29 14:03:55 volumio go-librespot[26701]: time="2025-12-29T14:03:55-06:00" level=debug msg="completed challenge"
Dec 29 14:03:55 volumio go-librespot[26701]: time="2025-12-29T14:03:55-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 29 14:03:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 29 14:03:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 29 14:03:55 volumio volumio[25761]: info: Connection to go-librespot Websocket closed
Dec 29 14:03:57 volumio volumio[25761]: info: Getting Spotify volume
Dec 29 14:03:57 volumio volumio[25761]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 29 14:03:57 volumio volumio[25761]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 29 14:03:57 volumio volumio[25761]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 29 14:03:57 volumio volumio[25761]: errno: -111,
Dec 29 14:03:57 volumio volumio[25761]: code: 'ECONNREFUSED',
Dec 29 14:03:57 volumio volumio[25761]: syscall: 'connect',
Dec 29 14:03:57 volumio volumio[25761]: address: '127.0.0.1',
Dec 29 14:03:57 volumio volumio[25761]: port: 9879,
Dec 29 14:03:57 volumio volumio[25761]: response: undefined
Dec 29 14:03:57 volumio volumio[25761]: }
Dec 29 14:03:57 volumio volumio[25761]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 29 14:03:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44.
Dec 29 14:03:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 29 14:03:58 volumio go-librespot[26721]: go-librespot daemon starting...
Dec 29 14:03:58 volumio go-librespot[26722]: time="2025-12-29T14:03:58-06:00" level=info msg="running go-librespot 0.4.0"
Dec 29 14:03:58 volumio go-librespot[26722]: time="2025-12-29T14:03:58-06:00" level=debug msg="app state loaded"
Dec 29 14:03:58 volumio go-librespot[26722]: time="2025-12-29T14:03:58-06:00" level=debug msg="stored credentials not found"
Dec 29 14:03:58 volumio go-librespot[26722]: time="2025-12-29T14:03:58-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 29 14:03:58 volumio sudo[26730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 14:02'
Dec 29 14:03:58 volumio sudo[26730]: 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"