Nov 02 04:02:00 volumio volumio[30042]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 04:02:00 volumio volumio[30042]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 04:02:00 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:00 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:00 volumio volumio[30042]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 04:02:00 volumio volumio[30042]: info: MyVolumio login type: Token Nov 02 04:02:00 volumio volumio[30042]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 04:02:00 volumio volumio[30042]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 04:02:00 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:02:00 volumio volumio[30042]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 04:02:00 volumio volumio[30042]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 04:02:00 volumio volumio[30042]: info: Streaming services startup Nov 02 04:02:00 volumio volumio[30042]: info: Starting Streaming Daemon Nov 02 04:02:00 volumio sudo[30354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 04:02:00 volumio sudo[30354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 04:02:00 volumio volumio[30042]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 04:02:00 volumio sudo[30354]: pam_unix(sudo:session): session closed for user root Nov 02 04:02:00 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:00 volumio volumio[30042]: error: Cannot start Volumio Streaming Daemon Nov 02 04:02:00 volumio volumio[30042]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 04:02:00 volumio volumio[30042]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 04:02:00 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:00 volumio volumio[30042]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 04:02:01 volumio volumio[30042]: info: MyVolumio token set successfully Nov 02 04:02:01 volumio volumio[30042]: info: MYVOLUMIO: Adding device Nov 02 04:02:01 volumio volumio[30042]: info: MYVOLUMIO: Evaluating Server Nov 02 04:02:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Nov 02 04:02:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:01 volumio go-librespot[30363]: go-librespot daemon starting... Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=debug msg="app state loaded" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" 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 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" 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 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" 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 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=info msg="zeroconf server listening on port 39139" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=debug msg="obtained new client token: AABLXzYDk3MxbFWf0zhTQuvdDF+KbphxaVs86aZhAlo16rTX2/nLUxiibFRMQh/+bHkp8tt41Xu1VPxi++nDW8U1wRUPY7eHhQTkqd97UtnP2A/qREV6MUi69sdfncnbMCR9VlgAYjANlfEOr33cOGTQSyuv8Jm3k0Q+YQ8rRaJ54fLaI/7sXAR8tKx9yotshWFEuduwMhtSVaKp1Ks9EHwvUxT4NENHq6WBWy2mgGud22i/qeMn7Qc=" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" 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 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=debug msg="completed keyexchange" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" level=debug msg="completed challenge" Nov 02 04:02:01 volumio go-librespot[30364]: time="2025-11-02T04:02:01Z" 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 04:02:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:01 volumio volumio[30042]: info: MyVolumio status changed Nov 02 04:02:01 volumio volumio[30042]: info: Streaming services startup Nov 02 04:02:01 volumio volumio[30042]: info: Starting Streaming Daemon Nov 02 04:02:01 volumio volumio[30042]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 04:02:01 volumio volumio[30042]: info: Removing audio output: Nov 02 04:02:01 volumio volumio[30042]: info: Stoppping Tunnel 1 Nov 02 04:02:01 volumio sudo[30372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 04:02:01 volumio sudo[30372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 04:02:01 volumio sudo[30372]: pam_unix(sudo:session): session closed for user root Nov 02 04:02:01 volumio sudo[30376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 04:02:01 volumio sudo[30376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 04:02: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 04:02: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 04:02: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 04:02: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 04:02: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 04:02: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 04:02:01 volumio sudo[30376]: pam_unix(sudo:session): session closed for user root Nov 02 04:02:01 volumio volumio[30042]: info: Remote SSH Stopped Nov 02 04:02:01 volumio volumio[30042]: error: Cannot start Volumio Streaming Daemon Nov 02 04:02:01 volumio volumio[30042]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 04:02:01 volumio volumio[30042]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 04:02:02 volumio volumio[30042]: info: Setting Geolocation for MyVolumio to eu3 Nov 02 04:02:02 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:02 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:02 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:02 volumio volumio[30042]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 04:02:03 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:03 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:03 volumio volumio[30042]: info: Updating MyVolumio device info Nov 02 04:02:03 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:03 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:03 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:03 volumio volumio[30042]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 04:02:03 volumio volumio[30042]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 02 04:02:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Nov 02 04:02:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:04 volumio go-librespot[30392]: go-librespot daemon starting... Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=debug msg="app state loaded" Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" 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 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" 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 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" 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 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=info msg="zeroconf server listening on port 40633" Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=debug msg="obtained new client token: AACt5ZVL7IpIeo+Ryi2RuB0NydgfrIwAx0FEZ0MHdDJUcPzMF9UXulH2sTiyZveB/rsEOVDkiXFksUf2oZo4yqKxAMpoTfoXqcPJMLJbAOJ/4NtsFiLWCq1pnGa9661R396U/Hzj/zV3xwF4Y4TXR2qXKH7MJQpRzr37qfN3wY2efhPeJzFFqVjwPZdMFi/SCKK8mtlSyFRj1NUolBkKcHiFqEqB/gxVbzwGsShJqOlsl9ajI292p2I=" Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=debug msg="completed keyexchange" Nov 02 04:02:04 volumio go-librespot[30393]: time="2025-11-02T04:02:04Z" level=debug msg="completed challenge" Nov 02 04:02:05 volumio go-librespot[30393]: time="2025-11-02T04:02:05Z" 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 04:02:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:06 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:06 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:06 volumio volumio[30042]: info: MYVOLUMIO: Adding device Nov 02 04:02:06 volumio volumio[30042]: info: MYVOLUMIO: Evaluating Server Nov 02 04:02:07 volumio volumio[30042]: info: Setting Geolocation for MyVolumio to eu3 Nov 02 04:02:07 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:07 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:07 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:07 volumio volumio[30042]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 04:02:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Nov 02 04:02:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:08 volumio go-librespot[30415]: go-librespot daemon starting... Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=debug msg="app state loaded" Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:08 volumio volumio[30042]: info: Updating MyVolumio device info Nov 02 04:02:08 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:08 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:08 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" 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 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" 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 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" 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 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=info msg="zeroconf server listening on port 38825" Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=debug msg="obtained new client token: AAAs/IK/PNVueYaohtfmYV+D4rtff126ZlyEYCPNYR2NayKWdPdJAG2Gwm9VKTtMd9X3GN7sUucAdWLmN1YEyloNHOJdgfUMVRKcEmtF3Zp96s86HoPKo1ZR858Bry6QmV9HGNzkYb5ZwBbpXKB4XSJW+bAs9IDuv9jaQltTsXZNpf34JWEqRlAGRtVQLqoBHAgWOTgXJe5608W0Wyz2KmyvHRk/krP5RSXja9P8VY7iwvyJOxPjZuU=" Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=debug msg="completed keyexchange" Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" level=debug msg="completed challenge" Nov 02 04:02:08 volumio go-librespot[30416]: time="2025-11-02T04:02:08Z" 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 04:02:08 volumio volumio[30042]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 04:02:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:08 volumio volumio[30042]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 04:02:09 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:09 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Nov 02 04:02:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:11 volumio go-librespot[30437]: go-librespot daemon starting... Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=debug msg="app state loaded" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=info msg="zeroconf server listening on port 35397" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=debug msg="obtained new client token: AACC0f8mMHJ8yPFN0534JZLkVuw0O0v9w9oGaFztTC9S5m/XJdSkYksldaBXPuq3Sa1W9t7ryAWP/uHP36uiY9xrttSIFCTjvd2uXS1YiXBOseUfz2sFXS+w9atPCtMd+w6ZGEk3q4gDQ9M2ZXSyaNSgTxdT+Xu5cxDG3VqrpOD568irekzyt84j/VafTwW/Ns5Xq5ekbhnEZOTkbGrifu9VJp/vSjB4X7xkL9EwOXZMAMWlHdUXHVI=" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 02 04:02:11 volumio go-librespot[30438]: time="2025-11-02T04:02:11Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 02 04:02:12 volumio go-librespot[30438]: time="2025-11-02T04:02:12Z" level=debug msg="completed keyexchange" Nov 02 04:02:12 volumio go-librespot[30438]: time="2025-11-02T04:02:12Z" level=debug msg="completed challenge" Nov 02 04:02:12 volumio go-librespot[30438]: time="2025-11-02T04:02:12Z" 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 04:02:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:12 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:12 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:12 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:02:15 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 04:02:15 volumio volumio[30042]: 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 04:02:15 volumio volumio[30042]: info: CoreCommandRouter::volumioGetState Nov 02 04:02:15 volumio volumio[30042]: info: CorePlayQueue::getTrack 0 Nov 02 04:02:15 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:15 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Nov 02 04:02:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:15 volumio go-librespot[30448]: go-librespot daemon starting... Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=debug msg="app state loaded" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" 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 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" 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 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" 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 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=info msg="zeroconf server listening on port 37017" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=debug msg="obtained new client token: AAAOy4kIERHTwgWiD3oU692GABvZKCiSGs9d3MA24dOD0i1gNaS8FlZA2NlUelT81lLLMo/SclVSZlVch/7Ut9JcU3OkbHtQNqRdNJlAL0e/y9Ha3pndDOnsv5+ybpktXM3Lmh+4GNQLQDUEbTi8WYyqiRnGk2ToCEzOatS1RcM53UxjpjPExoIL35zqnfy4gfTqUkgNRpuSv392j5Rz3w8kvACJYorvXplKoWquFBhft3isLhJ0v4o=" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=debug msg="completed keyexchange" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" level=debug msg="completed challenge" Nov 02 04:02:15 volumio go-librespot[30449]: time="2025-11-02T04:02:15Z" 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 04:02:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:17 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:02:18 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:18 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Nov 02 04:02:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:18 volumio go-librespot[30471]: go-librespot daemon starting... Nov 02 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" level=debug msg="app state loaded" Nov 02 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02: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-gew4.spotify.com:80]" Nov 02 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" 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 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" 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 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" level=info msg="zeroconf server listening on port 35343" Nov 02 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" level=debug msg="obtained new client token: AABuyLa5QyZ/YhtuAT6zjKwhOQCIjd2eHCgIRTcUuujAVWJ8eYPg+q6MJOLFhC5+aJLQcaAzWoxxQOOHJdW0TsLCSVcOUyozEVqRvxgmfLGbkKPnOFTxpDRhcdzrbG3zd4QdYPBSxfBVNWju/drByxwdV5DpVFYzpxEhq7iZg2mnYpveB2pRY73RJPcnz6Rq/enwY8o77JaSVZ81SpqGGAbgWJ81OllG0q3l/2vinSBuXsIhihhb72c=" Nov 02 04:02:18 volumio go-librespot[30472]: time="2025-11-02T04:02:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:19 volumio go-librespot[30472]: time="2025-11-02T04:02:19Z" level=debug msg="completed keyexchange" Nov 02 04:02:19 volumio go-librespot[30472]: time="2025-11-02T04:02:19Z" level=debug msg="completed challenge" Nov 02 04:02:19 volumio go-librespot[30472]: time="2025-11-02T04:02:19Z" 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 04:02:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 04:02:20 volumio volumio[30042]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 04:02:20 volumio volumio[30042]: info: Completed starting MyVolumio Plugin Nov 02 04:02:20 volumio volumio[30042]: [Metrics] CommandRouter: 32s 81.71ms Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::Close All Modals sent Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::Close All Modals sent Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 04:02:20 volumio volumio-remote-updater[874]: Test mode disabled Nov 02 04:02:20 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 02 04:02:20 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 04:02:20 volumio volumio[30042]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 04:02:20 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 04:02:21 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 04:02:21 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 04:02:21 volumio volumio[30042]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 04:02:21 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:21 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Nov 02 04:02:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:22 volumio go-librespot[30489]: go-librespot daemon starting... Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=debug msg="app state loaded" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" 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 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" 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 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" 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 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=info msg="zeroconf server listening on port 44011" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=debug msg="obtained new client token: AACdNEQAFb0s+rVgJGESNg5MY2UPi9V0J1HQoaIR2bhqVuKLM2IuW1gAmIiGghYeEPGYOGVQp2nlcV5LBsB15j3gRzavQ/yxqdf5FTSVZ2QqTAePIOc5H+ZE8BapH8XpPWiyMn2boEjHLAmbnUY+OGcbPDHD74NcdYJeOX5y9e39Ntr0bwGkULJyUna/HiHv0v86/lbbVJGcBE/fecR08xjYnEHzXHCJVIdAXQjouE/DnR2EIa5tIEk=" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=debug msg="completed keyexchange" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" level=debug msg="completed challenge" Nov 02 04:02:22 volumio go-librespot[30490]: time="2025-11-02T04:02:22Z" 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 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:24 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:24 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:25 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:02:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Nov 02 04:02:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:25 volumio go-librespot[30497]: go-librespot daemon starting... Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=debug msg="app state loaded" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" 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 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" 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 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=info msg="zeroconf server listening on port 38735" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=debug msg="obtained new client token: AADCfAhq8c2NSbp9orUBZo25O69hMZgpWd2OM87akki6diqaItUZ/77rCkhex/Jj7hLgnqzVGYqvb+69x5yckDu7I9f62CIytuEnPhvw9HPTarAZYjZNGP4XvWRSHlxJ/E1OvGf832Gve9cq/gBSrioK8yiK9VI5XMgkHBglvgsM/WYa8XTf7sKgtqJOn12/RruN8n4wNrWivdKwUGxYJ3GNfgPfMosPvhF7KFLTC1J2pjn0hDbI88c=" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=debug msg="completed keyexchange" Nov 02 04:02:25 volumio go-librespot[30498]: time="2025-11-02T04:02:25Z" level=debug msg="completed challenge" Nov 02 04:02:26 volumio go-librespot[30498]: time="2025-11-02T04:02:26Z" 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 04:02:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:27 volumio volumio[30042]: info: BOOT COMPLETED Nov 02 04:02:27 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:27 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Nov 02 04:02:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:29 volumio go-librespot[30519]: go-librespot daemon starting... Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=debug msg="app state loaded" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" 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 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" 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 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" 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 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=info msg="zeroconf server listening on port 38517" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=debug msg="obtained new client token: AADdQKU/cbNU+mfN1DINHfkGxh9uTWbj5yNQvPvdBexgMQSRMsN7yS693XGJP1aX1N3fvpiMWT4UvDyQL0ge91F9a8hj27uxiu2mvQpVrAwa8617QpXu7yqqi1YcrIVGSFZbGWXWdkRWSBjAHyt/R5dcUaQ3w3eUthLxdoCA2MKj6HHq5Gvlat9noYrLWLoz6Ns3H4L9cl9RrtabQlpuuzck+a3YzQcZFpRBTMIWmQevdfre11ir2jc=" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=debug msg="completed keyexchange" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" level=debug msg="completed challenge" Nov 02 04:02:29 volumio go-librespot[30520]: time="2025-11-02T04:02:29Z" 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 04:02:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:30 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:30 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Nov 02 04:02:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:32 volumio go-librespot[30527]: go-librespot daemon starting... Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=debug msg="app state loaded" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" 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 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" 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 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=info msg="zeroconf server listening on port 38507" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=debug msg="obtained new client token: AACFn1Y0EdNEOzpOVT7pIDz9JBSRJL5iarhaygVCVCeApICR36CVgXkGg/poLJeufJ44txM5TihRQjxQRM8i6rJWVOttSPzYrb2/BJyNBW2ME3jsW3SlELMS8223eQCSoApR7RJg1JZx4mPLspOxed3hyfKYovuAPmTSwmA6lQLbW243uxg6ZsV37w2IBks8oXeJTBSIVuRXCBBB2rbXVw1H6Y1ByumkiLVcKDlcwzJEkXvx5p0+Up4=" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=debug msg="completed keyexchange" Nov 02 04:02:32 volumio go-librespot[30528]: time="2025-11-02T04:02:32Z" level=debug msg="completed challenge" Nov 02 04:02:33 volumio go-librespot[30528]: time="2025-11-02T04:02:33Z" 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 04:02:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:33 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:33 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Nov 02 04:02:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:36 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:36 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:36 volumio go-librespot[30538]: go-librespot daemon starting... Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=debug msg="app state loaded" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" 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 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" 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 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" 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 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=info msg="zeroconf server listening on port 38833" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=debug msg="obtained new client token: AAC2Fyf6vjL/bmNZ5vfeEKF0b4+9Euy7lcELys38GKBwk40Pd+eYJZ4gGZGa2UvOUYjPIjvX+Cqbc0EpF+IfdF+btJW5hTixKshSsCDx2cdZLYrXscxNNwSWHPp5nZRcepqEwZTIlyPrh8arqV6MZmqmXLpga1eJdW8d9KWjJPV7zMLCxYODVMVIFPfMaXrWJOrH8igb42ryS1+wWzcGcjiKBRM2peQRkEJV3aHPaCqHRvY7yKVWC6A=" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=debug msg="completed keyexchange" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" level=debug msg="completed challenge" Nov 02 04:02:36 volumio go-librespot[30539]: time="2025-11-02T04:02:36Z" 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 04:02:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:38 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:02:39 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:39 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Nov 02 04:02:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:39 volumio go-librespot[30561]: go-librespot daemon starting... Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=debug msg="app state loaded" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=info msg="zeroconf server listening on port 41033" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=debug msg="obtained new client token: AAC+5q0IOr9AAx12wipDjD8jAfRyJLA70pmdBaL64wWC08kAhp+5yYH15WPatPFYCdvsPRR7DaA5JzNktN2vS7Q5RQZXTqLoZ8Xo2+6B5/ub5rrGuEfDCKJ/7tB3xmqXFfWY4Usmswf2gjlr9mt+jAJdGaoeunFhn0OxtqWj8QdHVgqQKLQ2MleZDaOVKl3S7KhwpntGnk8VdS1WgXU0tm+EaOxv/AMGe42HpWfwCRCj91evaMqKuHE=" Nov 02 04:02:39 volumio go-librespot[30562]: time="2025-11-02T04:02:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:40 volumio go-librespot[30562]: time="2025-11-02T04:02:40Z" level=debug msg="completed keyexchange" Nov 02 04:02:40 volumio go-librespot[30562]: time="2025-11-02T04:02:40Z" level=debug msg="completed challenge" Nov 02 04:02:40 volumio go-librespot[30562]: time="2025-11-02T04:02:40Z" 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 04:02:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:42 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:42 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Nov 02 04:02:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:43 volumio go-librespot[30569]: go-librespot daemon starting... Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=debug msg="app state loaded" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" 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 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" 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 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" 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 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=info msg="zeroconf server listening on port 44559" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=debug msg="obtained new client token: AAAkN9G0PK43Odn+tlEvd4IuWFEnURgvjF62hvKoSh7sLSI6CY+Os7NCD88ymQbe+kmOF2j45CJ3jyLPcOaukNDKNaWHJTNagx0aeT2uCBbicxXFjAFZTg4qXLLQ+oCRjjY/5ndIl4wGHwfZKWsuabeH4/ckk4SOmcBWuauyuZ8D6cCA8Tg++llQO6Ij8ZXCFMZhLAoaCGFio7ph4DdaYkmlWffU4Kf/JH08J9gctO63jiv8PDKee0A=" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=debug msg="completed keyexchange" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=debug msg="completed challenge" Nov 02 04:02:43 volumio go-librespot[30570]: time="2025-11-02T04:02:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 04:02:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:45 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:45 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Nov 02 04:02:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:46 volumio go-librespot[30577]: go-librespot daemon starting... Nov 02 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02:46Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02:46Z" level=debug msg="app state loaded" Nov 02 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02: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 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02: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 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02: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 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02:46Z" level=info msg="zeroconf server listening on port 43713" Nov 02 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02:46Z" level=debug msg="obtained new client token: AAAvib9wDQWI+nTXOk03iV+ZPhqzbt+mkBudd/BGkitgEsw+rWLtgGULai81JOMFIlg/25oAISeUJ+L21hr4MytYbvNvdgUnqzzVISrYi6aEf0a5ufdSfJr8nrMpN2ZzZRpQsvJmFsMt9A4Y/Gh87A5gTuTNrlNs26qFNZc5VTcLSLey/w1NzD1YXZMpqjfFsX2DyKUqQiOstJ/Yk2IHdPyXg0BeN6tBtQftAU+/OZMYM763SUVF9yI=" Nov 02 04:02:46 volumio go-librespot[30578]: time="2025-11-02T04:02:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:47 volumio go-librespot[30578]: time="2025-11-02T04:02:47Z" level=debug msg="completed keyexchange" Nov 02 04:02:47 volumio go-librespot[30578]: time="2025-11-02T04:02:47Z" level=debug msg="completed challenge" Nov 02 04:02:47 volumio go-librespot[30578]: time="2025-11-02T04:02:47Z" 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 04:02:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:48 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:48 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Nov 02 04:02:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:50 volumio go-librespot[30599]: go-librespot daemon starting... Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=debug msg="app state loaded" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" 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 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" 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 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" 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 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=info msg="zeroconf server listening on port 46697" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=debug msg="obtained new client token: AABbQ0yiIijAYVaUFY2gNhdNAI6gfBcUHuEks4SgnfWCeSwah8R5eOrXKb0HyR2dcstVOOPF+czoZjl3yJJw7uHFhP2yHT3UF4hhOOE0uUfdD3gw6kRNplNl/AUwar5gsWvux25JLAO9HMq2IzWpWKr4YZ/hUA1du9YJMOGpFuu3sDS3MmNkk2PTx1nQVkasg6B2rqUNGcFpPItlqL98wVFn5NlDPngkEIvUa/+Q3MWnamRAiezJ+uA=" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=debug msg="completed keyexchange" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=debug msg="completed challenge" Nov 02 04:02:50 volumio go-librespot[30600]: time="2025-11-02T04:02:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 04:02:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:51 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:51 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:52 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:02:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Nov 02 04:02:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:53 volumio go-librespot[30607]: go-librespot daemon starting... Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=debug msg="app state loaded" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" 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 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" 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 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=info msg="zeroconf server listening on port 44605" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=debug msg="obtained new client token: AACtd+dMRSnxaKo2TxEUn/XuL4S3RqlVmJQ+5daZDN2m+2ASG9khJdcH9fRcghc1LnnEHXX9b99N8iZTuEFITCureC10OTZjQbApmyEW0pD6YdrFgHMhlrGfWBrQ6d+5T3Hwb3t8fIzLhpK1D+R1lz2VSN7fTCmKGbTUBOqyc9zXCPFwZ9xcOx9x6WYAjgtVexzaxeqTOfpsIrSSgJHD4wk2R4VpzZgyGGsuVYHA4H+xOpB7mieRI24=" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=debug msg="completed keyexchange" Nov 02 04:02:53 volumio go-librespot[30608]: time="2025-11-02T04:02:53Z" level=debug msg="completed challenge" Nov 02 04:02:54 volumio go-librespot[30608]: time="2025-11-02T04:02:54Z" 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 04:02:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:02:54 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:54 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Nov 02 04:02:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:57 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:02:57 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:02:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:02:57 volumio go-librespot[30618]: go-librespot daemon starting... Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=debug msg="app state loaded" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" 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 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" 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 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" 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 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=info msg="zeroconf server listening on port 45793" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=debug msg="obtained new client token: AABUog8PlHbH88yiV8orOcOLb28l5QufJ3Fkt+5NG3UG8ZLXPD8vPlzJ+Tz23OcJHD/LZdBFFr8esebqcmth1zK7Egt5z+uD4GzXiEpSOuIJNz/JonBuv+Yfo8nLmQDfiq3kKhtXVyvG6J5DQh4lp+a662PXW1cRS+wwVob4j5K4dvQULR8Y3oVEbiGCtJHvHqo0wtP0vZlVth39iZKBcvLyFZo663FK3qXjb+tR2buXW/T+cD/y9Hc=" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=debug msg="completed keyexchange" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=debug msg="completed challenge" Nov 02 04:02:57 volumio go-librespot[30619]: time="2025-11-02T04:02:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 04:02:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:02:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:00 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:00 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Nov 02 04:03:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:00 volumio go-librespot[30641]: go-librespot daemon starting... Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=debug msg="app state loaded" Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03: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 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03: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 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03: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 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=info msg="zeroconf server listening on port 44623" Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=debug msg="obtained new client token: AADeNhHRX0TXw4u2UG7rxqnmuiVdJf2qkXfEtH9vpyCpItuQTOtumBVcKTtgBVs+H0lL4HW1wqnA8PH4xdbkVU2XKI+62MzCL4wFfx+BTLuhCJXtavdys3SywRy+mtq9v87jWE+QwveniC924mitR8WjD+Mc6K0Q2MHI2uR98Tbc1RIbBgdj0hdKReI1tyzoRq4xCejPNiKr4ebcuSm6XWKyQT3XlbnWk3Z/6kXSTlGex3ef+pqaxtc=" Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=debug msg="completed keyexchange" Nov 02 04:03:00 volumio go-librespot[30642]: time="2025-11-02T04:03:00Z" level=debug msg="completed challenge" Nov 02 04:03:01 volumio go-librespot[30642]: time="2025-11-02T04:03:01Z" 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 04:03:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:03 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:03 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Nov 02 04:03:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:04 volumio go-librespot[30649]: go-librespot daemon starting... Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=debug msg="app state loaded" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" 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 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" 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 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=info msg="zeroconf server listening on port 35395" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=debug msg="obtained new client token: AAD7/YNJIq0EEjRQuVcCwtsrWpf+e6N1OLOQzRg/bXdfOzlT7i8uW6hfsu6twYuuBUglhbwfRqm+kptV2OA6qt8w8HiTHIBtnORvHzBZkx24mJTAI6WpHMHFHeZBQhx3pOpzp+ESeBH4jmgkLU50XesavjTpRDD6/LkNQZC8BgzoTw3hPRRuXCeKVL4QRd155/fQZ6gwd7wWD5/tqt/8XImDdBhLXn9GvXxJt3JjEUsQJoT8wV/KaaU=" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=debug msg="completed keyexchange" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03:04Z" level=debug msg="completed challenge" Nov 02 04:03:04 volumio go-librespot[30650]: time="2025-11-02T04:03: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 04:03:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:06 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:03:06 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:06 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Nov 02 04:03:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:07 volumio go-librespot[30657]: go-librespot daemon starting... Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=debug msg="app state loaded" Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" 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 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" 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 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" 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 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=info msg="zeroconf server listening on port 39981" Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=debug msg="obtained new client token: AAD1hoq04nVoUNXoQpehxxKhi/iYp5kQbkxdisXGS7uPpejRQRGFLpLEfWxcB7xdpTsi/IMFnxcjwzeYee7x9jT1s4OkHFzsOVMXa5PB/T7XPxz3M6pPrcfdwDncT5p/KPel3AOWpsIBzfj2p4U0WLa1mBvyCUlpjRkKQjk8lTAW0zR2UHUTdQG/lMCUY+PfF5jC+GSCyDEzxbrnf8tvTeZCqhZxQvVBF69Corq5gZYKQMgWbmME58s=" Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=debug msg="completed keyexchange" Nov 02 04:03:07 volumio go-librespot[30658]: time="2025-11-02T04:03:07Z" level=debug msg="completed challenge" Nov 02 04:03:08 volumio go-librespot[30658]: time="2025-11-02T04:03:08Z" 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 04:03:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:09 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:09 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Nov 02 04:03:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:11 volumio go-librespot[30680]: go-librespot daemon starting... Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=debug msg="app state loaded" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" 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 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" 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 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=info msg="zeroconf server listening on port 38359" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=debug msg="obtained new client token: AACRlUE6BkQeCmagIucyotxym0an8vyvp9uHcyWtq6vVF2LLtNsIQOB+KlYixV3D9KNTD8ywXv8rSRkNTnwjY1Bj2cPvs5Eqno1TRFHr4VdO8bCaq/e9oMWiSkWRsKvKjratcxgnVhwLa9/CNWiNiyAHcNRLXQhihjoJkcuQf1VXgrdw+8uXPsIJMMoSK1XkdESq8++eXWx9OnZjjxz9I1muLXipIdp0bS4r/pTl/d8+sg7ledx5hoE=" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=debug msg="completed keyexchange" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03:11Z" level=debug msg="completed challenge" Nov 02 04:03:11 volumio go-librespot[30681]: time="2025-11-02T04:03: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 04:03:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:12 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:12 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Nov 02 04:03:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:14 volumio go-librespot[30691]: go-librespot daemon starting... Nov 02 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03:14Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03:14Z" level=debug msg="app state loaded" Nov 02 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03: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 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03: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 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03: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 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03:14Z" level=info msg="zeroconf server listening on port 35811" Nov 02 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03:14Z" level=debug msg="obtained new client token: AACagUTSWPYInNijD8v4KeivKyvLtB10WIEQecDJfDAviSptZmfI4qtsEqy3scAHilQz5yYCfs0tNODWlp1N8qBIlxMnH67R8TiQEMML5RD9mw0Qu2n6laFMAmUPiJE18w0mNSb521EymVFaiaudCIvX4eFF4Jwz05Mi6DqKEyZjfnv7oaqTL0RlrdVAo6ffPaf1xdbMU/lCPEZlOTHF3M9BYDyY9GKGW1zfsDWwTIB3O+uIcjn6jeU=" Nov 02 04:03:14 volumio go-librespot[30692]: time="2025-11-02T04:03:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:15 volumio go-librespot[30692]: time="2025-11-02T04:03:15Z" level=debug msg="completed keyexchange" Nov 02 04:03:15 volumio go-librespot[30692]: time="2025-11-02T04:03:15Z" level=debug msg="completed challenge" Nov 02 04:03:15 volumio go-librespot[30692]: time="2025-11-02T04:03:15Z" 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 04:03:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:15 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:15 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Nov 02 04:03:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:18 volumio go-librespot[30700]: go-librespot daemon starting... Nov 02 04:03:18 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:18 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="app state loaded" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=info msg="zeroconf server listening on port 38433" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="obtained new client token: AADOBL6Y6N3V5Rtz55uzl1wnsua9y3tiGsFA/UGjHDo0zLrMrk/SdQb1bif3y4aHprU0uBUJsAE/4qTz7+mcMdtNaiyze9CVv6ZUmPxFi+S0Ruu7XY/bfSYq50uth1nUVvjqG2Xu0b+OGdJzoIfIL3qyQSCucTnL7xWhG0axifC8ls+vuXdzuG8vsRuyLCwljaRcWq0TrlG8XtlDv/FzhokcIj9mAHUNBTDYQGIYxj1Kv7+gzSp/vV8=" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="completed keyexchange" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03:18Z" level=debug msg="completed challenge" Nov 02 04:03:18 volumio go-librespot[30701]: time="2025-11-02T04:03: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 04:03:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:21 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:21 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:21 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:03:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Nov 02 04:03:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:21 volumio go-librespot[30722]: go-librespot daemon starting... Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="app state loaded" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=info msg="zeroconf server listening on port 42885" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="obtained new client token: AABeK90eeop7guazjwSwTAMwEgIxpA9PNosboPZyTfbvm5ycErq1JEbv8Rv7jSHBLg8plaQcXT8q2lQDNzVvQXLAxiQ8CqjyvPysJ/6rhZB4VPjdQ85cn5WWKpR1zzWXXB3Rvr6nezR2ZnNNMIn/zdaNRSDlZ847W/sZhqhf7fI4wJZYCEC5WSghJ/NH+Hc198HrLcgV+hN/B47DOpjn2AYTqBfEwEycF4MY9ToMrLXQuykj0N8AO3g=" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="completed keyexchange" Nov 02 04:03:21 volumio go-librespot[30723]: time="2025-11-02T04:03:21Z" level=debug msg="completed challenge" Nov 02 04:03:22 volumio go-librespot[30723]: time="2025-11-02T04:03:22Z" 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 04:03:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:24 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:24 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Nov 02 04:03:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:25 volumio go-librespot[30731]: go-librespot daemon starting... Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=debug msg="app state loaded" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" 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 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" 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 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=info msg="zeroconf server listening on port 34607" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=debug msg="obtained new client token: AAAJABkcYUTaC0ibTD9HOlhUq3iKQxM5IFWSo5V/xtd8sqpSB4gUhY14WQ3VZBtzZoxkpzJ3phN5L+YTeqR/FXAqXz7kNblTc4XX+4ZOPa5va+cUP3o1hCWU10kLF/MpxFIAWTyxaYB1dVZd+Dzz223zXliHRZEXc0s7/FIGjsNosztVptkJj6YIkHrD2Q4vSzAfsNJAoANYtnqq9XOO5IdMbfXDTf+QEBJndi2b+Zrn2pyCGzdSYYA=" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=debug msg="completed keyexchange" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03:25Z" level=debug msg="completed challenge" Nov 02 04:03:25 volumio go-librespot[30732]: time="2025-11-02T04:03: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 04:03:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:27 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:27 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Nov 02 04:03:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:28 volumio go-librespot[30753]: go-librespot daemon starting... Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="app state loaded" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=info msg="zeroconf server listening on port 38813" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="obtained new client token: AACgnwOk8m7l7NsiuzG+Cbw0uO7AkRfd9Pk6eXM++/fQZt524rnsL2CZn3eIB3LlBQDGcdVLmI//67JARBkhElRL9Au5MdNVGEdmfJDZSisHFhh9bDRNfLGjSlail/COErKXa6J9ZlxC7OhH714eKN+TDRp9sLqu+j041lybRbYyrrKFIO5/8aChZ5eI5ZABpLl+5hSzuKjI8DNOZq/3pgTLFFT0Tpg/fLiI+jG6FekjkZqifRwV4po=" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="completed keyexchange" Nov 02 04:03:28 volumio go-librespot[30754]: time="2025-11-02T04:03:28Z" level=debug msg="completed challenge" Nov 02 04:03:29 volumio go-librespot[30754]: time="2025-11-02T04:03:29Z" 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 04:03:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:29 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 04:03:30 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:30 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Nov 02 04:03:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:32 volumio go-librespot[30761]: go-librespot daemon starting... Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=debug msg="app state loaded" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" 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 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" 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 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=info msg="zeroconf server listening on port 46199" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=debug msg="obtained new client token: AAC+ID2PAZk77G/MMRB4OcqoRR+7gAPdYmSW6bT5r86mYse7nteK3MCTHQtSOJnkD53Dm2tp8liPYTjny8DaofDXEwsvJ0t95LT/d012M4ubMLzQHdDermcH6MgSAEm9dJLFnsKfBEyBbLsq5pg0o8x9sOTyhHO0C23sNWFInVp4VvOXDWfhAJnpOc2zsTvtcEnYG4QBe+62FVEZzXnr5cF5oJwD4cK8xZAo2Q2aZn+udD6rB+WSjCU=" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=debug msg="completed keyexchange" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03:32Z" level=debug msg="completed challenge" Nov 02 04:03:32 volumio go-librespot[30762]: time="2025-11-02T04:03: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 04:03:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:33 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:33 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Nov 02 04:03:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:35 volumio go-librespot[30772]: go-librespot daemon starting... Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=debug msg="app state loaded" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=info msg="zeroconf server listening on port 34773" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=debug msg="obtained new client token: AAA35hFJSbRy2iMZZeimPGCyR0RnZGCQgA41iYstY6doKhFLpx77s+e9UhcaSIE4PQK8LeuCGakA5Xo6chwpDL9yEhYzcobIgCrGMOJ2qHI1rmTIkGwXfXSrJp2XpQi3TWC+SsJl86OiLsm2KxPNiLi09wP9BLP9UyvXLnEuDplgSD6KepmHW90/L5wtsfWmDpZUPUrvsFZKuWgMA2LvvqzDtbXky5IY7nVnPX3NDNI15BinW89cOKo=" Nov 02 04:03:35 volumio go-librespot[30773]: time="2025-11-02T04:03:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:36 volumio go-librespot[30773]: time="2025-11-02T04:03:36Z" level=debug msg="completed keyexchange" Nov 02 04:03:36 volumio go-librespot[30773]: time="2025-11-02T04:03:36Z" level=debug msg="completed challenge" Nov 02 04:03:36 volumio go-librespot[30773]: time="2025-11-02T04:03:36Z" 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 04:03:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:36 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:36 volumio volumio[30042]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Nov 02 04:03:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 04:03:39 volumio go-librespot[30794]: go-librespot daemon starting... Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=info msg="running go-librespot 0.4.0" Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=debug msg="app state loaded" Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 04:03:39 volumio volumio[30042]: info: Initializing connection to go-librespot Websocket Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=debug msg="new websocket client" Nov 02 04:03:39 volumio volumio[30042]: info: Connection to go-librespot Websocket established Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" 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 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" 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 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" 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 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=info msg="zeroconf server listening on port 40649" Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=debug msg="obtained new client token: AAD4gVv/Ij3tKyex08T+byDQP7WoxRv8xriJNKW1XepttOLfYBggDAYMXkZzUkNB0fvPqIEidaV0S+uvtFelgWsLPImcz6AalGXMOX49vUiLyWyAfS1j4rXfwvQofod4u7jckE9WlFE6baraaCPJTbJa70uR3Da7Jara72iWDf2DuM2VFS+qC/aTUL84MiFePBdba0oa5tKrQfDiCI0Qk+mvH1BzVw7bOdDxieRfhR0Oh4zwYSBzCrU=" Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=debug msg="completed keyexchange" Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03:39Z" level=debug msg="completed challenge" Nov 02 04:03:39 volumio go-librespot[30795]: time="2025-11-02T04:03: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 04:03:39 volumio volumio[30042]: info: Connection to go-librespot Websocket closed Nov 02 04:03:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 04:03:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 04:03:42 volumio volumio[30042]: info: Getting Spotify volume Nov 02 04:03:42 volumio volumio[30042]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 04:03:42 volumio volumio[30042]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 04:03:42 volumio volumio[30042]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 02 04:03:42 volumio volumio[30042]: errno: -111, Nov 02 04:03:42 volumio volumio[30042]: code: 'ECONNREFUSED', Nov 02 04:03:42 volumio volumio[30042]: syscall: 'connect', Nov 02 04:03:42 volumio volumio[30042]: address: '127.0.0.1', Nov 02 04:03:42 volumio volumio[30042]: port: 9879, Nov 02 04:03:42 volumio volumio[30042]: response: undefined Nov 02 04:03:42 volumio volumio[30042]: } Nov 02 04:03:42 volumio volumio[30042]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 04:03:42 volumio sudo[30816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-02 04:02' Nov 02 04:03:42 volumio sudo[30816]: 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"