Dec 08 08:49:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Dec 08 08:49:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:01 volumio go-librespot[14016]: go-librespot daemon starting...
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="app state loaded"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=info msg="zeroconf server listening on port 38029"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="obtained new client token: AAC+kS7ir6EJVpi2ZfYU7eea/FbVFCTREO0EgeUwXyhEL43GqGFtVkv/4N6K3vKQSyGz/Hy/Ju75FVuNIEwgwesIV3Q6DOdODUD1SXhiXk3dlqElo/Ts6wK0WO26TpSRSxyxh+poNPQKD8g5M1EQlNrxze1TpXwu5JzfrlMHNlSCrDNmlIN3fd17nmlbA099d3ADaPJnXsmxUAzW2ZNHKifO8P0qCiyqcOWpzKaGjvVEmgRvL1Dqr4flMg=="
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=debug msg="completed challenge"
Dec 08 08:49:01 volumio go-librespot[14017]: time="2025-12-08T08:49:01-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:03 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:03 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Dec 08 08:49:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:04 volumio go-librespot[14024]: go-librespot daemon starting...
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=debug msg="app state loaded"
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:04 volumio go-librespot[14025]: time="2025-12-08T08:49:04-06:00" level=info msg="zeroconf server listening on port 46243"
Dec 08 08:49:05 volumio go-librespot[14025]: time="2025-12-08T08:49:05-06:00" level=debug msg="obtained new client token: AAD7L13yb6A05O0z+D9fchiwNEew+KeWwHt4eDduiZ0Mo6WqbTpZcQbX/jImKPxKDc3aAZczfEim022jzWEn6gwZPV67nFDI8pdXi5muGI8FR2y5WrUew5S3Jt18kNLOzkt3mbf44i7EdZaNw32+C6hr9XxhOceYagD7muGkoDyJh8ukBIsTkKqijhnk+v4R62ZGtyaY2qiD9O5U/dA6e1m1boyJ8+CKYJnzU8qOwTXzcTbHBf3Dg/w="
Dec 08 08:49:05 volumio go-librespot[14025]: time="2025-12-08T08:49:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:05 volumio go-librespot[14025]: time="2025-12-08T08:49:05-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:05 volumio go-librespot[14025]: time="2025-12-08T08:49:05-06:00" level=debug msg="completed challenge"
Dec 08 08:49:05 volumio go-librespot[14025]: time="2025-12-08T08:49:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 08 08:49:06 volumio volumio[13559]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 08 08:49:06 volumio volumio[13559]: info: Adding plugin multiroom to MyMusic Plugins
Dec 08 08:49:06 volumio volumio[13559]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 08 08:49:06 volumio volumio[13559]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 08 08:49:06 volumio volumio[13559]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 08 08:49:06 volumio volumio[13559]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 08 08:49:06 volumio volumio[13559]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 08 08:49:06 volumio volumio[13559]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 08 08:49:06 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:06 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:06 volumio volumio[13559]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 08 08:49:07 volumio volumio[13559]: info: MyVolumio login type: Token
Dec 08 08:49:07 volumio volumio[13559]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 08 08:49:07 volumio volumio[13559]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 08 08:49:07 volumio volumio[13559]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 08 08:49:07 volumio volumio[13559]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 08 08:49:07 volumio volumio[13559]: info: Streaming services startup
Dec 08 08:49:07 volumio volumio[13559]: info: Starting Streaming Daemon
Dec 08 08:49:07 volumio sudo[14048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 08 08:49:07 volumio sudo[14048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 08 08:49:07 volumio volumio[13559]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 08 08:49:07 volumio sudo[14048]: pam_unix(sudo:session): session closed for user root
Dec 08 08:49:07 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:07 volumio volumio[13559]: error: Cannot start Volumio Streaming Daemon
Dec 08 08:49:07 volumio volumio[13559]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 08 08:49:07 volumio volumio[13559]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 08 08:49:08 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:08 volumio volumio[13559]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 08 08:49:08 volumio volumio[13559]: info: MyVolumio token set successfully
Dec 08 08:49:08 volumio volumio[13559]: info: MYVOLUMIO: Adding device
Dec 08 08:49:08 volumio volumio[13559]: info: MYVOLUMIO: Evaluating Server
Dec 08 08:49:08 volumio volumio[13559]: info: MyVolumio status changed
Dec 08 08:49:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Dec 08 08:49:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:08 volumio volumio[13559]: info: Streaming services startup
Dec 08 08:49:08 volumio volumio[13559]: info: Starting Streaming Daemon
Dec 08 08:49:08 volumio volumio[13559]: info: Removing browser output: myVolumio user plan is not superstar
Dec 08 08:49:08 volumio volumio[13559]: info: Removing audio output:
Dec 08 08:49:08 volumio volumio[13559]: info: Stoppping Tunnel 1
Dec 08 08:49:08 volumio sudo[14076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 08 08:49:08 volumio sudo[14076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 08 08:49:09 volumio sudo[14078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 08 08:49:09 volumio sudo[14078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 08 08:49:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:09 volumio go-librespot[14074]: go-librespot daemon starting...
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="app state loaded"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 08 08:49:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 08 08:49:09 volumio sudo[14076]: pam_unix(sudo:session): session closed for user root
Dec 08 08:49:09 volumio volumio[13559]: error: Cannot start Volumio Streaming Daemon
Dec 08 08:49:09 volumio volumio[13559]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 08 08:49:09 volumio volumio[13559]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 08 08:49:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 08 08:49:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 08 08:49:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 08 08:49:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 08 08:49:09 volumio sudo[14078]: pam_unix(sudo:session): session closed for user root
Dec 08 08:49:09 volumio volumio[13559]: info: Remote SSH Stopped
Dec 08 08:49:09 volumio volumio[13559]: info: Setting Geolocation for MyVolumio to us4
Dec 08 08:49:09 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:09 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:09 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=info msg="zeroconf server listening on port 35269"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="obtained new client token: AABd/Ygf+UTkpHcAHEYi94bmYwvDh7JWy2Q69LKn+fa3ml9oOz7bGFNrXH49HYO9nOOeaR07epRAz7lOHC2AVV634+O8F435EBhFQD1hWpmM+BUihpP3RtLsqabdUb6vRTDRBR+MPLSTi/QoYLtN/1TuK1OSEzTMC1wBKCiE8UBNxWHmeQ4Sv1y02ZPiMYqtWGsGW3tZAdfnHZ9fPbiHnO0tim/xsqWzFw/rwHdCZ9eGq0KM4MSEdf1r3Q=="
Dec 08 08:49:09 volumio volumio[13559]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=debug msg="completed challenge"
Dec 08 08:49:09 volumio go-librespot[14081]: time="2025-12-08T08:49:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:10 volumio volumio[13559]: info: Updating MyVolumio device info
Dec 08 08:49:10 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:10 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:10 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:10 volumio volumio[13559]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 08 08:49:11 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:11 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Dec 08 08:49:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:12 volumio go-librespot[14089]: go-librespot daemon starting...
Dec 08 08:49:12 volumio go-librespot[14090]: time="2025-12-08T08:49:12-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:12 volumio go-librespot[14090]: time="2025-12-08T08:49:12-06:00" level=debug msg="app state loaded"
Dec 08 08:49:12 volumio go-librespot[14090]: time="2025-12-08T08:49:12-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:12 volumio go-librespot[14090]: time="2025-12-08T08:49:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:12 volumio go-librespot[14090]: time="2025-12-08T08:49:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:12 volumio go-librespot[14090]: time="2025-12-08T08:49:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:12 volumio go-librespot[14090]: time="2025-12-08T08:49:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:13 volumio go-librespot[14090]: time="2025-12-08T08:49:13-06:00" level=info msg="zeroconf server listening on port 34491"
Dec 08 08:49:13 volumio go-librespot[14090]: time="2025-12-08T08:49:13-06:00" level=debug msg="obtained new client token: AABcOJ9sC0fgBKviqe2AMKmaISmPDJnx1fNMB7KqOoV4dX5esiTUFNVNdnEcKFWy8GozUtn9klUIvM1WTXOMcBHp8R8E+uI+hRHjU5KP/un/c0V/WmlWzOvpTx9XKCyrUZ1YYFLzu8LgYk1xk+MzBBMCuQemmp25rtzx2KbDf8+E1iS9XveYmlGSDejyHjo/71v0Euamd4LEqp7KqdN02wg9EEOvH/B72Svt/8QYap+xSbXN8hgJILk="
Dec 08 08:49:13 volumio go-librespot[14090]: time="2025-12-08T08:49:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:13 volumio go-librespot[14090]: time="2025-12-08T08:49:13-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:13 volumio go-librespot[14090]: time="2025-12-08T08:49:13-06:00" level=debug msg="completed challenge"
Dec 08 08:49:13 volumio go-librespot[14090]: time="2025-12-08T08:49:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:13 volumio volumio[13559]: info: MYVOLUMIO: Adding device
Dec 08 08:49:13 volumio volumio[13559]: info: MYVOLUMIO: Evaluating Server
Dec 08 08:49:13 volumio volumio[13559]: info: Setting Geolocation for MyVolumio to us4
Dec 08 08:49:13 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:13 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:13 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:14 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:14 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:14 volumio volumio[13559]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 08 08:49:14 volumio volumio[13559]: info: Updating MyVolumio device info
Dec 08 08:49:14 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:14 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:14 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:15 volumio volumio[13559]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 08 08:49:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Dec 08 08:49:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:16 volumio go-librespot[14129]: go-librespot daemon starting...
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="app state loaded"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=info msg="zeroconf server listening on port 46631"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="obtained new client token: AABtDOXKaL3zCQeQNZz8vK1SfADdtOw8NDzLSHLx7xrAQMi1SoBAIjFI8oyxB/3nt+DXYDYdFRQmKV31aEPO5vSCNdbqCeMLgBPC90vCNY85L6HUXJfkUNQoBb1blXEt5W3iizhQb9oP6VCnhxpEJD9rcrQaQhTB/Plco5gwmsFFMmb6XfclrAem3K5fj1tBR0TqBjYutpGtxa1ul0gVxH5j2xk/SzLxGJqg9gWVOFkQEbOXMSn16G53Gw=="
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:16 volumio go-librespot[14130]: time="2025-12-08T08:49:16-06:00" level=debug msg="completed challenge"
Dec 08 08:49:17 volumio go-librespot[14130]: time="2025-12-08T08:49:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:17 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:17 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:19 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 08 08:49:19 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:19 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 08 08:49:19 volumio volumio-remote-updater[9752]: Test mode disabled
Dec 08 08:49:19 volumio volumio-remote-updater[9752]: Alpha mode disabled
Dec 08 08:49:19 volumio volumio-remote-updater[9752]: Alpha legacy test mode disabled
Dec 08 08:49:19 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 08 08:49:19 volumio volumio[13559]: 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 08 08:49:19 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 08 08:49:20 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:20 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Dec 08 08:49:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:20 volumio go-librespot[14146]: go-librespot daemon starting...
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="app state loaded"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=info msg="zeroconf server listening on port 36225"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="obtained new client token: AAD6C6lH3wVVWSx5BCDEvDsmIgy6yOTBR3pYJWawF4cgua0goDac5bVEm1F5IoCkU77RtC6/Elg/yFVtGHxz5Z4ybi+TWER1PFY9RZFStuNMhKmWf7lq81FYyimnaPTf2HYJGVbgZM4tZwcj9hgpJ2Yk1ytkzYA9SWn3dgmEWFqX74JD+CXSUKXdAO/NQcPZNtwmj8ipGOWUteNg2DxeZOe3/dKag8J2h1mH6+gm6VqjGoR8eCL7NQpdPw=="
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=debug msg="completed challenge"
Dec 08 08:49:20 volumio go-librespot[14147]: time="2025-12-08T08:49:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:21 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 08 08:49:22 volumio volumio[13559]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Dec 08 08:49:22 volumio volumio[13559]: info: CoreCommandRouter::volumioGetState
Dec 08 08:49:22 volumio volumio[13559]: info: CorePlayQueue::getTrack 0
Dec 08 08:49:23 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:23 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Dec 08 08:49:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:23 volumio go-librespot[14154]: go-librespot daemon starting...
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="app state loaded"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=info msg="zeroconf server listening on port 43637"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="obtained new client token: AAAIX9m2ovVaFEH847+tde9chBDzvmsdWl0rnUlvD8nXmALdfpgSJHRnSt+P/cL/UESIchxOuOoho1lC9jq5uo84cHkpvZM0b3Cb8tsM4Q1Z9+G/rDFbPZtKM0zyK3T2XBFotANXls7iz0HiMpHEG4y7DAFbPYXrKHdUH3P9sRDxJBrpTq6Av+WA4j4T8Mxg36FL+vSw7ZUpaiYuiCrxz7LxpHPu0VsNOkSsvqmEkCIJjV0uzEoI5FvLqw=="
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=debug msg="completed challenge"
Dec 08 08:49:24 volumio go-librespot[14155]: time="2025-12-08T08:49:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:26 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:26 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:26 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 08 08:49:26 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 08 08:49:26 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 08 08:49:26 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 08 08:49:26 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 08 08:49:26 volumio volumio[13559]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 08 08:49:26 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 08 08:49:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Dec 08 08:49:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:27 volumio go-librespot[14176]: go-librespot daemon starting...
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=debug msg="app state loaded"
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:27 volumio volumio[13559]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 08 08:49:27 volumio volumio[13559]: info: Completed starting MyVolumio Plugin
Dec 08 08:49:27 volumio volumio[13559]: [Metrics] CommandRouter: 53s 979.89ms
Dec 08 08:49:27 volumio volumio[13559]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 08 08:49:27 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 08 08:49:27 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 08 08:49:27 volumio volumio[13559]: info: CoreCommandRouter::Close All Modals sent
Dec 08 08:49:27 volumio volumio[13559]: info: CoreCommandRouter::Close All Modals sent
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:27 volumio go-librespot[14177]: time="2025-12-08T08:49:27-06:00" level=info msg="zeroconf server listening on port 43757"
Dec 08 08:49:28 volumio go-librespot[14177]: time="2025-12-08T08:49:28-06:00" level=debug msg="obtained new client token: AABxsMCWa45s4egSf+nhkuv3HBkupICmjW5BN/9i5/gyuR9EZMbe5qROMDKzYu/Boh5sNyUa4EYrZxs83MJBumkox6Ls7KOhDv5SubM7Gv+jPEBJHxvdUQMC8PZrRi0fzOK2LANFYcilF3t1G/DtoRis5/SFEVkFTNoHJHeF0HV32EsRkHP/XCtKjeXTBISTfco0ApcDBybmccA7poB0cLIfJvWBjuWt0Rxx21F6neSXPjwASq01/9U="
Dec 08 08:49:28 volumio go-librespot[14177]: time="2025-12-08T08:49:28-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 08 08:49:28 volumio go-librespot[14177]: time="2025-12-08T08:49:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 08 08:49:28 volumio go-librespot[14177]: time="2025-12-08T08:49:28-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:28 volumio go-librespot[14177]: time="2025-12-08T08:49:28-06:00" level=debug msg="completed challenge"
Dec 08 08:49:28 volumio go-librespot[14177]: time="2025-12-08T08:49:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:28 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 08 08:49:28 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 08 08:49:29 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 08 08:49:29 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:29 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Dec 08 08:49:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:31 volumio go-librespot[14190]: go-librespot daemon starting...
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="app state loaded"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=info msg="zeroconf server listening on port 42847"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="obtained new client token: AACKEc064uB/bt7oC2QtFzMKv5IFi+RVpteuGVLrRYJOtZUbrJJuam2nytqsYGrGpMBMHM+EX1gng3k7NYv94lnmPrqNQ8zXoOqyuh+YKpj9GtpBZLAMpGl/J5FcgMB6nJIRFzQNz/Axo4GzsR8/EREqoP5ze9PQFFU1p6CCKPzOhvLExWF541gOYjP1yzwYrMRXjl37026divxzIRHBHQ0j5SND8elx2jVkhC7+qudxqZN1efYBIqHJZw=="
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=debug msg="completed challenge"
Dec 08 08:49:31 volumio go-librespot[14191]: time="2025-12-08T08:49:31-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:32 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:32 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:34 volumio volumio[13559]: info: BOOT COMPLETED
Dec 08 08:49:35 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:35 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Dec 08 08:49:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:35 volumio go-librespot[14198]: go-librespot daemon starting...
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="app state loaded"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=info msg="zeroconf server listening on port 43155"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="obtained new client token: AACTiMbNhvmSA3JTsn/4f2f55ZyU9Hiy11V3lQe0/b+jvraHjku5h4e8660Kz2S/7Mp19oNqnGcLda52L0uuT7lv8aOQMyMds1Ds8h1rBFBmsrzhnWuUOD3SgQRPw5Fe2oehm1dez3xiqAKltZTqwf2zcV0BzY4WcBAlIfC90HffAY5BDVgyum6iijl6ynWbRmwUTMGbFP++MuCN1NMYy4cprR7UfMhUW4nJi83mA+lHmKI5o7djucENqg=="
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=debug msg="completed challenge"
Dec 08 08:49:35 volumio go-librespot[14199]: time="2025-12-08T08:49:35-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:38 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:38 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23.
Dec 08 08:49:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:39 volumio go-librespot[14224]: go-librespot daemon starting...
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="app state loaded"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=info msg="zeroconf server listening on port 41007"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="obtained new client token: AAAvSYj4Am8ktbrk+3+XCH0gz8/eCg1JajWM8I8UZfgvmQDQlbAVmYcHz90gKyNGkZ3ypLCJuFEwXI9NTCOzhG2iGaCIPDGC/S8eI9RA3L4BwB2dRSpS5+XEpERlVI2Lax0btpqdq+sYCEN94qS+xhw7sSkBHcV0TLZ4g9n23B9xx/aNkrGes9GscyNW88enxcBX0KUkpFU5+eMqGL3dwb/xub5RdHtpU11R0ozMW2T2D1KjmcZC5vk2/Q=="
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=debug msg="completed challenge"
Dec 08 08:49:39 volumio go-librespot[14225]: time="2025-12-08T08:49:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:41 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:41 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Dec 08 08:49:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:42 volumio go-librespot[14232]: go-librespot daemon starting...
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=debug msg="app state loaded"
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:42 volumio go-librespot[14233]: time="2025-12-08T08:49:42-06:00" level=info msg="zeroconf server listening on port 39051"
Dec 08 08:49:43 volumio go-librespot[14233]: time="2025-12-08T08:49:43-06:00" level=debug msg="obtained new client token: AACRdq93M4y04WgpsbiPcqPwdZONCqt5uhjEmEKZ2g4VGv3E1TP9Vk2EH3EQZE+jUjcZSyIGCIve3fsRLSL9X8cPDUMzqCkdh1VuQ9srslREBi3t5FeL3omhkNAm+1oCq0kdZv4hKnSWncn4VHxxgdJhDUi/M9CnzJTDehKqjgODlsRl0Z+UFDTj4APZmMLN/TRIkGScVz0oayD/m+uogQkmn99/cgR0sOvvKXQGgu7++YS0O6b4Fjs="
Dec 08 08:49:43 volumio go-librespot[14233]: time="2025-12-08T08:49:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:43 volumio go-librespot[14233]: time="2025-12-08T08:49:43-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:43 volumio go-librespot[14233]: time="2025-12-08T08:49:43-06:00" level=debug msg="completed challenge"
Dec 08 08:49:43 volumio go-librespot[14233]: time="2025-12-08T08:49:43-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:44 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:44 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Dec 08 08:49:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:46 volumio go-librespot[14255]: go-librespot daemon starting...
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=debug msg="app state loaded"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=info msg="zeroconf server listening on port 34645"
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=debug msg="obtained new client token: AACSfrFJgvQPJHuBTmJzTyCAHyQ/iH5g0GrEP/uN/BghlqU0ugzUdJaxwmw8Ov/KjtyfWwmN4e5Pcsh+1fx7Mnn1j2faCS4DkrpgaHoEOQz1btfl8PIjbq0JYxx3ixT0ioSkpkWjpLNNZ9rMoJFWlxHAfOlnLY4KDcNd2t24OBftxNEf5tT45kE+BbFX6dWZ3zphd13+/LN4zHxKdRBwhoYFoVzFuVIjkufhyHYyqnPfGHIUOZi4PqjD1w=="
Dec 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-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 08 08:49:46 volumio go-librespot[14256]: time="2025-12-08T08:49:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 08 08:49:47 volumio go-librespot[14256]: time="2025-12-08T08:49:47-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:47 volumio go-librespot[14256]: time="2025-12-08T08:49:47-06:00" level=debug msg="completed challenge"
Dec 08 08:49:47 volumio go-librespot[14256]: time="2025-12-08T08:49:47-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:47 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:47 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:49 volumio volumio[13559]: info: UPDATER: Scheduling automatic update
Dec 08 08:49:49 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 08 08:49:49 volumio volumio[13559]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 08 08:49:49 volumio volumio[13559]: info: UPDATER: Auto update will take place at: Tue Dec 09 2025 05:06:35 GMT-0600 (Central Standard Time)
Dec 08 08:49:50 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:50 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Dec 08 08:49:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:50 volumio go-librespot[14263]: go-librespot daemon starting...
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="app state loaded"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=info msg="zeroconf server listening on port 41549"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="obtained new client token: AADlAABiQX/CYwNFRdYHD6M+JfLlux+mS0OhP+QpHvQv9cPlMznmGiK12n8fv5NyUTiJIQXWaBLLtgLYsKW7noBz3+KvE7B5sgcv6MXeUqushL0W60j4xL+VCQKyQ8boyDL4lq7+9dtuzG271iKjb03QdygnYiZXfxLU3FaePFIo9cf9M7JR+ZKzoZV2ECQE+IBmFkqTwpdwWrkVgKmCnqMZmwB4oZObK35A1Oqu8+xToKZv+dlCa/Al/g=="
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=debug msg="completed challenge"
Dec 08 08:49:50 volumio go-librespot[14264]: time="2025-12-08T08:49:50-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:53 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:53 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Dec 08 08:49:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:54 volumio go-librespot[14271]: go-librespot daemon starting...
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="app state loaded"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=info msg="zeroconf server listening on port 37563"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="obtained new client token: AAA916bPNLmlvX4KuH1AkKMsvYk8vxuKhJ3tR8uGHLfmUTk4OjyPsvrpQPd3dLmG/Sj2MiGCF6/t3iISzWB9+HVu4y3bOADzaQ2qokFCDHzfUsoeePdKBr8/73OaojBL8/pkjdQ/IDTAt8pB+wnBeX+7sKX1JUA20EWhp7zHScKhJBY14ZKpHAYQ6kQu597NJhMRwo2LabhjKvDHf5vpY/wF5RkOcsIBMrwJOYzf7Z642JRur18oGdx9ZQ=="
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=debug msg="completed challenge"
Dec 08 08:49:54 volumio go-librespot[14272]: time="2025-12-08T08:49:54-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:56 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:56 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:49:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28.
Dec 08 08:49:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:49:57 volumio go-librespot[14293]: go-librespot daemon starting...
Dec 08 08:49:57 volumio go-librespot[14294]: time="2025-12-08T08:49:57-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:49:57 volumio go-librespot[14294]: time="2025-12-08T08:49:57-06:00" level=debug msg="app state loaded"
Dec 08 08:49:57 volumio go-librespot[14294]: time="2025-12-08T08:49:57-06:00" level=debug msg="stored credentials not found"
Dec 08 08:49:57 volumio go-librespot[14294]: time="2025-12-08T08:49:57-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:49:57 volumio go-librespot[14294]: time="2025-12-08T08:49:57-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:49:57 volumio go-librespot[14294]: time="2025-12-08T08:49:57-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:49:57 volumio go-librespot[14294]: time="2025-12-08T08:49:57-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:49:58 volumio go-librespot[14294]: time="2025-12-08T08:49:58-06:00" level=info msg="zeroconf server listening on port 37043"
Dec 08 08:49:58 volumio go-librespot[14294]: time="2025-12-08T08:49:58-06:00" level=debug msg="obtained new client token: AAAPq9Vd5xm8yFJB9DWTKVDS6FpUTZms+Ds+rcFz3nkK1nLNhgqs5sblW4fxdgNwcwVPNDUgWE6OICV7JWoK8EWyIuxJcJCM8hQxlfAybvE/S9bncbNsqMzjJLfSA96y//PzS4keG6LOdaRpY4Sus9WWp2tMVZwpmahGSGhr9BaeU6Kev8gtHd+qXU/ZOjBXmqYYcyB+7qCmgSXCkjhWvAEvFmMefNCXQ55dAPjPXixVsSCp+KRc3IM="
Dec 08 08:49:58 volumio go-librespot[14294]: time="2025-12-08T08:49:58-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 08 08:49:58 volumio go-librespot[14294]: time="2025-12-08T08:49:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 08 08:49:58 volumio go-librespot[14294]: time="2025-12-08T08:49:58-06:00" level=debug msg="completed keyexchange"
Dec 08 08:49:58 volumio go-librespot[14294]: time="2025-12-08T08:49:58-06:00" level=debug msg="completed challenge"
Dec 08 08:49:58 volumio go-librespot[14294]: time="2025-12-08T08:49:58-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:49:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:49:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:49:59 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:49:59 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:50:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29.
Dec 08 08:50:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:01 volumio go-librespot[14305]: go-librespot daemon starting...
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="app state loaded"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="stored credentials not found"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=info msg="zeroconf server listening on port 46245"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="obtained new client token: AAB+28YdAO+1BIkZSkoXp0r3A2orAHDRueOx9W2YBq3v4wqUViGVXEiSi0syUnimFKW48vcWUK0sA69Xw5Ppd4NAr7/krxn9SthHPmcsx8Oe8X3EB6TgHK3ODB5oAeSkT3FKW7UBnPt64FscDPgkokwZ2wQxGsEdyphWSSudKxnnN8pjtYVEBRRLyu8NjMBFdHG5+0Z1Zc7+71x+x/KS0QGXg9IVKDzhQB86C2kcVC1hDURoVG8i7QH1Tg=="
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="completed keyexchange"
Dec 08 08:50:01 volumio go-librespot[14306]: time="2025-12-08T08:50:01-06:00" level=debug msg="completed challenge"
Dec 08 08:50:02 volumio go-librespot[14306]: time="2025-12-08T08:50:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:50:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:50:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:50:02 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:50:02 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:50:05 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:50:05 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:50:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30.
Dec 08 08:50:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:05 volumio go-librespot[14313]: go-librespot daemon starting...
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="app state loaded"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="stored credentials not found"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=info msg="zeroconf server listening on port 32845"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="obtained new client token: AAAB7iHP7m4GRexHDnCAUvjUgD4a2+2RON29n+pOJQV5xlA3Y4boyGtOXqQOpETUkWERlHRk2wJXGH4dvIUf8eCDA5qGF0eIb31PdKrs6CNM7jX8fVbZ3shc24xlLr3Bz3Tey5osLmqRKYpnYgc5OPB/OaRr8M9C5CItsi0SJ87KW4mjx0XXBp40M/W/5xmPfd++bbMcAgRoxYFokL3vXcX2rC1lABVz4aHh6dLJUR2Yi1AQvA3ga2DaKg=="
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="completed keyexchange"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=debug msg="completed challenge"
Dec 08 08:50:05 volumio go-librespot[14314]: time="2025-12-08T08:50:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:50:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:50:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:50:08 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:50:08 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:50:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31.
Dec 08 08:50:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:09 volumio go-librespot[14336]: go-librespot daemon starting...
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="app state loaded"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="stored credentials not found"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=info msg="zeroconf server listening on port 45909"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="obtained new client token: AAC8kFwdujM1kL0gKf7Xar1LfKm10G0Z1dmU7OtyOtShN11pcaYfr/uGh++LXCMFyWrEjivdUiOVlCrjhYdMcrqhCBdaRJ/BMnN/7IYlGYQoa4nJhRXJPvVBdzBwC0XsIQ0l9YOojfboSvPvLVT3iRmt9sjtbgp1cPxTrAqd/XbXEZyaL/EPFc/pfWQ+kPct4sDIv7dh5csVfyo7m3oHh7eMXz3b+DrsyK/VogqpNjWCq7uV1Yuz1WFNpQ=="
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="completed keyexchange"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=debug msg="completed challenge"
Dec 08 08:50:09 volumio go-librespot[14337]: time="2025-12-08T08:50:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:50:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:50:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:50:11 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:50:11 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:50:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32.
Dec 08 08:50:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:12 volumio go-librespot[14344]: go-librespot daemon starting...
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=debug msg="app state loaded"
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=debug msg="stored credentials not found"
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 08 08:50:12 volumio go-librespot[14345]: time="2025-12-08T08:50:12-06:00" level=info msg="zeroconf server listening on port 41151"
Dec 08 08:50:13 volumio go-librespot[14345]: time="2025-12-08T08:50:13-06:00" level=debug msg="obtained new client token: AAB9h+Kb+jqnCIrFh5fjMn++i/Tk+VW97Y9WB46v2yIPV539veFdaaMAjEl+H0DwAGJwI88ghP8QGz/XKdZb/dYbjf9OczejudMEjg7JoOXI13fEwmXNk2gB/fKn2zWF0iIHbxLS43gYzB4CF97FDLPQTkHdELLPWkLOrhkqAy4PTkB4/mfbR+ENKP2tFfZjIR6S8Rpzm7eM8g0B8D54u3jb4O5AYOeetR3xd/48RmugC6NC59b5Lhg="
Dec 08 08:50:13 volumio go-librespot[14345]: time="2025-12-08T08:50:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:50:13 volumio go-librespot[14345]: time="2025-12-08T08:50:13-06:00" level=debug msg="completed keyexchange"
Dec 08 08:50:13 volumio go-librespot[14345]: time="2025-12-08T08:50:13-06:00" level=debug msg="completed challenge"
Dec 08 08:50:13 volumio go-librespot[14345]: time="2025-12-08T08:50:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:50:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:50:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:50:14 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:50:14 volumio volumio[13559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 08 08:50:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33.
Dec 08 08:50:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 08 08:50:16 volumio go-librespot[14352]: go-librespot daemon starting...
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=info msg="running go-librespot 0.4.0"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=debug msg="app state loaded"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=debug msg="stored credentials not found"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=info msg="zeroconf server listening on port 38637"
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=debug msg="obtained new client token: AADLvEjO1t7lJUn0X9JEJ8OiKkaJfUmAV8KQeZ+vAgGVS5n5Ll6dmDW7T9PpTgLngwZTEBcuxRfQkWxIasSiuUvkzs2dyRLR4IFBwn/zPxb0LYsZLDCdSyV+PR2vxN4geLkG3M5i6papSI7n/9acNLp9+oLAHxTgxaqZRxpCUMRyLhwyDjWjVrD+pJAcA2g0AkNkWaVq/8KFfgNHts1JZkhJybi9zhfpqs4p59dXzOO/yA481/Q4b6OqPQ=="
Dec 08 08:50:16 volumio go-librespot[14356]: time="2025-12-08T08:50:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 08 08:50:17 volumio volumio[13559]: info: Initializing connection to go-librespot Websocket
Dec 08 08:50:17 volumio go-librespot[14356]: time="2025-12-08T08:50:17-06:00" level=debug msg="new websocket client"
Dec 08 08:50:17 volumio volumio[13559]: info: Connection to go-librespot Websocket established
Dec 08 08:50:20 volumio volumio[13559]: info: Getting Spotify volume
Dec 08 08:50:20 volumio volumio[13559]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Dec 08 08:50:20 volumio volumio[13559]: info: CoreCommandRouter::volumioGetState
Dec 08 08:50:20 volumio volumio[13559]: info: CorePlayQueue::getTrack 0
Dec 08 08:50:20 volumio volumio[13559]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86
Dec 08 08:50:20 volumio volumio[13559]: SPOTIFY: SPOTIFY VOLUME undefined
Dec 08 08:50:20 volumio volumio[13559]: SPOTIFY: VOLUMIO VOLUME 86
Dec 08 08:50:20 volumio volumio[13559]: info: Aligning Spotify Volume to Volumio Volume
Dec 08 08:50:20 volumio volumio[13559]: info: CoreCommandRouter::volumioGetState
Dec 08 08:50:20 volumio volumio[13559]: info: CorePlayQueue::getTrack 0
Dec 08 08:50:20 volumio volumio[13559]: info: Setting Spotify Volume from Volumio: 86
Dec 08 08:50:21 volumio volumio[13559]: SPOTIFY: SETTING SPOTIFY VOLUME 86
Dec 08 08:50:21 volumio volumio[13559]: info: Sending Spotify command with payload to local API: /player/volume
Dec 08 08:50:26 volumio go-librespot[14356]: time="2025-12-08T08:50:26-06:00" level=debug msg="completed keyexchange"
Dec 08 08:50:26 volumio go-librespot[14356]: time="2025-12-08T08:50:26-06:00" level=debug msg="completed challenge"
Dec 08 08:50:27 volumio go-librespot[14356]: time="2025-12-08T08:50:27-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 08 08:50:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 08 08:50:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 08 08:50:27 volumio volumio[13559]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up
Dec 08 08:50:27 volumio volumio[13559]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 08 08:50:27 volumio volumio[13559]: Error: socket hang up
Dec 08 08:50:27 volumio volumio[13559]: at connResetException (node:internal/errors:720:14)
Dec 08 08:50:27 volumio volumio[13559]: at Socket.socketOnEnd (node:_http_client:519:23)
Dec 08 08:50:27 volumio volumio[13559]: at Socket.emit (node:events:526:35)
Dec 08 08:50:27 volumio volumio[13559]: at endReadableNT (node:internal/streams/readable:1376:12)
Dec 08 08:50:27 volumio volumio[13559]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Dec 08 08:50:27 volumio volumio[13559]: code: 'ECONNRESET',
Dec 08 08:50:27 volumio volumio[13559]: response: undefined
Dec 08 08:50:27 volumio volumio[13559]: }
Dec 08 08:50:27 volumio volumio[13559]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 08 08:50:27 volumio sudo[14407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-08 08:49'
Dec 08 08:50:27 volumio sudo[14407]: 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"