Nov 02 05:20:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 284. Nov 02 05:20:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:00 volumio go-librespot[31449]: go-librespot daemon starting... Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=debug msg="app state loaded" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20: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 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20: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 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20: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 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=info msg="zeroconf server listening on port 41551" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=debug msg="obtained new client token: AACPmhgyJPLFKsTpB9UzOH7opTcYqtTt8jd6ymVJIl0rj15Z7ynNgf9GghrYnAVu6oG1BI1PKb3gXu5U78cauHz1WQpB3RrEud6KGm6Vjkl7Bonwe60M17IrUBFHaavP0Oo1FVI35LdIzWa8BwA/6HqLTJsRbwv+N/zdwGPId7s9ILiaLF/oI4WOUaGyq1oQdKqcYW0+EdXZ/hAuwn/3Afynqec/uRps4Go9OAQQVhe1oqbdF5CB6Ok=" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=debug msg="completed keyexchange" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20:00Z" level=debug msg="completed challenge" Nov 02 05:20:00 volumio go-librespot[31450]: time="2025-11-02T05:20: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 05:20:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:01 volumio volumio[31145]: info: MyVolumio token set successfully Nov 02 05:20:01 volumio volumio[31145]: info: MYVOLUMIO: Adding device Nov 02 05:20:01 volumio volumio[31145]: info: MYVOLUMIO: Evaluating Server Nov 02 05:20:01 volumio volumio[31145]: info: MyVolumio status changed Nov 02 05:20:01 volumio volumio[31145]: info: Streaming services startup Nov 02 05:20:01 volumio volumio[31145]: info: Starting Streaming Daemon Nov 02 05:20:01 volumio volumio[31145]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 05:20:01 volumio volumio[31145]: info: Removing audio output: Nov 02 05:20:01 volumio volumio[31145]: info: Stoppping Tunnel 1 Nov 02 05:20:01 volumio sudo[31476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 05:20:01 volumio sudo[31476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 05:20:01 volumio sudo[31478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 05:20:01 volumio sudo[31478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 05:20:01 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 05:20:01 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 05:20:01 volumio sudo[31476]: pam_unix(sudo:session): session closed for user root Nov 02 05:20:01 volumio volumio[31145]: error: Cannot start Volumio Streaming Daemon Nov 02 05:20:01 volumio volumio[31145]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 05:20:01 volumio volumio[31145]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 05:20:01 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 05:20:01 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 05:20:01 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 05:20:01 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 05:20:01 volumio sudo[31478]: pam_unix(sudo:session): session closed for user root Nov 02 05:20:01 volumio volumio[31145]: info: Remote SSH Stopped Nov 02 05:20:01 volumio volumio[31145]: info: Setting Geolocation for MyVolumio to eu3 Nov 02 05:20:01 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:01 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:01 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:02 volumio volumio[31145]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 05:20:02 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:02 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:02 volumio volumio[31145]: info: Updating MyVolumio device info Nov 02 05:20:02 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:02 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:02 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:02 volumio volumio[31145]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 02 05:20:03 volumio volumio[31145]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 05:20:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 285. Nov 02 05:20:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:04 volumio go-librespot[31481]: go-librespot daemon starting... Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=debug msg="app state loaded" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20: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 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20: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 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20: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 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=info msg="zeroconf server listening on port 44279" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=debug msg="obtained new client token: AABRjNfVNl81z+gZohZsX2iqNjczVJvi93M64XkC47LMDOHI1+KYh5If9zFyNTa4dAdzHQ5AiobM/cxjqs2KBaUR9JsFsTFALQ+cJORqdmDBbA5UJwEAd2T62mPsj3rPo7c2tGmdIeBWL2DJHeLoSgT8Pj6kAtIa0hHzAKtCYqSmHceFgzu8jiPbH2FWKoioB3XXRvI4McCbVnYDiD13Vu77J2LdQ6Ht5fSjXed6ZuHm09wkiPM/" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=debug msg="completed keyexchange" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20:04Z" level=debug msg="completed challenge" Nov 02 05:20:04 volumio go-librespot[31482]: time="2025-11-02T05:20: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 05:20:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:05 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:05 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:06 volumio volumio[31145]: info: MYVOLUMIO: Adding device Nov 02 05:20:06 volumio volumio[31145]: info: MYVOLUMIO: Evaluating Server Nov 02 05:20:06 volumio volumio[31145]: info: Setting Geolocation for MyVolumio to eu3 Nov 02 05:20:06 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:06 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:06 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:06 volumio volumio[31145]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 05:20:07 volumio volumio[31145]: info: Updating MyVolumio device info Nov 02 05:20:07 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:07 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:07 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 286. Nov 02 05:20:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:07 volumio go-librespot[31504]: go-librespot daemon starting... Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=debug msg="app state loaded" Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20: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 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20: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 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20: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 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=info msg="zeroconf server listening on port 45465" Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=debug msg="obtained new client token: AAA6FCAaUbAozL85/41oouXsTfMCGAHaT74NKbYMvlwEHezwBZ4LxD23FZlty5j9uUqXmUzhF2iYsWhT4DlpWB68Ty22n4kjRPOHqzOaeD3pgKgeF4OP42cCitkJ0sF6Wp/FHL0N4ZTUhxsyYtJjr7sNkj2GP22vW2CFaGz+muAM9dQ+GVspvFmEVeqDU+yaDJRj4UTZOD4U591EsibCX4nZEWCOxEVyOlJrYUBSCx/PWnNZ/1Ctvs8=" Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:07 volumio volumio[31145]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=debug msg="completed keyexchange" Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20:07Z" level=debug msg="completed challenge" Nov 02 05:20:07 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:20:07 volumio go-librespot[31505]: time="2025-11-02T05:20: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 05:20:07 volumio volumio[31145]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 05:20:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:08 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:08 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 287. Nov 02 05:20:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:11 volumio go-librespot[31526]: go-librespot daemon starting... Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=debug msg="app state loaded" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" 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 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" 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 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=info msg="zeroconf server listening on port 34689" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=debug msg="obtained new client token: AAApIK0mLTQIE3actlRA8XH7L94Bv2ehnASKLuH9UoI/YLXWE1MNikgPJANhHNKNjma5IANjXV5hbk4k3gYmGX/d/1YDvfIF9ZnxVZe6DLJ6rk2kW5Lk7QjV+tdvMxY9oTMMNY9pqCDhPyhh3R4MkCFgHkX41pcBpkaWYS3Wd1mkADoggGHx6hLrO+349BZ2CLJqfu+9oYaShqVw585ovWpwWxCMLY/trTxFR6j1fUYeS8R9O/yE" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=debug msg="completed keyexchange" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20:11Z" level=debug msg="completed challenge" Nov 02 05:20:11 volumio go-librespot[31527]: time="2025-11-02T05:20: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 05:20:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:11 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:11 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:14 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 05:20:14 volumio volumio[31145]: 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 05:20:14 volumio volumio[31145]: info: CoreCommandRouter::volumioGetState Nov 02 05:20:14 volumio volumio[31145]: info: CorePlayQueue::getTrack 0 Nov 02 05:20:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 288. Nov 02 05:20:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:14 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:14 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:14 volumio go-librespot[31538]: go-librespot daemon starting... Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=debug msg="app state loaded" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20: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 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20: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 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20: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 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=info msg="zeroconf server listening on port 37671" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=debug msg="obtained new client token: AACg232yfMr2wrMg2PV5pA28Ihrj2RTnaiovdK2NtNVMBRhzlPBOwwixxwQk9moZE5gg2LYK8PYqrFvRAk4lo6mtOVcKP9z6C6GKhfaSzyWJGLvpIT2A3mTXysWd5kL+ad/OjlQ01gok123X0cbw60HtB9pyJS1S4Q/OM+PVrqdCheuYWJpKd7znqY2YavzGs9gWeKGqvoxQG1EsfNiHo3VbQSoFR5feOiVBKZPhNHCVugPUYDyn1g8=" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=debug msg="completed keyexchange" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20:14Z" level=debug msg="completed challenge" Nov 02 05:20:14 volumio go-librespot[31539]: time="2025-11-02T05:20: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 05:20:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:17 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:17 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 289. Nov 02 05:20:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:18 volumio go-librespot[31560]: go-librespot daemon starting... Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=debug msg="app state loaded" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" 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 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" 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 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=info msg="zeroconf server listening on port 36603" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=debug msg="obtained new client token: AAAlwkYB311yei6DSjcHjaDw92Mc4H2Wpxv9WZRMQxvFg58j9ShvIS73Xr7bDXjdATyqEYiYgYS2o4grFy68h1hkheUTP906M8JhUIEJQFlIvsHPMF6YIuIBZxrgJdr6QMeLs++V4cc8Lh+PQF19almzMHG4qRDxGcnHLJq7sfqccBfz/3Ajk76rhT0VhZ3dQdNCK2kcomyMyMXqwFtQFTEdQaM7ZHwxRA1dCpce4HH81RCEgDMU" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:18 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=debug msg="completed keyexchange" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20:18Z" level=debug msg="completed challenge" Nov 02 05:20:18 volumio go-librespot[31561]: time="2025-11-02T05:20: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 05:20:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:20:19 volumio volumio[31145]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 05:20:19 volumio volumio[31145]: info: Completed starting MyVolumio Plugin Nov 02 05:20:19 volumio volumio[31145]: [Metrics] CommandRouter: 32s 86.79ms Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::Close All Modals sent Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::Close All Modals sent Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 05:20:19 volumio volumio-remote-updater[874]: Test mode disabled Nov 02 05:20:19 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 02 05:20:19 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 02 05:20:19 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 05:20:20 volumio volumio[31145]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 05:20:20 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 05:20:20 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 05:20:20 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 05:20:20 volumio volumio[31145]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 05:20:20 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:20 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 290. Nov 02 05:20:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:21 volumio go-librespot[31577]: go-librespot daemon starting... Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=debug msg="app state loaded" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20: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-gae2.spotify.com:80]" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" 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 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" 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 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=info msg="zeroconf server listening on port 45437" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=debug msg="obtained new client token: AACFjafnm0W3CdH8ebKhz7XI5uQGshmwP9NGTMoUIJjeEoGrgm3yPGuuIg0jOCkEJqFZrPqzWs+SuFAJvGpKghvaPPGAmBYUsSWRPjRLytvtzgpM4fkHRRzgP55+ATQn5W89CB6acS7Til3wjWVonZmZq8I56Ycm+4o/Zyk2ESOLzSIIdhXX2ysgjanjFxdCCFWfLo9WE6YmtvWRu44tEmthRSVHZX8rFmVffcpwwU7tyGXnxjsdkik=" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=debug msg="completed keyexchange" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20:21Z" level=debug msg="completed challenge" Nov 02 05:20:21 volumio go-librespot[31578]: time="2025-11-02T05:20: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 05:20:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:23 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:23 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 291. Nov 02 05:20:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:25 volumio go-librespot[31585]: go-librespot daemon starting... Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=debug msg="app state loaded" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20: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-gae2.spotify.com:80]" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" 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 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" 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 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=info msg="zeroconf server listening on port 34417" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=debug msg="obtained new client token: AADyp97rd52UXCMW36nl2BW+bodf6QINtBKUzZQRuTqtJC6ADmfAX3u3lGLxJBnfAZHcoKwe0fzO5/9MaISeTGEQN9U7FYVz2T6GPky4OBF2lau4jox8sgpWlUehR7P89yyZ/2DtM8YJplgaWJszTcZIsptDCTRFf1TgyVk42INgpnB4L/Zelv49ScMWdD3J3TUu1FJPysyr8CdJircou3s57QDaeg0Dh6Ard6sTgZoSOqh5E/8y" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=debug msg="completed keyexchange" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20:25Z" level=debug msg="completed challenge" Nov 02 05:20:25 volumio go-librespot[31586]: time="2025-11-02T05:20: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 05:20:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:26 volumio volumio[31145]: info: BOOT COMPLETED Nov 02 05:20:26 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:26 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 292. Nov 02 05:20:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:28 volumio go-librespot[31607]: go-librespot daemon starting... Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=debug msg="app state loaded" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20: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 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20: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 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20: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 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=info msg="zeroconf server listening on port 42109" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=debug msg="obtained new client token: AAAsA1AcwCZ/htkfpem6f9jlYSqfRR3LteufUewZY2DGDpjuE3R9eXwYkoZV5A4qmmWacdfzQslsPDZx9tjReKGO3E1b1LS3xj311qb8NzPXzAHwKIqeH/n7sTdcgf3s2likBbflBTXnB+CxZpWFXD5mZ5hDhFUZJhVNOSQOsiStHexQlQ6EbzoGjteG91hvvydN4HKQeUtj99SJaBPfBmhJNwE7TKVZrQU5C4UmJHrF7QzEaEyP70Q=" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=debug msg="completed keyexchange" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20:28Z" level=debug msg="completed challenge" Nov 02 05:20:28 volumio go-librespot[31608]: time="2025-11-02T05:20: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 05:20:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:29 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:29 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 293. Nov 02 05:20:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:32 volumio go-librespot[31615]: go-librespot daemon starting... Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=debug msg="app state loaded" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" 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 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" 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 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" 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 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=info msg="zeroconf server listening on port 45531" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=debug msg="obtained new client token: AADbl7guD1PPVdkf7p5aHHKCeljdAF3oa38YIebBAs4RPBtm3qz70+fbEE0/8eGQ9ynakARV4C4RUaJ9EPO5jLP+MnZVtzwIar0ccxFEaQZ8gY5vBL1Y/7uh7fJfrcCukiLpOye55F5Q9YvvxXLhR5R8/+2e5lx7dcTtat8XrTZtjrx1bVR76+JwBDgrpW9NPWAO/mxw0aD+y/FxVjNtU09t8axW40qmi5AwbdgMK1Xq6IVB0c3Y" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=debug msg="completed keyexchange" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20:32Z" level=debug msg="completed challenge" Nov 02 05:20:32 volumio go-librespot[31616]: time="2025-11-02T05:20: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 05:20:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:32 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:32 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:32 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:20:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 294. Nov 02 05:20:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:35 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:35 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:35 volumio go-librespot[31627]: go-librespot daemon starting... Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=debug msg="app state loaded" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20: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-gae2.spotify.com:80]" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" 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 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" 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 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=info msg="zeroconf server listening on port 37917" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=debug msg="obtained new client token: AACZQiL2VXWB8VxzOnymohV6JhznW9MeaHz/ZP81/0KB3rLysUq85wlIic6ssgE0hXkin9JdA/Tba1xEBueEkK8jVWEgcfM04her+gqQhBDmj+JpDTija1eX1/tXJBQ9fOLo/+DDVEuTZVRhnMr3OOj8zJzdiol7AcnKCbpRhKfiA+9Rnwi/ZgbLFlgnMrJG9N+02TORWJBnFP4odeMI8uQ8MjXXvzr+1sk+uRtzpwbCFxERUzHL7o8=" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=debug msg="completed keyexchange" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20:35Z" level=debug msg="completed challenge" Nov 02 05:20:35 volumio go-librespot[31628]: time="2025-11-02T05:20: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 05:20:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:38 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:38 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 295. Nov 02 05:20:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:39 volumio go-librespot[31650]: go-librespot daemon starting... Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=debug msg="app state loaded" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" 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 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" 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 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=info msg="zeroconf server listening on port 32831" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=debug msg="obtained new client token: AABaiFoJIzF9r6bEBTdZ2NlIH1es1my3YM4SVs7TOdUl5qj98soDMxsOwWMJ+rlRkcsUf7zBxN2duZywtDIn6bhEkkhfkMhRgv5Hv9NEVDbYJscrzVPywQvDFpfr5sHRJGEzvNeHcT+2STvjmE1DOfsbFsKuwURAFv+HrYuOi4fW8kmUllm+I3mNUhDsv7J7G2q/1GrW889MaLJG+vCnSPy8dmERRK96yOKUYPJOZG1bC3fT1k6X" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=debug msg="completed keyexchange" Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20:39Z" level=debug msg="completed challenge" Nov 02 05:20:39 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:20:39 volumio go-librespot[31651]: time="2025-11-02T05:20: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 05:20:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:41 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:41 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 296. Nov 02 05:20:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:42 volumio go-librespot[31658]: go-librespot daemon starting... Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=debug msg="app state loaded" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" 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 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" 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 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" 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 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=info msg="zeroconf server listening on port 39859" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=debug msg="obtained new client token: AAD7CeJZowQmW3Dt6XDStIPQ42imhr7Ixq3WWGw8EqsZSgp9R/2MJ5Gns9wOozY+VinsFklW38YVTJULpEFxFee2WtMwqZQEAE6J04iPfhzEK5PDrQb6kzKSVcVF8UKPP2QDCIoM/bpapOsWriHWRW4sNpWj0nmRVY2k9tBTelzZbSXFccbMWvIK06mK9prdjCoDJ7aWU4rZ9/u6pTekqtYxIzQ14qhDCDvAzwlYRcskujLnF9FNrZ0=" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=debug msg="completed keyexchange" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20:42Z" level=debug msg="completed challenge" Nov 02 05:20:42 volumio go-librespot[31659]: time="2025-11-02T05:20: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 05:20:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:44 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:44 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 297. Nov 02 05:20:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:46 volumio go-librespot[31666]: go-librespot daemon starting... Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=debug msg="app state loaded" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" 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 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" 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 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=info msg="zeroconf server listening on port 34669" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=debug msg="obtained new client token: AAB6Ww3oTAImE6xb2RwdFye0gDwhE4dCU28tJZODiURXbCSWUhVD0hjNTa3P7EUXGxW5+ArgmbdzZgUw47XFaOi+TGVwI0dgc/+DUqq3Ra0MpgBq9bADH1uE3wjgYl42aHe4J/7YNjp/1PB6KtrsVVQNmH5K80pFC9eIFTUUqUulcUFNdenaCA5aIn4WhLE5OQsiqUbnUAnYeBo2URBEKuML4QdX6x41d1tRyt3Ccg0yHUH4YbWy" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=debug msg="completed keyexchange" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20:46Z" level=debug msg="completed challenge" Nov 02 05:20:46 volumio go-librespot[31667]: time="2025-11-02T05:20: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 05:20:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:47 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:47 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 298. Nov 02 05:20:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:49 volumio go-librespot[31689]: go-librespot daemon starting... Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=debug msg="app state loaded" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" 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 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" 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 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" 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 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=info msg="zeroconf server listening on port 38223" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=debug msg="obtained new client token: AAAMuwuXLJQwqG47UYbIH5mHytdPswlcysH0VwN3Z8rFm1ZGXeOZ6FJ04sVgqyTAHYQhHCMcITBkCRQW4JcAuypdWhwpzBQGPsbIPKCLg6Qy82rOz0UK4v/VTMVi1ivwudNyto5JDGE/9wkNG4ulimSTE5BOyFboFXCrmtKLw3huUECW8f/WmUocgfJgF5P51n8ARJpJSNJPzO8F5evIZqMTih6EivKWnA5EBh5J/AC0G9jAHSOOisk=" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=debug msg="completed keyexchange" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20:49Z" level=debug msg="completed challenge" Nov 02 05:20:49 volumio go-librespot[31690]: time="2025-11-02T05:20: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 05:20:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:50 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:50 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 299. Nov 02 05:20:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:53 volumio go-librespot[31697]: go-librespot daemon starting... Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=debug msg="app state loaded" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20: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 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20: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 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20: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 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=info msg="zeroconf server listening on port 33535" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=debug msg="obtained new client token: AAAheJKCceC6LiDKqMe/nqh+KlSeWQ1/BIZmoGwSDOj8vRQomoKVl/MIABR1dut7C1PABcQ8F2akHmVE8cEz876z2C4ywvzz8U07gXl19xXpZFQkE/GBYuFqAdZ154HUhiVo636GOZcFTS7v9pHRCdJYveMw9yLuNcU1dDbRLJIxeuri1HvXOBASxtWtSjXI5WcFbmyW65kYdXMfs6CBBIUNj00UwMLS+ksiqjxlLbjB/JEYBh9+" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=debug msg="completed keyexchange" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20:53Z" level=debug msg="completed challenge" Nov 02 05:20:53 volumio go-librespot[31698]: time="2025-11-02T05:20: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 05:20:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:53 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:53 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 300. Nov 02 05:20:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:56 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:56 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:20:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:20:56 volumio go-librespot[31709]: go-librespot daemon starting... Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=debug msg="app state loaded" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" 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 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" 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 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=info msg="zeroconf server listening on port 42515" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=debug msg="obtained new client token: AADkk+QxK4mE1vxk0obRll9tCeQWDQx3V7nHAaJmv+Ux9R1JF6DF4dHYKBQgsQqSpnEnSva2ipurZgACH2w7EySKkh7hvOSFbJ674hWWkzjqyuBxILj8HHtIHRUhFbtMKEQZox+wHXxneBgjtNV2yhFg3IyxBs7H+hAjAJzzUzC0O+v/ex60WwolVk2dggrRUhxlTtz2Sx/VRRh28gUi8f8J1C4jKZxSisUcoCpUSVF7LOlwr7ONJGg=" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" 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 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=debug msg="connected to ap-gew1.spotify.com:80" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=debug msg="completed keyexchange" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20:56Z" level=debug msg="completed challenge" Nov 02 05:20:56 volumio go-librespot[31710]: time="2025-11-02T05:20: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 05:20:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:20:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:20:59 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:20:59 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 301. Nov 02 05:21:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:00 volumio go-librespot[31731]: go-librespot daemon starting... Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=debug msg="app state loaded" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" 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 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" 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 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" 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 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=info msg="zeroconf server listening on port 41023" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=debug msg="obtained new client token: AADtv9BZbWQAbrfk4w/NmOY90r6Y8YZ2OezIob6GtnYdzTBZkwf1dyG8XwDmr6eTeXAEVKbGRbgJ6GzGrKbRTXzyMp221j6Lngfv6rNFbuo3uxxYH6sbFofFyxOgBKWScWMEQU0IhEMNd6v5j024wsH7cKWAVpK+qXSWk5JZFVJzCPpJhQkPdNz2NCkL6qSqe1x+BgW07+kzGa7npB8PV8dEjY4VBA96HDNUX0CNWfA5riAGi4TG" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=debug msg="completed keyexchange" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21:00Z" level=debug msg="completed challenge" Nov 02 05:21:00 volumio go-librespot[31732]: time="2025-11-02T05:21: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 05:21:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:00 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:21:02 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:02 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 302. Nov 02 05:21:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:03 volumio go-librespot[31739]: go-librespot daemon starting... Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=debug msg="app state loaded" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" 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 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" 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 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" 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 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=info msg="zeroconf server listening on port 38475" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=debug msg="obtained new client token: AACJlHX2fOEWIzwVUU/N/rhFaCscjE2khVn/5BIImpNdn8y6+fNd65hxxHEOTYoVWSajcslgKEcRpzOy3OGtBAIECY9Pd/8b8fjtOoKRO0yJjcCpj3SjbPpBJeLIpS3U0hlzwqdrLfpiSyAuM/jM2jdn/n8F9VVnf/O+gJziTJzi7kMODdgLD7VK7aYa6fV2yU+r7kPAQfL2ENiABxAOVag2uxtbFgJugE/k4OvjF6JlKNl03AlspW8=" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=debug msg="completed keyexchange" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" level=debug msg="completed challenge" Nov 02 05:21:03 volumio go-librespot[31740]: time="2025-11-02T05:21:03Z" 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 05:21:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:05 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:05 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 303. Nov 02 05:21:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:06 volumio go-librespot[31748]: go-librespot daemon starting... Nov 02 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" level=debug msg="app state loaded" Nov 02 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" 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 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" 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 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" 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 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" level=info msg="zeroconf server listening on port 34243" Nov 02 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" level=debug msg="obtained new client token: AACoZ9tRACtrelBYI0JbmOb4w091x4kfOOr2NHSV7L9lsIO/Km6fJFEbcbVjjIZVmSkh3lsIm+1AuN4OmiGfB2OQZKRMCAyuwNz4Z12cDeGrW8gQTe4EXY+6vsLNE/nl1PQ8DsGQe4ElCs2d5wYJMcZ7iNEPlQ2aNXxiHppO8dieMsl0d+YNtwNl5cOILjKj2SoUReLKZyghyDBZFZVZ466cEcROpXzE0Lsee5HcteDFK/Mi8ZEt1Dg=" Nov 02 05:21:06 volumio go-librespot[31749]: time="2025-11-02T05:21:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:07 volumio go-librespot[31749]: time="2025-11-02T05:21:07Z" level=debug msg="completed keyexchange" Nov 02 05:21:07 volumio go-librespot[31749]: time="2025-11-02T05:21:07Z" level=debug msg="completed challenge" Nov 02 05:21:07 volumio go-librespot[31749]: time="2025-11-02T05:21: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 05:21:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:08 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:08 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 304. Nov 02 05:21:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:10 volumio go-librespot[31770]: go-librespot daemon starting... Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=debug msg="app state loaded" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" 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 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" 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 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" 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 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=info msg="zeroconf server listening on port 40531" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=debug msg="obtained new client token: AABR6S0KF5YTv6knn3uQEK/GKd1SNOGWAQ6XgxByf0EtP7CrZCYzEtjTb5eAiyrOZbtoshIRJzp9nTojrWbs5pPEXZHI5Ardb9/E7RTBOz5gKr0NnBoT5dRkw3cxWLTCHgrjzmXncHe7lzTm8JvBIxfD1Xg69PUwVtsDWcYMrg4klBjwM1rFAGXrUNhmzjrU/rBo/cUJrXG4V3k0A0T1tTpbZdgNNrZCcfmpWerj1zw/LQQHZNkC95c=" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=debug msg="completed keyexchange" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" level=debug msg="completed challenge" Nov 02 05:21:10 volumio go-librespot[31771]: time="2025-11-02T05:21:10Z" 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 05:21:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:10 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:21:11 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:11 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 305. Nov 02 05:21:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:13 volumio go-librespot[31782]: go-librespot daemon starting... Nov 02 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" level=debug msg="app state loaded" Nov 02 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" 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 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" 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 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" 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 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" level=info msg="zeroconf server listening on port 37053" Nov 02 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" level=debug msg="obtained new client token: AAA2Hh6fEChqF59uZ5YTJdsemIKshLaszu27ka8iJKdJHFWykKom+7bfwoMadXVhcksbsMv51V+TNT0who6BChCGfhLEJQylfeWSLvkidxXg0cUP6qPh56MjmQk+ThriV9Lg17+g9OrPtfrfWdkR+PIhBQm7Uw0QN8sxlxFZLav+MjotMtQYRda/iUSdmxgJnZ78aN2e08pkkvusEdBpVWYq1u/AfJFnSddciJGS3hIATEuRLry0r+o=" Nov 02 05:21:13 volumio go-librespot[31783]: time="2025-11-02T05:21:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:14 volumio go-librespot[31783]: time="2025-11-02T05:21:14Z" level=debug msg="completed keyexchange" Nov 02 05:21:14 volumio go-librespot[31783]: time="2025-11-02T05:21:14Z" level=debug msg="completed challenge" Nov 02 05:21:14 volumio go-librespot[31783]: time="2025-11-02T05:21: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 05:21:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:14 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:14 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 306. Nov 02 05:21:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:17 volumio go-librespot[31790]: go-librespot daemon starting... Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=debug msg="app state loaded" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" 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 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" 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 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" 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 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=info msg="zeroconf server listening on port 46737" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=debug msg="obtained new client token: AABOrVXt9ewlytAlsbBORYXfjce8LS+JDF4eT8/lhIJB3H5OGRt0veQDCnzi1nWcY0VC8pL43rb6QhsX8ey1lHHsALbZ38RW7QYjtq6er4pabfnd2ASZ/pH7l69rHLxKBzyeIEUePM8Yk2J6vvdSS6VH5WxOqrxco7YUTGAnDohQZiRY7upo3LPv82lHwxERgoAAdEDpSIygPPcsTq2zPlU+CLVu1TX0yY10dTi3RlnT3dmlH/PzlPI=" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=debug msg="completed keyexchange" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" level=debug msg="completed challenge" Nov 02 05:21:17 volumio go-librespot[31791]: time="2025-11-02T05:21:17Z" 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 05:21:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:17 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:17 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:18 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:21:20 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:20 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 307. Nov 02 05:21:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:20 volumio go-librespot[31812]: go-librespot daemon starting... Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=debug msg="app state loaded" Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" 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 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" 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 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" 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 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=info msg="zeroconf server listening on port 38361" Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=debug msg="obtained new client token: AACFmleUxj1MNXqjjRwALTjQNaTQHJHz4u0RperHJ46FXRGoyeq5RuIGaPl0Q7491ESnViwL4VcCvBhkIy6NRwmQJhkdTfS3qXXkb4VAr188adsrjrknfd+Zw8K8+TVwiNo2nkpTIN6ejoGnm2ziiNIdqng8RVrPB3pkJc7XeVgg4ST74uzEB/x6gsoCyrKdyFai7EOswEtKU9px3/YjcXM/wkoOY661vRsgXOh2l777NSk9p4A+ZSk=" Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=debug msg="completed keyexchange" Nov 02 05:21:20 volumio go-librespot[31813]: time="2025-11-02T05:21:20Z" level=debug msg="completed challenge" Nov 02 05:21:21 volumio go-librespot[31813]: time="2025-11-02T05:21: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 05:21:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:23 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:23 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 308. Nov 02 05:21:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:24 volumio go-librespot[31820]: go-librespot daemon starting... Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=debug msg="app state loaded" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" 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 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" 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 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" 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 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=info msg="zeroconf server listening on port 43077" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=debug msg="obtained new client token: AAADEfI1JTrR9IKXuKVSm/9CFYdvebcB4DQ7GrzbLIOJEEcGQJxqrWaNj0ztOmfYpiXIZ1hNalAwgGynVW8fJkLhlTuktVQrvGiwaz8Co8hiH+Vnfl6exrCs1WcDQtsMPZHafVtw3iHO7JzrZdJpNUm84+B1dQPFJ7k3faP80b454eGwdVMsjrF4UJ3MKghsCLbklp9acSmXaXU0q7bTxu0KvKsSl0IbtdOs7TVq4P3fc9m/j5yKUr0=" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" 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 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=debug msg="completed keyexchange" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" level=debug msg="completed challenge" Nov 02 05:21:24 volumio go-librespot[31821]: time="2025-11-02T05:21:24Z" 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 05:21:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:26 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:26 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 309. Nov 02 05:21:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:27 volumio go-librespot[31828]: go-librespot daemon starting... Nov 02 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" level=debug msg="app state loaded" Nov 02 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" 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 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" 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 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" 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 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" level=info msg="zeroconf server listening on port 44935" Nov 02 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" level=debug msg="obtained new client token: AACRr05UV96AGQ5Jy3RqYMmQfkgXMuP9YlWqoPzbkCCzXEEUHeXi7aHPUe1xDorz4cj4Y00gXg/rB1gXNprFS7EIt/T0fm5eaF7ibgNoNbUKm+ASdHaGNcWNRZShdcbAq6emTD8iJQYSaBHPrTAZMbVZPUr7HU808geaIdr+KPBSWULtROY1cFkFVw5m8ltwCqYwGqLgzhm5tMEBF9l73Pe9oNf0HzSBYrRoMX/YUGzG5OIHnfuYtbo=" Nov 02 05:21:27 volumio go-librespot[31829]: time="2025-11-02T05:21:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:28 volumio go-librespot[31829]: time="2025-11-02T05:21:28Z" level=debug msg="completed keyexchange" Nov 02 05:21:28 volumio go-librespot[31829]: time="2025-11-02T05:21:28Z" level=debug msg="completed challenge" Nov 02 05:21:28 volumio go-librespot[31829]: time="2025-11-02T05:21: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 05:21:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:29 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:21:29 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:29 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 310. Nov 02 05:21:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:31 volumio go-librespot[31851]: go-librespot daemon starting... Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=debug msg="app state loaded" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" 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 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" 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 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" 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 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=info msg="zeroconf server listening on port 46491" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=debug msg="obtained new client token: AAAoj+q3+BWIoKX8CxJk60kQ2x1zef2Iy/O61Wiz/mnZ325h4XeUMF9Z7dD3JDRAn08+CI73t5YnQ+8U6w9PB8DPP5B3LXfg4oPcWRTr+XNOjX2K7BQL4OGJqclXKsuVsYSjijokZ6ujVm+gsotVesGiJcsmAkeP9xkyumgs6sySYCAB9cN9ksHrxiNV6EtDLFzibq0/X/xWSl/bXBEj1NFzWzSDwDdmLPFW9PPN630HWQhhpU0K2IA=" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=debug msg="completed keyexchange" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" level=debug msg="completed challenge" Nov 02 05:21:31 volumio go-librespot[31852]: time="2025-11-02T05:21:31Z" 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 05:21:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:32 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:32 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 311. Nov 02 05:21:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:34 volumio go-librespot[31863]: go-librespot daemon starting... Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=debug msg="app state loaded" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" 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 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" 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 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" 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 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=info msg="zeroconf server listening on port 34435" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=debug msg="obtained new client token: AAC4E5+uaIN2Cl/UL+WH4zxx+ZDazo2O6n8AZCDHopK8/qpfE+OTwbimqiHdIqKcdXo3tFuI7dJa7igmhajtXyPjgC9a0lQ02KtgdcsFsBVVjjP7yzliRDaHtz008R5LuXm5P8mXRVtbPpi9yY8qJkQeOZM6cn8+XwDA5jLUtcjAZobaLBpoCg9TiO8LneFTWjkVhUog/9Q+QWVgljCvgk1VokPcCV40rI+17UdZOmzCaG14vNreiDQ=" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=debug msg="completed keyexchange" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" level=debug msg="completed challenge" Nov 02 05:21:34 volumio go-librespot[31864]: time="2025-11-02T05:21:34Z" 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 05:21:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:35 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:35 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 312. Nov 02 05:21:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:38 volumio go-librespot[31885]: go-librespot daemon starting... Nov 02 05:21:38 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=debug msg="app state loaded" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" 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 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" 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 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=info msg="zeroconf server listening on port 42475" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=debug msg="obtained new client token: AACGZnGKRCJE+iOvkf4ecjFzlGBF3X11gyrnRFeB2yWsHgQTHE6b22mCFkVvcqwjo4yP7VoFFLEuuWzTtfro0OcNN6Iu2fNwrRhsvwGt2FZeadnxZ/Bis2hX/GPEvyqJSu/3MODecWO5gQmeD/EnA3fImPAoQ1GxfC09+c8va7RRu/dUN4/AdvUEgY9A0r35yJyn2W8Zhh+dSqt2rWhU5X392ciGKylXK92kerYagLmP0ZrH691Y" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=debug msg="completed keyexchange" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" level=debug msg="completed challenge" Nov 02 05:21:38 volumio go-librespot[31886]: time="2025-11-02T05:21:38Z" 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 05:21:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:38 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Nov 02 05:21:38 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Nov 02 05:21:38 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:38 volumio volumio[31145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 313. Nov 02 05:21:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:21:41 volumio go-librespot[31918]: go-librespot daemon starting... Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=debug msg="app state loaded" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:21:41 volumio volumio[31145]: info: Initializing connection to go-librespot Websocket Nov 02 05:21:41 volumio volumio[31145]: info: Connection to go-librespot Websocket established Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=debug msg="new websocket client" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" 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 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" 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 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" 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 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=info msg="zeroconf server listening on port 33295" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=debug msg="obtained new client token: AABgpZRFj371giXBSf5d02IOmEKz8ejwgKXggCj25jx/jFANiFGkI+eT0B68miV7i8e6mhrzW/GoPkwXDdcEWOqZuGha4okNymURSAkL3Hge1iOp1jLZiOpdVsbSXv91xvh3vJ3chaHY4TMBRHP7tIMdvKjv1WFycMlHChd/HLBi4eKOmrlQwWIJQowlwX1lxlINADE9CZDJsZAWAN6VGl7XFWZuvG4ahxYmTHP5dlVFGNsytkUediE=" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=debug msg="completed keyexchange" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" level=debug msg="completed challenge" Nov 02 05:21:41 volumio go-librespot[31919]: time="2025-11-02T05:21:41Z" 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 05:21:41 volumio volumio[31145]: info: Connection to go-librespot Websocket closed Nov 02 05:21:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:21:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:21:44 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:21:44 volumio volumio[31145]: info: Getting Spotify volume Nov 02 05:21:44 volumio volumio[31145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:21:44 volumio volumio[31145]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:21:44 volumio volumio[31145]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 02 05:21:44 volumio volumio[31145]: errno: -111, Nov 02 05:21:44 volumio volumio[31145]: code: 'ECONNREFUSED', Nov 02 05:21:44 volumio volumio[31145]: syscall: 'connect', Nov 02 05:21:44 volumio volumio[31145]: address: '127.0.0.1', Nov 02 05:21:44 volumio volumio[31145]: port: 9879, Nov 02 05:21:44 volumio volumio[31145]: response: undefined Nov 02 05:21:44 volumio volumio[31145]: } Nov 02 05:21:44 volumio volumio[31145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:21:44 volumio sudo[31941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-02 05:20' Nov 02 05:21:44 volumio sudo[31941]: 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"