Nov 04 23:11:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Nov 04 23:11:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:00 volumio go-librespot[24349]: go-librespot daemon starting... Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="app state loaded" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=info msg="zeroconf server listening on port 46827" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="obtained new client token: AAD0yVHQKWeNnJcnaVZjiCM0w8X8jTfMV6wEGZwDobRM5E1doT7kWkvFvm1o4T9JZalX5YbJw3cmBTzhwxjot5JGVv/amUsQCTYSYcJHZXB4SdggV4lbPRdVsYEzUhzBvtpeKEnioFY8c0oWBSd1YlQO3FD0XBo/qhNhfaKPVoHXZCSdYb59TXX7aE81vGRVrcN5puPLvf5f5JEYHz/DLxsW1TIQqRb7wHYU9AulMmwDgea61Urwmz8=" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="completed keyexchange" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=debug msg="completed challenge" Nov 04 23:11:00 volumio go-librespot[24350]: time="2025-11-04T23:11:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:01 volumio volumio[24087]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 04 23:11:01 volumio sudo[24372]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 04 23:11:01 volumio sudo[24372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 04 23:11:01 volumio sudo[24374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 04 23:11:01 volumio sudo[24374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 04 23:11:01 volumio sudo[24372]: pam_unix(sudo:session): session closed for user root Nov 04 23:11:01 volumio sudo[24374]: pam_unix(sudo:session): session closed for user root Nov 04 23:11:01 volumio sudo[24378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 04 23:11:01 volumio sudo[24378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 04 23:11:01 volumio sudo[24378]: pam_unix(sudo:session): session closed for user root Nov 04 23:11:01 volumio volumio[24087]: info: Upmpdcli Daemon Started Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 04 23:11:02 volumio volumio[24087]: info: Adding plugin bluetooth to MyMusic Plugins Nov 04 23:11:02 volumio volumio[24087]: info: Adding plugin multiroom to MyMusic Plugins Nov 04 23:11:02 volumio volumio[24087]: info: Adding plugin metavolumio to MyMusic Plugins Nov 04 23:11:02 volumio volumio[24087]: info: Adding plugin cd_controller to MyMusic Plugins Nov 04 23:11:02 volumio volumio[24087]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 04 23:11:02 volumio volumio[24087]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 04 23:11:02 volumio volumio[24087]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 04 23:11:02 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:02 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:02 volumio volumio[24087]: info: Starting MyVolumio Remote Streaming Endpoints Nov 04 23:11:02 volumio volumio[24087]: info: MyVolumio login type: Token Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 04 23:11:02 volumio volumio[24087]: info: Streaming services startup Nov 04 23:11:02 volumio volumio[24087]: info: Starting Streaming Daemon Nov 04 23:11:02 volumio sudo[24381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 04 23:11:02 volumio sudo[24381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 04 23:11:02 volumio volumio[24087]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 04 23:11:02 volumio sudo[24381]: pam_unix(sudo:session): session closed for user root Nov 04 23:11:02 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:02 volumio volumio[24087]: error: Cannot start Volumio Streaming Daemon Nov 04 23:11:02 volumio volumio[24087]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 04 23:11:02 volumio volumio[24087]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 04 23:11:02 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:03 volumio volumio[24087]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 04 23:11:03 volumio volumio[24087]: info: MyVolumio token set successfully Nov 04 23:11:03 volumio volumio[24087]: info: MYVOLUMIO: Adding device Nov 04 23:11:03 volumio volumio[24087]: info: MYVOLUMIO: Evaluating Server Nov 04 23:11:03 volumio volumio[24087]: info: MyVolumio status changed Nov 04 23:11:03 volumio volumio[24087]: info: Streaming services startup Nov 04 23:11:03 volumio volumio[24087]: info: Starting Streaming Daemon Nov 04 23:11:03 volumio volumio[24087]: info: Removing browser output: myVolumio user plan is not superstar Nov 04 23:11:03 volumio volumio[24087]: info: Removing audio output: Nov 04 23:11:03 volumio volumio[24087]: info: Stoppping Tunnel 1 Nov 04 23:11:03 volumio sudo[24392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 04 23:11:03 volumio sudo[24392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 04 23:11:03 volumio sudo[24394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 04 23:11:03 volumio sudo[24394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 04 23:11:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Nov 04 23:11:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:03 volumio go-librespot[24399]: go-librespot daemon starting... Nov 04 23:11:03 volumio sudo[24392]: pam_unix(sudo:session): session closed for user root Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="app state loaded" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:04 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. Nov 04 23:11:04 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. Nov 04 23:11:04 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. Nov 04 23:11:04 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. Nov 04 23:11:04 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. Nov 04 23:11:04 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. Nov 04 23:11:04 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. Nov 04 23:11:04 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. Nov 04 23:11:04 volumio volumio[24087]: error: Cannot start Volumio Streaming Daemon Nov 04 23:11:04 volumio volumio[24087]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 04 23:11:04 volumio volumio[24087]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 04 23:11:04 volumio sudo[24394]: pam_unix(sudo:session): session closed for user root Nov 04 23:11:04 volumio volumio[24087]: info: Remote SSH Stopped Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=info msg="zeroconf server listening on port 36639" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="obtained new client token: AADOpxRD7/KYofJ+IKEjeDtmLTTFJJor2x/NPgT1YL0P+hskfzEKW0g2QUqmNVo0qcbxw7YCxkljUpeTp9kXt/KkoCYt8WdBt5rpf5R8qUZQ9MTcnNSNMmI6D66R4XRcNj4khpbwN6OMsVP5oyWocbHkcwzu8PHWImr25qVVMPrpbOocOdZ9H0iUZGvgGEyksr86+mqetNt1OZjKIWru26TIXVYfquE0kay659E83MwtutHIhLGc" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="completed keyexchange" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=debug msg="completed challenge" Nov 04 23:11:04 volumio go-librespot[24410]: time="2025-11-04T23:11:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:04 volumio volumio[24087]: info: Setting Geolocation for MyVolumio to eu3 Nov 04 23:11:04 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:04 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:04 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:04 volumio volumio[24087]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 04 23:11:05 volumio volumio[24087]: info: Updating MyVolumio device info Nov 04 23:11:05 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:05 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:05 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:05 volumio volumio[24087]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 04 23:11:05 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:05 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:06 volumio volumio[24087]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 04 23:11:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Nov 04 23:11:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:07 volumio go-librespot[24420]: go-librespot daemon starting... Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="app state loaded" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=info msg="zeroconf server listening on port 39613" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="obtained new client token: AADo5TakdW7PMug3ep1FFjw7/5ZyOdgRC2zzFiDWwEFo/IAbWOzTAPkqQOOMfuWJV5naeqQKJer32eN1dOwrutYdJ1qbuyrUgQVa9Hjk6z8gBTqxlnZlrrsivr2pdi18LJyc4LTnG8Ri4QJeSMjQYsPuLuBGUrXsiKGYmAcqT9u7DwPuhHPkQ0uUKjOpXc9T8KyHKiR4FWVYTsubB+V5kcipfKAHx9m5RJV6pY0d8WiPAQVAXcz+his=" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="completed keyexchange" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=debug msg="completed challenge" Nov 04 23:11:07 volumio go-librespot[24421]: time="2025-11-04T23:11:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:08 volumio volumio[24087]: info: MYVOLUMIO: Adding device Nov 04 23:11:08 volumio volumio[24087]: info: MYVOLUMIO: Evaluating Server Nov 04 23:11:08 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:08 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:09 volumio volumio[24087]: info: Setting Geolocation for MyVolumio to eu3 Nov 04 23:11:09 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:09 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:09 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:09 volumio volumio[24087]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 04 23:11:09 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:11:09 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:11:10 volumio volumio[24087]: info: Updating MyVolumio device info Nov 04 23:11:10 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:10 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:10 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:10 volumio volumio[24087]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 04 23:11:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Nov 04 23:11:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:11 volumio go-librespot[24444]: go-librespot daemon starting... Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="app state loaded" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=info msg="zeroconf server listening on port 43975" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="obtained new client token: AAAb5V0J0sqcsrHtflTK5PrTzXpgHHSrDjYG01mhoZg0rB3fOY7ygHD2kIyll/NclKpQuS1Z7w5dgiVuKNJtD/GJSD6qNs0FE4+RjX2diqKq3qAh5QKK53zNRvWmxiAcul1GXoZ8LnWu/xSTnvQMHGRIcJHYe3OdB5MSFycCEk2CcVHBfz1KlYrIUHygFgFx9Wp72fYr2HRMUzadSeGularhGcOCkH1hMIIZGrrr6BeUNCIsborD" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="completed keyexchange" Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=debug msg="completed challenge" Nov 04 23:11:11 volumio volumio[24087]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 04 23:11:11 volumio go-librespot[24445]: time="2025-11-04T23:11:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:11 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:11 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Nov 04 23:11:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:14 volumio go-librespot[24466]: go-librespot daemon starting... Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="app state loaded" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=info msg="zeroconf server listening on port 34961" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="obtained new client token: AACVzasfhiOtMjjrhluUuKj4LyDSXZfcoMKt90Xe5zmZqKFnNh+nv94fm7T5bV278vo7W8v75QaHZcUN4FJS+JPRjvDYN09WD4PzvXMyOEU5ZvTzwvf9L/CwcaVo4Fa0zDjjGqJj05PeNG1nwfxh/V2IIkblqnt7rSAOMsV4bTjl4zsvdjVO0IjBCh95OAPCs0znZnDB6tHmYyykq8TC7MXX/56af5oDKW3hWQu4qe/W9zZYFlH9txs=" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="completed keyexchange" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=debug msg="completed challenge" Nov 04 23:11:14 volumio go-librespot[24467]: time="2025-11-04T23:11:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:14 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:14 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:17 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 04 23:11:17 volumio volumio[24087]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 04 23:11:17 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:11:17 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:11:17 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:17 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Nov 04 23:11:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:18 volumio go-librespot[24478]: go-librespot daemon starting... Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="app state loaded" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=info msg="zeroconf server listening on port 36905" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="obtained new client token: AAC+fCwUlaOhkqocTYzdHUiBqZ0uSfChhc+uHjvET+Ovlxh3GOqldy21qFE1WmB8W3tv+K3xXIlVWOZCssvkHWjTlrzkX0aznF6enm752Hkh8HytaiDZd9ViSK/MIPN2TFxNyH7fdniW9IOLEWdkPuf0bBjeBsW/r9OBQ9MpKiRhFAoCrXGZdRauKxRrJe7i2kxg9ptiikAEfkfTR/+9x9XXHgy/9sqblMo1xW/p1xOlmz0PoLy1" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="completed keyexchange" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=debug msg="completed challenge" Nov 04 23:11:18 volumio go-librespot[24479]: time="2025-11-04T23:11:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:19 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:11:19 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:11:19 volumio volumio[24087]: info: Listing playlists Nov 04 23:11:19 volumio volumio[24087]: info: Listing playlists Nov 04 23:11:20 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:20 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Nov 04 23:11:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:21 volumio go-librespot[24500]: go-librespot daemon starting... Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="app state loaded" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=info msg="zeroconf server listening on port 45689" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="obtained new client token: AABMyw6XrXclBXqsMYQXokw9FSBPydQVex8dRsnxwYb8/Hgc99kseH0ER5I8bSJTmDM35y+lpmDibVzso1wmk3xeXk2aybZXTIzYXHHcaZWDq+bltP9q0CqJASoVRPvdak5CjpvFHdEQVOPwSxMmMGyT/v1lAJ/3dULwUb6B78ecLEwKuPrEyS5HAz4iX1iTCtvrPWgNSWRLJB+b9ZIux+5ayT4N3jQUqHHHm5YxE2Eysyizck/pvss=" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="completed keyexchange" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=debug msg="completed challenge" Nov 04 23:11:21 volumio go-librespot[24501]: time="2025-11-04T23:11:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::volumioGetBrowseSources Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 23:11:22 volumio volumio[24087]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 04 23:11:22 volumio volumio[24087]: info: Completed starting MyVolumio Plugin Nov 04 23:11:22 volumio volumio[24087]: [Metrics] CommandRouter: 32s 9.58ms Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::volumiosetStartupVolume Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:11:22 volumio volumio[24087]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 23:11:23 volumio volumio-remote-updater[874]: Test mode disabled Nov 04 23:11:23 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 04 23:11:23 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 04 23:11:23 volumio volumio[24087]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 04 23:11:23 volumio volumio[24087]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 04 23:11:23 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:23 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Nov 04 23:11:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:25 volumio go-librespot[24517]: go-librespot daemon starting... Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="app state loaded" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=info msg="zeroconf server listening on port 35905" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="obtained new client token: AAAxOrMQMC0Ei/cW1v/wxgYFYoeubNWe36TikhyauAquCD1sYDvA4Z1jo2uQVV3QvwI+WPXop7QG9eXDTJoF0jlVJ5hrgfRy/cfkhxC2quZ5gIVnUdNoityL14pUImZoybTPqOIk24y87K+rn2UCPpFEb2HLbjNQRH8N5DMHHH90uZvbz9Jdr1DY5du+RcrQ8swM2rIRPpS+lyY0CB1q7VBxQ4/rndBH4HrOg3OBfUbn2yW8VNTQ" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="completed keyexchange" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=debug msg="completed challenge" Nov 04 23:11:25 volumio go-librespot[24518]: time="2025-11-04T23:11:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:26 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:26 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Nov 04 23:11:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:28 volumio go-librespot[24525]: go-librespot daemon starting... Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="app state loaded" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=info msg="zeroconf server listening on port 44359" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="obtained new client token: AABNH31ST3wP186oJyvQngYtmO5shvj8eZnQcLUlAdDTam93dKZoJ9uCPphBdVAMXDHley/QtSQ0JsJcAJz39HcTKYQdAS3A3d0zg/F1ZMrceAajsglbadUXajScu+Z3nPjUDTOhiq4UaN8CeuPXak8k9FDZWmCaicDHvQyZaskDyzb1p4jnusBgG5CEAsffv+0hs6bqMbmsgWXaOx2IX3nnnjd241VfyCPraaJOhzSlHtHsGzo2hM8=" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="completed keyexchange" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=debug msg="completed challenge" Nov 04 23:11:28 volumio go-librespot[24526]: time="2025-11-04T23:11:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:29 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:11:29 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:11:29 volumio volumio[24087]: info: BOOT COMPLETED Nov 04 23:11:29 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:29 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Nov 04 23:11:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:32 volumio go-librespot[24549]: go-librespot daemon starting... Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="app state loaded" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=info msg="zeroconf server listening on port 34457" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="obtained new client token: AACevP6puPDzZvvo/8npguMm1/zSkhfZBaMHVZtUYEQM8agLQM9N0ExRShOCKIiiI3mQCrCEtjnq6oVTI2un/NS0h18mRzvDkqK8Fo+wUG3YHDhZ16d33/2mOE++Zn75PSdklUluwsu3OI/JdnU7zgn0np9Jbm8mbrzqEQSNRmYTjlZB6gEtXXPcNdtGCQBIcIWFurTLaZxWx6QbGZt3iycwWn/swa8FGgnwuYANH9SZEiJ9+6FQ" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="completed keyexchange" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=debug msg="completed challenge" Nov 04 23:11:32 volumio go-librespot[24550]: time="2025-11-04T23:11:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:32 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:32 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Nov 04 23:11:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:35 volumio go-librespot[24557]: go-librespot daemon starting... Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="app state loaded" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=info msg="zeroconf server listening on port 39915" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="obtained new client token: AACgWhv2lVmW+uVZb9KK+w8X72Z6a23IEFLdDgH2AqaSfyXUG39GMqHkQ7Q4OQxUJQCiB9R9RFFVkg1jRf2qYs6i3FQI1K4nZ1wZ1yzuOWQeCq9UzTXAsZsPhsWnfE2Fycsq6t9XR8/ymiBL53KE01O1j1fNg01g6dGADq1otUUjRdzVe0dkidGuyU5a+LDXWv1biSrhVUFJ5Jsa6mCbHblGVLEgsIzIE9OFvD8i5Kudm76j+2xqEak=" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="completed keyexchange" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=debug msg="completed challenge" Nov 04 23:11:35 volumio go-librespot[24558]: time="2025-11-04T23:11:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:35 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:35 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:38 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:38 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Nov 04 23:11:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:39 volumio go-librespot[24568]: go-librespot daemon starting... Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="app state loaded" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=info msg="zeroconf server listening on port 38145" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="obtained new client token: AACItWruBBAtPngbxsvTswgeRe3rIyWvxFCs1uIZOC3d1vtDVSSBtT+E9JAKZGX6RW/IC11QjDOpBDIZZ1JcDsSVvYe0TGBjkmCYfdzX5+881NYfCpyUt6cV4+JUSdyyor2j5Gs/SK/772Rkd1xrUHXdbq1N3Fuf3D3kREF9VYViqjoLqz03KQH6wVerrPvl6/KIDQ0Pelyy8nbOvavXlQn7xuZVeBC8xt60DtsS4ZovBBRm/AK+" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="completed keyexchange" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=debug msg="completed challenge" Nov 04 23:11:39 volumio go-librespot[24569]: time="2025-11-04T23:11:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:39 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:11:39 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:11:39 volumio volumio[24087]: info: Listing playlists Nov 04 23:11:39 volumio volumio[24087]: info: Listing playlists Nov 04 23:11:41 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:41 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Nov 04 23:11:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:42 volumio go-librespot[24591]: go-librespot daemon starting... Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=debug msg="app state loaded" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=info msg="zeroconf server listening on port 44511" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=debug msg="obtained new client token: AADZo7G+NvRBvHHe8axvbS1Hp9X5i6AlG93j1Vj7JCYCJCRwdQxs4B2rQ+MYoDvnQwP6U2pu0ZieT0BR1DuDKJoYDIdkBtQybIudd9rmhYlqen8Oz2cv/JNpKMPLVsj5UjFSkfHgBM0oaGtmFluUcKCB7vZ2CRNO/t3qBzR0N3JhZ8Vbd6Jv/Y5wvd//VQF08DU+4m2F7uNatuN7MJeFK45PzcXnPjlNzQarywueYJYxSAp2MbrecPo=" Nov 04 23:11:42 volumio go-librespot[24592]: time="2025-11-04T23:11:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:43 volumio go-librespot[24592]: time="2025-11-04T23:11:43Z" level=debug msg="completed keyexchange" Nov 04 23:11:43 volumio go-librespot[24592]: time="2025-11-04T23:11:43Z" level=debug msg="completed challenge" Nov 04 23:11:43 volumio go-librespot[24592]: time="2025-11-04T23:11:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:44 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:44 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Nov 04 23:11:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:46 volumio go-librespot[24599]: go-librespot daemon starting... Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="app state loaded" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=info msg="zeroconf server listening on port 45801" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="obtained new client token: AAAVXtLfRTWm45Jz+9+mPudvhlGKI7MEGOTvuQ5sALNzgiRdEgLqsXFP2CfOQ8JntLeu4kOg4kU3nFpkswE+pqLVJ8B0ojs1YVVnFbwWMXNrv28dcNLSrgTVKQWGN/KbbG/4KslRHGpvCqr8etibbefjeztsLj66T6x31Ryx+pMoYgxL5r8afI2ttcKAbErbjZ4+Ap/GywjvFbxbo3IS3zvr21SeMyXO/ccM/9d1HzYrn4F1A4MzjeI=" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="completed keyexchange" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=debug msg="completed challenge" Nov 04 23:11:46 volumio go-librespot[24600]: time="2025-11-04T23:11:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:47 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:47 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:49 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:11:49 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:11:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Nov 04 23:11:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:50 volumio go-librespot[24608]: go-librespot daemon starting... Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="app state loaded" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=info msg="zeroconf server listening on port 46695" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="obtained new client token: AACr0D02g5DKKUPUdrfmbG7uudZYm/cKOuxlmVf8jR+fIo22oEUgLtEXZevO2YspKp0lMqyQDPTD+QA+vBwIKkLIiKN2eRekB1LrOF0dd/2JmJ9Q0odRUg/GKB1DrewPBj58QmVfS4WA9CWdvMrjanz0ZTAxcR8ZkQoRiz8TU/pnyzSNgsTWdVCl/Fjzvp2Ou4q+C1uiCkzjleFC0bMV7TATVWZQLUpc0Gj2VuBk8nFXLWIEhjhf" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="completed keyexchange" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=debug msg="completed challenge" Nov 04 23:11:50 volumio go-librespot[24609]: time="2025-11-04T23:11:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:50 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:50 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Nov 04 23:11:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:53 volumio go-librespot[24631]: go-librespot daemon starting... Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="app state loaded" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=info msg="zeroconf server listening on port 34919" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="obtained new client token: AAD+zetjfP20Um4BbRZMyFZPg0j03eHHuF4ZFaEQBU3rwlpkXkq97O6frXhhbUUd1ll/B6wIA6mzLT52mN1X9jg308r0OKdIapeCpvyAIROcpKPS2xQ00ePqHxnFTWOFEl5IRSh0Nvdh5TtXVEl6yYD3ZEdWom0QVSbQ1gJgyVFXcCScugJRteB4hunm9ousFJjkAUg0SrGsRua3Q4zo7ESiPiX+m9DPbBIPsIdViabkg1dMhtBJGwQ=" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="completed keyexchange" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=debug msg="completed challenge" Nov 04 23:11:53 volumio go-librespot[24632]: time="2025-11-04T23:11:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:53 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:53 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:56 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:56 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:11:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Nov 04 23:11:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:11:57 volumio go-librespot[24642]: go-librespot daemon starting... Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="app state loaded" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=info msg="zeroconf server listening on port 38377" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="obtained new client token: AAB933/Iqlwmi/w67S3ShYbg9s1e+xJcQ3YQwnINnxuJbZeaSZP6VjabJn7DcLZoEeyAD/NtT6PRuPp52frCAxVzUIBeNUgYR5jOBF/mvTlDGPeGebSGc9GP7j2ItlJXZw4z/D3lyW2QavULy7RsPELJLYq9ObTNl7aN3ox8AaBGhCeI3Xi7OtoAju8VeJYqqFjtD9m955Pd66Zlm2yxFFwKkGWZ1DbWsaDhb98LcCP3xaNdIBbI" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="completed keyexchange" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=debug msg="completed challenge" Nov 04 23:11:57 volumio go-librespot[24643]: time="2025-11-04T23:11:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:11:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:11:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:11:59 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:11:59 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:11:59 volumio volumio[24087]: info: Listing playlists Nov 04 23:11:59 volumio volumio[24087]: info: Listing playlists Nov 04 23:11:59 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:11:59 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Nov 04 23:12:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:00 volumio go-librespot[24650]: go-librespot daemon starting... Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="app state loaded" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=info msg="zeroconf server listening on port 42845" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="obtained new client token: AADWT6jm7sv9DuaqUp5Mz/B3/KePZLm54lwpe1wuLL/CZ9eSABggHuH6v2VYHvixtmJdQDMPvZ78aPPlOT7cKALP3OowoQP1FBWMXfRvOWKOIcjz4MB8icOuDQqppIxNl5EkpVXOqASsBX/aWNp/HOoToeuMnWBXdBumcMefp2DHI8vgIuFz+kVRLZTs3ss/b0MansPVplstM1IvKg8++rAdeDEZVLRuNCXgglxpTMKpNl9yOMvG00M=" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="completed keyexchange" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=debug msg="completed challenge" Nov 04 23:12:00 volumio go-librespot[24651]: time="2025-11-04T23:12:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:02 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:02 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Nov 04 23:12:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:04 volumio go-librespot[24672]: go-librespot daemon starting... Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="app state loaded" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=info msg="zeroconf server listening on port 33515" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="obtained new client token: AADK6WpO6wrbvRy66lRUnOdr6Dgasvp1DkE5nNGWEeWCSfgeRTpCXeNBYK2oP09/xHTqPadUtK9ILb1KeV7iLEnz1iUgJwoievnOa2Es+aW7TbC0BcAkp3hn5CI0n2CRxXwn+BtoXxm9YjHp7v6HjiEltpkhVLNVWR2ekNSYVzVXrXf2JcdIppNZBqhiFMmKfvBrB3Jy5iLyvw7RLy6KldomwFNY+NIMSsYL9AqXPTp8BZzdiz4C" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="completed keyexchange" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=debug msg="completed challenge" Nov 04 23:12:04 volumio go-librespot[24673]: time="2025-11-04T23:12:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:05 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:05 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Nov 04 23:12:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:07 volumio go-librespot[24680]: go-librespot daemon starting... Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="app state loaded" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=info msg="zeroconf server listening on port 38583" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="obtained new client token: AABhn2AQ9Hk1KXLZpOfPhqBoPQ6LjGKSQlWDF4LGOOtV90q/2b64vpu6XRcIzt3VOecZ+oNIN6yFXp38kFxza4IgBcYPbd8H/xBN2dg33MMmhWGst1+2YIC95odp2Q9KShi/grfdFpSFD9NEL4eqj+ReyiItQn3GvWlSVqGITTihswgRSv3v5omCXTr5uQAkDNdPpIgKHZZfkDecLQFMbsBtrNU74lfCtza6fF/dVijvm5nFP05NvtM=" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="completed keyexchange" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=debug msg="completed challenge" Nov 04 23:12:07 volumio go-librespot[24681]: time="2025-11-04T23:12:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Nov 04 23:12:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:08 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:08 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:09 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:09 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Nov 04 23:12:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:11 volumio go-librespot[24689]: go-librespot daemon starting... Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="app state loaded" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=info msg="zeroconf server listening on port 37597" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="obtained new client token: AAC+ia6x8TyaTpUu+zzrnZunozY5x+lHJh+DJMKF4U4LhAwE4eqX+KQLN9bJIcOdLRpbWSnBWG74QPndrhCCDsPesT7SxPPZj2qqv5oZEzdcnUejDSKXiy6dZ7Hj/uiq9xuSbY6tb7M5StDKBBu2EO7sObSJl6YTxPmsJzDHiYmshUzvkTjmrXcCkl/oilQyjd11Xwee1SwUtwuca0qhtTdjf9tNhDL0LfFKuNEyxpPyVBpgE2fx" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="completed keyexchange" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=debug msg="completed challenge" Nov 04 23:12:11 volumio go-librespot[24690]: time="2025-11-04T23:12:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:11 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:11 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Nov 04 23:12:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:14 volumio go-librespot[24711]: go-librespot daemon starting... Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="app state loaded" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=info msg="zeroconf server listening on port 33581" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="obtained new client token: AADvop98A30BJKuQ/t35hde1p496bXv2tFSvB2+SOt/EVYbitISIPAq8OIReEAibq8UrZoDgLJYe4/Rin/S/0lZMRyb5Q278VYfa1TrT00IfgMaziym4quLf2FXsF+Z2kqDPgh5sIHxgci7LSahqfEut+e2BxpOvXX7q1a9fo3HptksPaobRRqYWbWI+KzZTXWWFwe6ONx9a8HPT3+i4VB4cNi4LRbm/v1uRqR7I95ITPOjw4PQohd4=" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="completed keyexchange" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=debug msg="completed challenge" Nov 04 23:12:14 volumio go-librespot[24712]: time="2025-11-04T23:12:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:14 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:14 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:17 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:17 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Nov 04 23:12:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:17 volumio go-librespot[24722]: go-librespot daemon starting... Nov 04 23:12:17 volumio go-librespot[24723]: time="2025-11-04T23:12:17Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:17 volumio go-librespot[24723]: time="2025-11-04T23:12:17Z" level=debug msg="app state loaded" Nov 04 23:12:17 volumio go-librespot[24723]: time="2025-11-04T23:12:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=info msg="zeroconf server listening on port 35581" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=debug msg="obtained new client token: AABzC1jYxu2SkiY6UBj9bJozqcjC89qom1VdGKHAntk266hgJL8i7qvbK0Rx1qDCDbkw8mPYVCIg9pgRqM7N+sAZJf2ClEFpLoq0+2NLF47nZPsBDegKqRTwtSLI2nU3Y5Pr/e5VBFlU8UiBF2bXGF8xsYI4j6U1jjo1xn/txKLeTa3jANBMgChcJ8C8XfEjq5elRDdw1DhjcmXe7mSdAdE846mj0iU776AFOrh6zLet0SnSJAXi" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=debug msg="completed keyexchange" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=debug msg="completed challenge" Nov 04 23:12:18 volumio go-librespot[24723]: time="2025-11-04T23:12:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:19 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:19 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:19 volumio volumio[24087]: info: Listing playlists Nov 04 23:12:19 volumio volumio[24087]: info: Listing playlists Nov 04 23:12:20 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:20 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Nov 04 23:12:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:21 volumio go-librespot[24730]: go-librespot daemon starting... Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="app state loaded" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=info msg="zeroconf server listening on port 37297" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="obtained new client token: AACBkDlpm1VE2ymqUKFS3GqOyY3ggH9U9HcDP9Qycab0dhEt1oN2ePsfmD0qr+6CGPiHbmO17E2U3EyGYOEW95/PlAqiYSk+LgwbhHbfq1fF0cZlhpRiD+k5hAeT8lG9kwKCk4BV7MGMHJ+ajswF8sjjjZo8EHKH2fiFxXrLtyqc9wGyq9dUDR0iUIUiZ00MXwjbfvnmz0JAnk38sVxO7hwGIA2c4t6XySo0DBXicCvQlSJ8gMk8dm0=" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="completed keyexchange" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=debug msg="completed challenge" Nov 04 23:12:21 volumio go-librespot[24731]: time="2025-11-04T23:12:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:23 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:23 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Nov 04 23:12:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:24 volumio go-librespot[24753]: go-librespot daemon starting... Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=debug msg="app state loaded" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=info msg="zeroconf server listening on port 41415" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=debug msg="obtained new client token: AAAdGW5u4m3n/QZAOJYeVHK6ftAoELfwU+KfBxFuxO7dQaTmpJEPo+nPZGFZBq7wb5HPbXoYXLtmKi3rdWe0+sqpmLthS3oRZ/hSIVOpnDiEM3KqFLUGywIaH/0kCIZnBgn+ONKd1lbtZ8D3VemkMzTWR1g79pWw9wZeWZftMg3qwaEZttCRlO8/Ax3oip81vfbOZ4kwV3FBwoUTby4AYaXA54lZL83lHy+3Zu/QAIA9jmb8laaArzk=" Nov 04 23:12:24 volumio go-librespot[24754]: time="2025-11-04T23:12:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:25 volumio go-librespot[24754]: time="2025-11-04T23:12:25Z" level=debug msg="completed keyexchange" Nov 04 23:12:25 volumio go-librespot[24754]: time="2025-11-04T23:12:25Z" level=debug msg="completed challenge" Nov 04 23:12:25 volumio go-librespot[24754]: time="2025-11-04T23:12:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:26 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:26 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Nov 04 23:12:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:28 volumio go-librespot[24762]: go-librespot daemon starting... Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="app state loaded" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=info msg="zeroconf server listening on port 44681" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="obtained new client token: AADzPnm7L6NevyBcWYSJbieOsvOjnT+1lhc6HVz6uHOx2uoOC49cwTnc91luxfzUtHvXnTzg4RpcKXiYF+QHBP0vYCdQmn6WNIrtqyRMkGugC0oLgFzA/fGLvHr/mqhspIjpd/I3RYzJWm5a9etgMmQL5MdI0OuClbd5FwA0GAOLAaZ4b9kh8OPEcwMLeL4YPlba6WLOxmqSfUikJLMcPUzvXtNoK0Kb1uR4MJnFaZg9DM3eu8YxSls=" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="completed keyexchange" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=debug msg="completed challenge" Nov 04 23:12:28 volumio go-librespot[24763]: time="2025-11-04T23:12:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:29 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:29 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:29 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Nov 04 23:12:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:31 volumio go-librespot[24786]: go-librespot daemon starting... Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=debug msg="app state loaded" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=info msg="zeroconf server listening on port 44299" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=debug msg="obtained new client token: AADbWNwC5UQPHjL/ikUgVSDwhNg/zSqhq+cHSxOAfK73cb/aI9bNQv5QI0GF6xttxWKQV8vlmP1c0NO9LrM/D1b1PMo8PspJQIT2jSPgvPg11ZN1Yee4uxgUpF3AJ9mYf7vO3l43/x/PzcjQxeJe6j3+nttynQAF0GvfJyDdeKOtmy8wmCaGe32wj3/0Y1ETiOd+ds4ZfPHMVcx0xzvfteGKFtxyFHJ1SfV8VD8BBqSgkwOPyexbOfg=" Nov 04 23:12:31 volumio go-librespot[24787]: time="2025-11-04T23:12:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:32 volumio go-librespot[24787]: time="2025-11-04T23:12:32Z" level=debug msg="completed keyexchange" Nov 04 23:12:32 volumio go-librespot[24787]: time="2025-11-04T23:12:32Z" level=debug msg="completed challenge" Nov 04 23:12:32 volumio go-librespot[24787]: time="2025-11-04T23:12:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:32 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:32 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Nov 04 23:12:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:35 volumio go-librespot[24794]: go-librespot daemon starting... Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="app state loaded" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=info msg="zeroconf server listening on port 42685" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="obtained new client token: AAD5+X6+AYzOribLn0k13sfuDs0Fsfjc9GPdHeVBzcfYqVStfn8230J5rCQ/W0G/HXa2V/bbVrsm8KBDZJ9Nslc5atPDaCkdNXwgnLhN4DSdMmw9WJ6ki6IeaFsMzfchfe0WD1jjwlLiMhMeiLu4BXG8D8MC0wKwPwSoRojgSwWp5vOdzOVu0MPF3YjHAho3Qb/LnV9B37TxhDYX1xOXtfGZYxUlAcEYH+6Op6ZOCcnw8+D+VPg+NeY=" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="completed keyexchange" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=debug msg="completed challenge" Nov 04 23:12:35 volumio go-librespot[24795]: time="2025-11-04T23:12:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:35 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:35 volumio volumio[24087]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Nov 04 23:12:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:12:38 volumio go-librespot[24806]: go-librespot daemon starting... Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=debug msg="app state loaded" Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=info msg="zeroconf server listening on port 43471" Nov 04 23:12:38 volumio volumio[24087]: info: Initializing connection to go-librespot Websocket Nov 04 23:12:38 volumio volumio[24087]: info: Connection to go-librespot Websocket established Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=debug msg="new websocket client" Nov 04 23:12:38 volumio go-librespot[24807]: time="2025-11-04T23:12:38Z" level=debug msg="obtained new client token: AACi1oSsudbRZDkUpMw1Gf/Jec6th9WwbF2UE9VoGxN7a8r9iZL3M/8Fk/BV9nr0b05CBd7BQEDzQmw0r1GmJLHRCPzzOCp6pmf37TnJQGlumy16G1IrPmLOnzg9I5ve0Lu5BRVZJEUqMPAdaQlgSivoPwxyc5tSFlayysgAGRD599hYbERL4yPxEYPImjzffdcbN9eghLFaDA55TsHNYuhdXaoB3gNlfcz1ISEfde3axeFVCaDMr7k=" Nov 04 23:12:39 volumio go-librespot[24807]: time="2025-11-04T23:12:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:12:39 volumio go-librespot[24807]: time="2025-11-04T23:12:39Z" level=debug msg="completed keyexchange" Nov 04 23:12:39 volumio go-librespot[24807]: time="2025-11-04T23:12:39Z" level=debug msg="completed challenge" Nov 04 23:12:39 volumio go-librespot[24807]: time="2025-11-04T23:12:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:12:39 volumio volumio[24087]: info: Connection to go-librespot Websocket closed Nov 04 23:12:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:12:39 volumio volumio[24087]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:39 volumio volumio[24087]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:39 volumio volumio[24087]: info: Listing playlists Nov 04 23:12:39 volumio volumio[24087]: info: Listing playlists Nov 04 23:12:41 volumio volumio[24087]: info: Getting Spotify volume Nov 04 23:12:41 volumio volumio[24087]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:12:41 volumio volumio[24087]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:12:41 volumio volumio[24087]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 04 23:12:41 volumio volumio[24087]: errno: -111, Nov 04 23:12:41 volumio volumio[24087]: code: 'ECONNREFUSED', Nov 04 23:12:41 volumio volumio[24087]: syscall: 'connect', Nov 04 23:12:41 volumio volumio[24087]: address: '127.0.0.1', Nov 04 23:12:41 volumio volumio[24087]: port: 9879, Nov 04 23:12:41 volumio volumio[24087]: response: undefined Nov 04 23:12:41 volumio volumio[24087]: } Nov 04 23:12:41 volumio volumio[24087]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:12:42 volumio sudo[24842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-04 23:11' Nov 04 23:12:42 volumio sudo[24842]: 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="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"