Nov 02 16:14:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Nov 02 16:14:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:00 volumio go-librespot[655]: go-librespot daemon starting... Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=debug msg="app state loaded" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" 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 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" 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 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=info msg="zeroconf server listening on port 39699" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=debug msg="obtained new client token: AACBoaNys/ufH8qcwGBl+qkDv2Uja9cz6O47g55hLU/2eSI0J8Lv/OZCN/QCdToU2A5zC3LD/tS1nIawWRYcoh7M6JOiFrD/aK5nHARhZuG8PIRQLTzER1X/EkXYhmI55+DRsmFZBQVsK7Khc2XL4VwEtm5vEZFKNCDjsR3jd/57lkEbl9kGx1wHMn4DVnlrnjdLYDe8aSuOdv57zE7c8pnWg1YjC7Fuxvz26OYk7pWkilnz28jgtGs=" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=debug msg="completed keyexchange" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14:00Z" level=debug msg="completed challenge" Nov 02 16:14:00 volumio go-librespot[656]: time="2025-11-02T16:14: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 02 16:14:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:01 volumio volumio[389]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 02 16:14:01 volumio sudo[679]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 16:14:01 volumio sudo[679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 16:14:01 volumio sudo[681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 16:14:01 volumio sudo[679]: pam_unix(sudo:session): session closed for user root Nov 02 16:14:01 volumio sudo[681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 16:14:01 volumio sudo[681]: pam_unix(sudo:session): session closed for user root Nov 02 16:14:01 volumio sudo[685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 16:14:01 volumio sudo[685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 16:14:01 volumio sudo[685]: pam_unix(sudo:session): session closed for user root Nov 02 16:14:01 volumio volumio[389]: info: Upmpdcli Daemon Started Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 16:14:02 volumio volumio[389]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 16:14:02 volumio volumio[389]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 16:14:02 volumio volumio[389]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 16:14:02 volumio volumio[389]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 16:14:02 volumio volumio[389]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 02 16:14:02 volumio volumio[389]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 16:14:02 volumio volumio[389]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 16:14:02 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:02 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:02 volumio volumio[389]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 16:14:02 volumio volumio[389]: info: MyVolumio login type: Token Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 16:14:02 volumio volumio[389]: info: Streaming services startup Nov 02 16:14:02 volumio volumio[389]: info: Starting Streaming Daemon Nov 02 16:14:02 volumio sudo[698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 16:14:02 volumio sudo[698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 16:14:02 volumio volumio[389]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 16:14:02 volumio sudo[698]: pam_unix(sudo:session): session closed for user root Nov 02 16:14:03 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:03 volumio volumio[389]: error: Cannot start Volumio Streaming Daemon Nov 02 16:14:03 volumio volumio[389]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 16:14:03 volumio volumio[389]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 16:14:03 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:03 volumio volumio[389]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 16:14:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Nov 02 16:14:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:04 volumio go-librespot[708]: go-librespot daemon starting... Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=debug msg="app state loaded" Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:04 volumio volumio[389]: info: MyVolumio token set successfully Nov 02 16:14:04 volumio volumio[389]: info: MYVOLUMIO: Adding device Nov 02 16:14:04 volumio volumio[389]: info: MYVOLUMIO: Evaluating Server Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14: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 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14: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 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14: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 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=info msg="zeroconf server listening on port 41267" Nov 02 16:14:04 volumio volumio[389]: info: MyVolumio status changed Nov 02 16:14:04 volumio volumio[389]: info: Streaming services startup Nov 02 16:14:04 volumio volumio[389]: info: Starting Streaming Daemon Nov 02 16:14:04 volumio volumio[389]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 16:14:04 volumio volumio[389]: info: Removing audio output: Nov 02 16:14:04 volumio volumio[389]: info: Stoppping Tunnel 1 Nov 02 16:14:04 volumio sudo[734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 16:14:04 volumio sudo[734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=debug msg="obtained new client token: AADYVh1RlqascyThVl3cMaN9fXHPsDyuYJpHlIDsMsrdmF8/lx8pltAqp5r6NCvgajaSvZBkERPuaEMuXTVVVW7Al5jvw1/RCpU2P3dpLVQYUd1nPYXgB6KqoyB4Qqtw299piY2zWs101dDpivi4bBottUtoMjEsK362zrjVsnJHNKflQ21TXiXLkcEz9/AUdrMeUfgWmR4yHjTDxtlGJ+kPCvf0165lldMDRUeCrqtUbHhkIqF/" Nov 02 16:14:04 volumio sudo[736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 16:14:04 volumio sudo[736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 16:14:04 volumio sudo[734]: pam_unix(sudo:session): session closed for user root Nov 02 16:14:04 volumio volumio[389]: error: Cannot start Volumio Streaming Daemon Nov 02 16:14:04 volumio volumio[389]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 16:14:04 volumio volumio[389]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 16:14: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 02 16:14: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 02 16:14: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 02 16:14: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 02 16:14: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 02 16:14: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 02 16:14: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 02 16:14: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 02 16:14:04 volumio sudo[736]: pam_unix(sudo:session): session closed for user root Nov 02 16:14:04 volumio volumio[389]: info: Remote SSH Stopped Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=debug msg="completed keyexchange" Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14:04Z" level=debug msg="completed challenge" Nov 02 16:14:04 volumio go-librespot[711]: time="2025-11-02T16:14: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 02 16:14:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:04 volumio volumio[389]: info: Setting Geolocation for MyVolumio to eu3 Nov 02 16:14:04 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:04 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:04 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:04 volumio volumio[389]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 16:14:05 volumio volumio[389]: info: Updating MyVolumio device info Nov 02 16:14:05 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:05 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:05 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:06 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:06 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:06 volumio volumio[389]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 16:14:06 volumio volumio[389]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 02 16:14:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Nov 02 16:14:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:07 volumio go-librespot[740]: go-librespot daemon starting... Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=debug msg="app state loaded" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14: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 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14: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 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14: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 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=info msg="zeroconf server listening on port 43669" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=debug msg="obtained new client token: AAACLlctxX3qwA58tdW7pB1cGIWeQtNpp64NwwnhX8+xt9OdIRjJhRw8kPyzVcTryasNXd/a62DQ08ScseRENNGYS0IPognvUXzJqwoGy9qjUXyfB00lGUd9coHzC+dFiwcY6wJJtP5ei5RbffYAen8A0qHHsmAer1E2NsAuh+nmXS576ledtpEagb/oWW140A+C8hOFFw8yRAYoYJirtfqYGhuH/6xvhuqhHPT23OPOcAUd813uE+s=" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=debug msg="completed keyexchange" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14:07Z" level=debug msg="completed challenge" Nov 02 16:14:07 volumio go-librespot[741]: time="2025-11-02T16:14: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 02 16:14:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:08 volumio volumio[389]: info: MYVOLUMIO: Adding device Nov 02 16:14:08 volumio volumio[389]: info: MYVOLUMIO: Evaluating Server Nov 02 16:14:09 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:09 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:09 volumio volumio[389]: info: Setting Geolocation for MyVolumio to eu3 Nov 02 16:14:09 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:09 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:09 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:09 volumio volumio[389]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 16:14:10 volumio volumio[389]: info: Updating MyVolumio device info Nov 02 16:14:10 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:10 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:10 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:10 volumio volumio[389]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 16:14:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Nov 02 16:14:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:11 volumio go-librespot[770]: go-librespot daemon starting... Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=debug msg="app state loaded" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" 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 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" 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 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" 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 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=info msg="zeroconf server listening on port 42137" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=debug msg="obtained new client token: AABRIZtv6oJKNsmqxbucU913IAIj2YHrnoB3XfaAOEE8lfSig2QoeHEwvrm4ch/jAmgBI6o4aDsMH4MBBTngPPPec1w4wXsjJ9UJRAaDIpxpra6ZSryR6S5XXKxywf1hJFv8LdaKpRk5U6nymDz+eAvinNgl8jcV9EJlJWN5T0dOOpjqJEOJsM7y/SSDDVBLUI3RNdqjm0uwF96BvGj7aavicyS9HXYk9W5rK0n1oKbb6WoJO+lx" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=debug msg="completed keyexchange" Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14:11Z" level=debug msg="completed challenge" Nov 02 16:14:11 volumio volumio[389]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 16:14:11 volumio go-librespot[771]: time="2025-11-02T16:14: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 02 16:14:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:12 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:12 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Nov 02 16:14:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:14 volumio go-librespot[792]: go-librespot daemon starting... Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=debug msg="app state loaded" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14: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-gew4.spotify.com:80]" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" 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 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" 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 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=info msg="zeroconf server listening on port 38847" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=debug msg="obtained new client token: AAB7oNrUVBRXmIHFUcduP0D6T7kEdGjS52yJUJKgLymJ1veS0VhCsSwGC+xJZebjdqd5gmkXBt0IQVLdEzgi6KkOgR+B8tQBqCxAOvIWBsB7NGr5W2LRbS+AvcVyHpL2oHaCGk6JMxXS4iVEGDj/MtQ68ap8NBap+Cfii5zPNuLNhthih+AE7XUl6Lp/g404iL1RhS42tNaZAedu/NEdMklX4TuAwqhJ0oloaYKjBqsy9vJePbtOAcU=" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=debug msg="completed keyexchange" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14:14Z" level=debug msg="completed challenge" Nov 02 16:14:14 volumio go-librespot[793]: time="2025-11-02T16:14: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 02 16:14:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:15 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:15 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:17 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 16:14:17 volumio volumio[389]: 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 02 16:14:17 volumio volumio[389]: info: CoreCommandRouter::volumioGetState Nov 02 16:14:17 volumio volumio[389]: info: CorePlayQueue::getTrack 0 Nov 02 16:14:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Nov 02 16:14:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:18 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:18 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:18 volumio go-librespot[803]: go-librespot daemon starting... Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=debug msg="app state loaded" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14: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 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14: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 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14: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 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=info msg="zeroconf server listening on port 46003" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=debug msg="obtained new client token: AACzzkTudkwSGT+fU0yQzPejcwpCLYLoLmAoQSp78dhNoIvXU6NtQwPabIFhL+/HEnF5a/nDK0TiBv+Vh/b9LqGdwQAYgZqlJmhqESv16CEdnkhCy5+7cuqn2xWFDPRhTIsAmMjqjB1VtjuaJYTxdr+2MpboFDfMIUdiecLw9GA7HhoAFG+IDj7Bv82j2zdsFJviHpWk17/frnLZ2BKpqH14I4CJVf+GhI82D4xvLiDgHEfx85LV" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=debug msg="completed keyexchange" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14:18Z" level=debug msg="completed challenge" Nov 02 16:14:18 volumio go-librespot[804]: time="2025-11-02T16:14: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 02 16:14:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:21 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:21 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Nov 02 16:14:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:21 volumio go-librespot[825]: go-librespot daemon starting... Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=debug msg="app state loaded" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" 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 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" 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 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=info msg="zeroconf server listening on port 35181" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=debug msg="obtained new client token: AAD82TFW5pMH+eHRALkGz1UVGXHoIyd/JPKz08XEfyhqiYj9CT7kiBXas6yG5SptvKmuj0fqvMHHAiZ7dQB2EroSWu1PuTj6b+djEK/frluhbZGosN1K1yfw5WVuE5jOeqntENY8vmEdxeSkoXq0RsIrpfe7NfwqtQpj68AubihgmkcR4TxqMd4p2sHUfM5N+z4rROcGCT0vNHMER9wRcibCoEDLJnuDDJornQbMi36Cl1tw6OQry5k=" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=debug msg="completed keyexchange" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14:21Z" level=debug msg="completed challenge" Nov 02 16:14:21 volumio go-librespot[826]: time="2025-11-02T16:14: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 02 16:14:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 16:14:22 volumio volumio[389]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 16:14:22 volumio volumio[389]: info: Completed starting MyVolumio Plugin Nov 02 16:14:22 volumio volumio[389]: [Metrics] CommandRouter: 32s 74.19ms Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::Close All Modals sent Nov 02 16:14:22 volumio volumio[389]: info: CoreCommandRouter::Close All Modals sent Nov 02 16:14:23 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 16:14:23 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:14:23 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 16:14:23 volumio volumio-remote-updater[874]: Test mode disabled Nov 02 16:14:23 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 02 16:14:23 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 02 16:14:23 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 16:14:23 volumio volumio[389]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 16:14:23 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 16:14:24 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 16:14:24 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 16:14:24 volumio volumio[389]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 16:14:24 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:24 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Nov 02 16:14:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:25 volumio go-librespot[843]: go-librespot daemon starting... Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=debug msg="app state loaded" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" 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 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" 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 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" 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 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=info msg="zeroconf server listening on port 37501" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=debug msg="obtained new client token: AACL7riGXo5YXDNd6mS6jBtRgRvgMvw8TkUxJYZQ8sYuo+6rNOpAXW2HEyiktXuM5piGazXZIUpnOGcTc0pBkY1c9ATl1eJ3GzOK/ljqlmI0vKwrY5CqRs92zYLEEKxxapaIyq8Bc3XuQjFpcWWQHD+ytcRbn4eihyHO5H2akCruXviYK2td3vYXkPRVprHFf1o+Hc03MoXBQRwebb28PpbB9ZVYLqZVrRVtmx4wugoNfWBtwcrp" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=debug msg="completed keyexchange" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14:25Z" level=debug msg="completed challenge" Nov 02 16:14:25 volumio go-librespot[844]: time="2025-11-02T16:14: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 02 16:14:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:27 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:27 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Nov 02 16:14:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:28 volumio go-librespot[852]: go-librespot daemon starting... Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=debug msg="app state loaded" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" 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 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" 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 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" 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 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=info msg="zeroconf server listening on port 33745" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=debug msg="obtained new client token: AAB9ve273iEdjd4Pw4kMtj3aX9AAHTYR0pQxlDzN1D2IPQB5qdKC2wNZuXyR2zm8CiIb5tJzAJsYE4kHrjQGbOpFjgZBhZgbBrau4Jhk4wlfKIIz7UZpUOuENJSm1X3D5wPfPvRYsf28Upix3URI39YFbIWX0SUcT5yVywLmtbfe7HV6nlWrRjfBH9WrLFdMK2s9cW4Yi/G1as8wVI4/Oax35VPr7l4F1QOSU39FGZ5JkALf0s+92FY=" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=debug msg="completed keyexchange" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14:28Z" level=debug msg="completed challenge" Nov 02 16:14:28 volumio go-librespot[853]: time="2025-11-02T16:14: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 02 16:14:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:29 volumio volumio[389]: info: BOOT COMPLETED Nov 02 16:14:30 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:30 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Nov 02 16:14:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:32 volumio go-librespot[880]: go-librespot daemon starting... Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=debug msg="app state loaded" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14: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 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14: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 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14: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 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=info msg="zeroconf server listening on port 36575" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=debug msg="obtained new client token: AADaQOzapWEeFHaH9dzRnbd7i7dDzLD6TBCqqVMedgScKe1nEyA5vlvdqj6elbwoNzdW7+73SIp924UVfIlecUHwxxsPN1hnbXiz9c6hHC8OVMmZ8XAdKN+Plks2RzPrgZ/7mB6eE+fO9bQ92BZueG4pSRVQlTRa+jtWZRcjTwyYEj5Dma15eRl3+QwWkWoRnb8KD+/N91wsypXiLiWL3Nz91yua+P4AFTfxhAwfWn34SV7YJHJf" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=debug msg="completed keyexchange" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14:32Z" level=debug msg="completed challenge" Nov 02 16:14:32 volumio go-librespot[881]: time="2025-11-02T16:14: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 02 16:14:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:33 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:33 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Nov 02 16:14:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:35 volumio go-librespot[890]: go-librespot daemon starting... Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=debug msg="app state loaded" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14: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 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14: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 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14: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 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=info msg="zeroconf server listening on port 39941" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=debug msg="obtained new client token: AABluvqe0EkpC0g+qR2938XVHJRSA7S/0Y0UfJmQxTdVUb0XlejW3Krg/w1iWPlCGR8QlBEbgMXvSPhObb5OKGYp+eno9JXe4c5o2fmAx1QtwamzB1mMq9febCe6ULLWI3GIQ2JKbPVZQnMm/0I3RHIDFB8H/dzYQFuJaIOIfga2iuWeErGEKEqKO7sQSOwNBw9ErlGMG+UOD3wbqKdoO8NQ3rRQvCewJ6rdCyYUvI4TNfVxM97QDEI=" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=debug msg="completed keyexchange" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14:35Z" level=debug msg="completed challenge" Nov 02 16:14:35 volumio go-librespot[891]: time="2025-11-02T16:14: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 02 16:14:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:36 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:36 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Nov 02 16:14:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:39 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:39 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:39 volumio go-librespot[902]: go-librespot daemon starting... Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=debug msg="app state loaded" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14: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-gae2.spotify.com:80]" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" 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 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" 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 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=info msg="zeroconf server listening on port 42959" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=debug msg="obtained new client token: AAAwK6U72iKWtF1032cEVCVCu48tIemORBSd5nRQrwVJHCsNF8hl4D7aoBwTU/4iaUPnu8VUrun/1QxCo12N8sjlllKch0gCAOpzKYt18+4jeQ2FSyb/PSgFtH2D/bAZsBE617wZrmjON7GQMojaY76UWvD8MPpGhFGvc8OTcDbsW7/9sKLOpcMOii/eYFy9K1IK74/oDkBqCxVqxJ28BsmaOgssQjtLA92AjTISBkzVc14z3h6k" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=debug msg="completed keyexchange" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14:39Z" level=debug msg="completed challenge" Nov 02 16:14:39 volumio go-librespot[908]: time="2025-11-02T16:14: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 02 16:14:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:42 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:42 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Nov 02 16:14:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:42 volumio go-librespot[929]: go-librespot daemon starting... Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=debug msg="app state loaded" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" 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 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" 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 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=info msg="zeroconf server listening on port 42621" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=debug msg="obtained new client token: AAAfI3HmlDquQOwla+D4+bA0G1gtobZfmbZxGNg3J0dSeZhLAXvAUW8wOq/MQtLkloUpLwta4nw9TiXf/VL7bgijTR3txq+5eTirrnJz39LN/ky7+yZ5OsFdz4CYNUXSLWpatzUrvL//o8lmf+pSgYpBoyHy9gkUERIR6sK5/7NCDIubwZ2OKD80I69uMAfZcvkiHhucXm0R456VM+UtiWpSqZ5hW05ihD/nnPM7KReIuWiIO7qHGz0=" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=debug msg="completed keyexchange" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=debug msg="completed challenge" Nov 02 16:14:42 volumio go-librespot[930]: time="2025-11-02T16:14:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 16:14:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:45 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:45 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Nov 02 16:14:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:46 volumio go-librespot[940]: go-librespot daemon starting... Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=debug msg="app state loaded" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" 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 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" 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 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" 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 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=info msg="zeroconf server listening on port 44563" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=debug msg="obtained new client token: AAB9gAKI8kiULSxk3zmhe18A3HiMV/VidnbgEktDDlsPoQBqR95DSbTj+pVQVfIMuCcVFwWQmYaPJ39eyQEv2DW/vfhVB2AU73kHBJ/WnIAsz7+tQhW8nHOtaG8WxPXbaor/E9fypPm/ApG2yiHzbXNZvyOl8wL8WMLsG1Da1AtJEAUr9hpm8WH0+ws6XBJ82zyPfafKsZTEWXd7LUJjgUF867Wg8GDIBxopij0PkVHjWX/9eAS9" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=debug msg="completed keyexchange" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14:46Z" level=debug msg="completed challenge" Nov 02 16:14:46 volumio go-librespot[941]: time="2025-11-02T16:14: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 02 16:14:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:48 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:48 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Nov 02 16:14:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:49 volumio go-librespot[948]: go-librespot daemon starting... Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=debug msg="app state loaded" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" 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 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" 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 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" 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 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=info msg="zeroconf server listening on port 39065" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=debug msg="obtained new client token: AADFcQDPrW7btHDRjMbI8vbAlNdIMxShtn5tZYJBVpoo77JtSZcX8aeadJkVgMxGJRokzkhXvA/cNz4DP3FKiSWy+FNuqRjGCkQUOw2olnN0TG4DQThttSoYKasu1CqRqR5sqHqDpqA9n/LBWZJvvLfNt36zaZdiuuanFaMkQBm4B9Zz84Do3sVU8np49cV68CPUWLtuPAepfXURwAGlHtD+l4MzPrJoDKcQNk1Sa2d6Rd39hxsImJo=" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=debug msg="completed keyexchange" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=debug msg="completed challenge" Nov 02 16:14:49 volumio go-librespot[949]: time="2025-11-02T16:14:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 16:14:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:51 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:51 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Nov 02 16:14:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:53 volumio go-librespot[970]: go-librespot daemon starting... Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=debug msg="app state loaded" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" 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 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" 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 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=info msg="zeroconf server listening on port 37181" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=debug msg="obtained new client token: AAD2DGzVk59pHF8ElgRt4GLLntOMk9Vr5ObAieoYWvbfpTM8Qp6QdZm/DbJGUsRkjxyaQUMcz6fq99a/8kQ55MDe9RdBmgKOf1r51/iFM3GzY6z+6dNN5q4hbq8Y7DAmOj+dKUdSQqOIR3Bh4+VT85SNkTkgKg+ZfZWCn0wjQe2F05w07weX9JCJR7asfdroUcLqbB9RkD0RkgU5vFtp+FKH506BgndZNoL98uvw8Jb76E1sKxOn" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=debug msg="completed keyexchange" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14:53Z" level=debug msg="completed challenge" Nov 02 16:14:53 volumio go-librespot[971]: time="2025-11-02T16:14: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 02 16:14:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:54 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:54 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:14:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Nov 02 16:14:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:14:56 volumio go-librespot[978]: go-librespot daemon starting... Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=debug msg="app state loaded" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" 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 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" 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 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" 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 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=info msg="zeroconf server listening on port 40589" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=debug msg="obtained new client token: AABjCtHgwVHXI8SyZ46opChIkQ797yzJ8Ac4NBXhHx11y3a5t9TxfWifuIo61dmVAbS9jkiwFj//JwOjjLQmF14/1SvcCW4e8YAISLEoXIC3SDg+23lgQIbAVl7Q3Zs4i8QoNTGEgN5mtlScKL67l73tWxdu/K8IBCL/Ysg+MNTfNG4CjpuaRvGEC/+5pDHKhfgQmQdbg5m7/PT4oAXkRPtqsjzg4meZpgyt10IQycHK8U9998KP5M8=" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=debug msg="completed keyexchange" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=debug msg="completed challenge" Nov 02 16:14:56 volumio go-librespot[979]: time="2025-11-02T16:14:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 16:14:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:14:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:14:57 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:14:57 volumio volumio[389]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:15:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Nov 02 16:15:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:15:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 16:15:00 volumio go-librespot[990]: go-librespot daemon starting... Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=info msg="running go-librespot 0.4.0" Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=debug msg="app state loaded" Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 16:15:00 volumio volumio[389]: info: Initializing connection to go-librespot Websocket Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=debug msg="new websocket client" Nov 02 16:15:00 volumio volumio[389]: info: Connection to go-librespot Websocket established Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15: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 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15: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 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15: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 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=info msg="zeroconf server listening on port 42937" Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=debug msg="obtained new client token: AADmIwFLTIpaP6eZ6UzLQYmY/gvieSnOr2+QUkulWokMrGcb9wAdwlCi6BRc8ZTFzSdvLTUWO/4k47T6i7bXwyfP+u9ngQdKsY5+P+jFxBzGlxJP54cLXWgkLppPQD/moVc9wjwdL3l7sIRRYbMN9DEc+/ijrbn+209SvHoxcOhKjX1ObZAvtnaVRczDMAD8VxmnPJIio85RAocSz+p2EN2wC8WAT4hcbo3Km8/Or7a+sKQXJGww" Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=debug msg="completed keyexchange" Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15:00Z" level=debug msg="completed challenge" Nov 02 16:15:00 volumio go-librespot[991]: time="2025-11-02T16:15: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 02 16:15:00 volumio volumio[389]: info: Connection to go-librespot Websocket closed Nov 02 16:15:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:15:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 16:15:03 volumio volumio[389]: info: Getting Spotify volume Nov 02 16:15:03 volumio volumio[389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 16:15:03 volumio volumio[389]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 16:15:03 volumio volumio[389]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 02 16:15:03 volumio volumio[389]: errno: -111, Nov 02 16:15:03 volumio volumio[389]: code: 'ECONNREFUSED', Nov 02 16:15:03 volumio volumio[389]: syscall: 'connect', Nov 02 16:15:03 volumio volumio[389]: address: '127.0.0.1', Nov 02 16:15:03 volumio volumio[389]: port: 9879, Nov 02 16:15:03 volumio volumio[389]: response: undefined Nov 02 16:15:03 volumio volumio[389]: } Nov 02 16:15:03 volumio volumio[389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 16:15:03 volumio sudo[1031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-02 16:14' Nov 02 16:15:03 volumio sudo[1031]: 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"