Oct 27 23:43:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101. Oct 27 23:43:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:00 volumio go-librespot[16280]: go-librespot daemon starting... Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="app state loaded" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=info msg="zeroconf server listening on port 32847" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="obtained new client token: AAC7ZNTPBXVNwcpNJkOvNa5t7o235kT64PCWUvGSzk29HY2SY9vC7VdTFfzZ04/gRCYC3RKzL8SicpLBvpR91DRcjTWb/NFQRO+tn5Mc+zLMbAOfN47xmH6i6uTIiVYQJxATixAkx5K8fz9G7VBebYQxO1W9gLb0XyLtYDsfuVNzD9QYxRNaFUfqwVFmXZyE5lE57ZtQTxht4ZtHJ+4EMC6F+3m/uIvnEGnFAX9+MhgQHcFeJcX5yDw=" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="completed keyexchange" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=debug msg="completed challenge" Oct 27 23:43:00 volumio go-librespot[16281]: time="2025-10-27T23:43:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:02 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:02 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:02 volumio volumio[15970]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Oct 27 23:43:03 volumio volumio[15970]: info: MyVolumio token set successfully Oct 27 23:43:03 volumio volumio[15970]: info: MYVOLUMIO: Adding device Oct 27 23:43:03 volumio volumio[15970]: info: MYVOLUMIO: Evaluating Server Oct 27 23:43:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102. Oct 27 23:43:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:03 volumio go-librespot[16292]: go-librespot daemon starting... Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" level=debug msg="app state loaded" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" 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]" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" 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]" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" 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]" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" level=info msg="zeroconf server listening on port 41211" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" level=debug msg="obtained new client token: AABZsqDPo6VKRjLoeja2yXivkkmtpw9Z/gZ3Kjcjp2U9tuZDil7nSBodJvbZ30JIFDKNNFfhfwJxpDnczmhINh5sKWxquj6m/YT6n6BsPMEHTCWZQsta9asZJCLWQy+nK9ekepy7Mf+2B10KGEKTjguLsUQmABdypGqaIh4FpmRMmHQMyZzB0Kn5jKHSBdGhGwAnx1YwIFnvnFWlEKZs1PlLwIkt3Uw0sEk7a3ztHXR0ZNXCdsYx3fU=" Oct 27 23:43:03 volumio go-librespot[16293]: time="2025-10-27T23:43:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:04 volumio go-librespot[16293]: time="2025-10-27T23:43:04Z" level=debug msg="completed keyexchange" Oct 27 23:43:04 volumio go-librespot[16293]: time="2025-10-27T23:43:04Z" level=debug msg="completed challenge" Oct 27 23:43:04 volumio go-librespot[16293]: time="2025-10-27T23:43:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:04 volumio volumio[15970]: info: MyVolumio status changed Oct 27 23:43:04 volumio volumio[15970]: info: Streaming services startup Oct 27 23:43:04 volumio volumio[15970]: info: Starting Streaming Daemon Oct 27 23:43:04 volumio volumio[15970]: info: Removing browser output: myVolumio user plan is not superstar Oct 27 23:43:04 volumio volumio[15970]: info: Removing audio output: Oct 27 23:43:04 volumio volumio[15970]: info: Stoppping Tunnel 1 Oct 27 23:43:04 volumio sudo[16318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 27 23:43:04 volumio sudo[16318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 23:43:04 volumio sudo[16316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 27 23:43:04 volumio sudo[16316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio sudo[16316]: pam_unix(sudo:session): session closed for user root Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 27 23:43:04 volumio volumio[15970]: error: Cannot start Volumio Streaming Daemon Oct 27 23:43:04 volumio volumio[15970]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 27 23:43:04 volumio volumio[15970]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 27 23:43:04 volumio sudo[16318]: pam_unix(sudo:session): session closed for user root Oct 27 23:43:04 volumio volumio[15970]: info: Remote SSH Stopped Oct 27 23:43:04 volumio volumio[15970]: info: Setting Geolocation for MyVolumio to eu8 Oct 27 23:43:04 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:04 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:04 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:05 volumio volumio[15970]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 27 23:43:05 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:05 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:05 volumio volumio[15970]: info: Updating MyVolumio device info Oct 27 23:43:05 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:05 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:05 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:05 volumio volumio[15970]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 27 23:43:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103. Oct 27 23:43:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:07 volumio go-librespot[16321]: go-librespot daemon starting... Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=debug msg="app state loaded" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" 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]" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" 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]" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=info msg="zeroconf server listening on port 38635" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=debug msg="obtained new client token: AACpwtqNQUEwp981hsP0xqcv9UmUht5p7dj90zj+Mgn4YpxWaGGNUlsQnapehnZvWCipZUSJYXw7dU9jdK2fLbsejTPz3WCqJ57xawK1mbG1w4Xf1ecf6cjE7D4Vvq90QKyL2UOOqJH0Gu4KGn/b34YIgWw1NCl3OUR6QXKkrZaW78faoSjuT+g6S6JbCMJw8gstGVyOW8q3cCr2H64NUZ+J9/rys+rMyVnq9fMr/vq8jYjciCmPB4w=" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=debug msg="completed keyexchange" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=debug msg="completed challenge" Oct 27 23:43:07 volumio go-librespot[16322]: time="2025-10-27T23:43:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 27 23:43:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:07 volumio volumio[15970]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 27 23:43:08 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:08 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:09 volumio volumio[15970]: info: MYVOLUMIO: Adding device Oct 27 23:43:09 volumio volumio[15970]: info: MYVOLUMIO: Evaluating Server Oct 27 23:43:09 volumio volumio[15970]: info: Setting Geolocation for MyVolumio to eu3 Oct 27 23:43:09 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:09 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:09 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:09 volumio volumio[15970]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 27 23:43:10 volumio volumio[15970]: info: Updating MyVolumio device info Oct 27 23:43:10 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:10 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:10 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104. Oct 27 23:43:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:10 volumio volumio[15970]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 27 23:43:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:10 volumio go-librespot[16358]: go-librespot daemon starting... Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" level=debug msg="app state loaded" Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" 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]" Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" 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]" Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" 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]" Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" level=info msg="zeroconf server listening on port 41675" Oct 27 23:43:10 volumio go-librespot[16359]: time="2025-10-27T23:43:10Z" level=debug msg="obtained new client token: AABq/FGGZQTxfyF9tg9DX4DU7DrLfjwLHRF2V/60XrLbwUaJMUIkpRqGQNjF1qiF3gbrKLOcBGzgCOrvK8PwBuqOjdT9HuiXAMaUdu/rUFFRsKfqPVqEwWiHxJH9gNcNKjhkI/9IZNGLp06W6+njDySoNU5YbLod/XGuJIjjjekM31DUQeSQO9BM5MGWP/8St4JU+oV6dC2QI6nyiYCLC9OHP+/yv2I3IYQOF/FusvFhHprDGw3Nqs8=" Oct 27 23:43:11 volumio go-librespot[16359]: time="2025-10-27T23:43:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:11 volumio go-librespot[16359]: time="2025-10-27T23:43:11Z" level=debug msg="completed keyexchange" Oct 27 23:43:11 volumio go-librespot[16359]: time="2025-10-27T23:43:11Z" level=debug msg="completed challenge" Oct 27 23:43:11 volumio go-librespot[16359]: time="2025-10-27T23:43:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:11 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:11 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:14 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 23:43:14 volumio volumio[15970]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 27 23:43:14 volumio volumio[15970]: info: CoreCommandRouter::volumioGetState Oct 27 23:43:14 volumio volumio[15970]: info: CorePlayQueue::getTrack 0 Oct 27 23:43:14 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:14 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105. Oct 27 23:43:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:14 volumio go-librespot[16371]: go-librespot daemon starting... Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=debug msg="app state loaded" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" 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]" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" 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]" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" 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]" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=info msg="zeroconf server listening on port 43691" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=debug msg="obtained new client token: AAAxvTUZ9PkPfjAG6PeLNH1xPskHi545UZ2l3G3Av1CbeMLcW3S8MxNfenTkhoLlOVJaKQwEI0uLxtPuJtALDcdar4q0m6el5A0TuJO2dgjW9K9ReGxdczbrpzhNKQsgq66lZ9GCaOhK8TCa6h9ELqKCoVByDjW7XfqGYaqRC9pzqCWuddiPrXIZDuMOBlHtErl9c47NGGRActjdxRcRcgxeBIv8RbigL3DXzvawogAhuYrg2fxIoCY=" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=debug msg="completed keyexchange" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=debug msg="completed challenge" Oct 27 23:43:14 volumio go-librespot[16372]: time="2025-10-27T23:43:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:17 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:17 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106. Oct 27 23:43:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:17 volumio go-librespot[16393]: go-librespot daemon starting... Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=debug msg="app state loaded" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=info msg="zeroconf server listening on port 40205" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=debug msg="obtained new client token: AADcJfQL2iYtPGfQkqDEGzwf1ELwjsoTa89NHAG4xe2MN7XSEor9/M35JrsjEUQXAr25wGL9HX+fOALtLlKd7G7qPXKN88tap5UyTB+tsNad+TtofBXo6ovaIZSf5fywjXGCOLcGB85CccguSfhLas67KdFaekAWKljwWuJtwB5UFbabMZ6VESJ4xyu1ug8mGBtswTVMDJ2emW5kgXv/EykU1M3OE7x3GHDHIiEURLzlzGJdCAuIxm4=" Oct 27 23:43:17 volumio go-librespot[16394]: time="2025-10-27T23:43:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:18 volumio go-librespot[16394]: time="2025-10-27T23:43:18Z" level=debug msg="completed keyexchange" Oct 27 23:43:18 volumio go-librespot[16394]: time="2025-10-27T23:43:18Z" level=debug msg="completed challenge" Oct 27 23:43:18 volumio go-librespot[16394]: time="2025-10-27T23:43:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 23:43:19 volumio volumio[15970]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 27 23:43:19 volumio volumio[15970]: info: Completed starting MyVolumio Plugin Oct 27 23:43:19 volumio volumio[15970]: [Metrics] CommandRouter: 32s 73.04ms Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::volumiosetStartupVolume Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::Close All Modals sent Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::Close All Modals sent Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 23:43:19 volumio volumio-remote-updater[874]: Test mode disabled Oct 27 23:43:19 volumio volumio-remote-updater[874]: Alpha mode disabled Oct 27 23:43:19 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Oct 27 23:43:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 27 23:43:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 27 23:43:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 23:43:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 27 23:43:20 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:20 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:20 volumio volumio[15970]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 27 23:43:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 27 23:43:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Oct 27 23:43:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:21 volumio go-librespot[16411]: go-librespot daemon starting... Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="app state loaded" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=info msg="zeroconf server listening on port 38171" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="obtained new client token: AAAG+vhF3cluPRYcloRmFn7c8rPLK3fns+u7x4j584wk0A6gcfhcJuuc9BILeDuQpvl8YGmpv/EU2/Bsl2971450TxrbeUjTJ62tk4/Ez/763ZvTMkUZ50MWN1fAp9Mw6XdGZqBX72YAqbkdaC1AL1yOi+Bp5LnTq8uCu7pA2YIGivTdoiNN7CKK1A7Fd27pRVYIdEkHA0cc1ZqI5NmlAnHomKqaBgaIc8IDEXlKlwAnLu8qej8iiyQ=" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="completed keyexchange" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=debug msg="completed challenge" Oct 27 23:43:21 volumio go-librespot[16412]: time="2025-10-27T23:43:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:23 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:23 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Oct 27 23:43:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:24 volumio go-librespot[16419]: go-librespot daemon starting... Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=debug msg="app state loaded" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=info msg="zeroconf server listening on port 37981" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=debug msg="obtained new client token: AACZcleqD4ClpskEX7j5m/UzQzYxiz+9dHr1T/JjiQAmKHXmGqxyILb/8DWuBNuY7cdQnyEJfC676s4DQRu1Pb6/Bd0Dj97LTJoX0pgbp77iYkbbf1/w+2CK9KyU4qJfmBRiy7UQxh4XnQqTpeqnSX4FpRJ01Pti0ULn98w8EqiByUHz9mFaz3xvfJnXM4OnJGdcBJl6G6TXX1y/U31rWLw+XYewq00osaK/ehUmyeLIayUy4qD9LE0=" Oct 27 23:43:24 volumio go-librespot[16420]: time="2025-10-27T23:43:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:25 volumio go-librespot[16420]: time="2025-10-27T23:43:25Z" level=debug msg="completed keyexchange" Oct 27 23:43:25 volumio go-librespot[16420]: time="2025-10-27T23:43:25Z" level=debug msg="completed challenge" Oct 27 23:43:25 volumio go-librespot[16420]: time="2025-10-27T23:43:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:26 volumio volumio[15970]: info: BOOT COMPLETED Oct 27 23:43:26 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:26 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Oct 27 23:43:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:28 volumio go-librespot[16441]: go-librespot daemon starting... Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="app state loaded" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=info msg="zeroconf server listening on port 39875" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="obtained new client token: AAA/r37VgDVeCpl71bezmppbnm2sqrOCLKVS8wgM48/daf7IY1dZPnKYcIGhYpD+egFGUf5703mFb5cwwdiehU1cvH6AF+n4P4fJ0r+r1a3CYfhwQQmZiGg3+QGQDJUu3mx2tBebnMuDp4iynRy6+jv9O91NlU4IhL7PlwMD+MnBZOaPoYbyVEW1G7ipHC56tLeJcirmfHTCX7GrofTG0Zt/HCSvMd1Xx7fWtzB7+97Jrt0pvMqBA8k=" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="completed keyexchange" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=debug msg="completed challenge" Oct 27 23:43:28 volumio go-librespot[16442]: time="2025-10-27T23:43:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:29 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:29 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Oct 27 23:43:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:31 volumio go-librespot[16449]: go-librespot daemon starting... Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="app state loaded" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=info msg="zeroconf server listening on port 40115" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="obtained new client token: AADM4AhmbIfxLzMl2XrtHbw5K2R6ogFIKSzEhqsaa4sVNimhdae29Y2xhGt7XZAS+1fYnLHhPJB434gt1YsaO3MNXMjP4gvVxEudCYQXO34B9eWz2W7tV/xrwQ/Z3uYZlqY2ukayYY/Eij4KskumW3y88AfN/oc2Lr6xvYOjlhfFz78VnVvonU2yt0Qn2cEOuihMr6hDRM+q0jlE0eyJVbvfgr44akRcKZnjWTQ4U7PQ7BWZHQ3al9o=" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="completed keyexchange" Oct 27 23:43:31 volumio go-librespot[16450]: time="2025-10-27T23:43:31Z" level=debug msg="completed challenge" Oct 27 23:43:32 volumio go-librespot[16450]: time="2025-10-27T23:43:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:32 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:32 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Oct 27 23:43:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:35 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:35 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:35 volumio go-librespot[16461]: go-librespot daemon starting... Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="app state loaded" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=info msg="zeroconf server listening on port 45073" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="obtained new client token: AAAoLKYqN2uwobUOvdSLhRqZQqD3N84HARhQfPMyKwyMgIwYlezXrXoFXNCL8KdajYrBU/2NTWe7YI5rrTWD5NX/Nu/PzNm5nzvEdzv4o+jRfMOGwI6lgqlCBE/qUaDP+WIjLw6cNerWduntU+eAJnTC/mxHOMvSlyC2tL+/VrF1O/sHkMtLo/6937Q0c2btDwAj/hEQBKkz5J4lRm5v2dpHJFKKrWtp0pwq2s9g06NFwZhX/69Jwsc=" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="completed keyexchange" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=debug msg="completed challenge" Oct 27 23:43:35 volumio go-librespot[16462]: time="2025-10-27T23:43:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:38 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:38 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Oct 27 23:43:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:38 volumio go-librespot[16483]: go-librespot daemon starting... Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" level=debug msg="app state loaded" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" 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]" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" 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]" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" 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]" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" level=info msg="zeroconf server listening on port 38209" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" level=debug msg="obtained new client token: AACX1ZTz2nsgsAkoGR1BSYr+CzXioF1XV6EkHxt/0FeGV9FlCDKVzf2H49ixu6j1p8pRgYZaqTOy6msijkd9IZwnPhBAmi13AaPGex0JQPBYBW0cgEcyhp7FRliwIN/MnVHuWi9nrs2qXIThZe2Cmqm135qCuxWxWttAxZQnsI+jY6+1bKpI6EpLsUB745CTJmAM35ZxBZ1s1v6xb/ae/5d1keWCn4KHJ2UO2QZBwuKalTi4lbYboYI=" Oct 27 23:43:38 volumio go-librespot[16484]: time="2025-10-27T23:43:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:39 volumio go-librespot[16484]: time="2025-10-27T23:43:39Z" level=debug msg="completed keyexchange" Oct 27 23:43:39 volumio go-librespot[16484]: time="2025-10-27T23:43:39Z" level=debug msg="completed challenge" Oct 27 23:43:39 volumio go-librespot[16484]: time="2025-10-27T23:43:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:41 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:41 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113. Oct 27 23:43:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:42 volumio go-librespot[16491]: go-librespot daemon starting... Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=debug msg="app state loaded" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" 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]" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" 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]" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=info msg="zeroconf server listening on port 46063" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=debug msg="obtained new client token: AACSqyTNquHJhraNnboyA7E+BYg6hMqmIHzN4NEPG8x8N8sJCDQmNVb6wq8Zalq+ta6/Vkp0vs/Z7fCvxOaQcEMQKVSiofafzx0g3Qvr0Y0r3Pe6E+6hdJpteBQSEKOR/TydoHRsaVNGehq4SjEtngJ4feef+PIK+ukFntCFJEihXHakaXIw8z52AQxphXDkVAytLjSQSHuumgAmrVUD/8HuDk/UmseQQO2Kj21pvsf057J0NsIPeTk=" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=debug msg="completed keyexchange" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=debug msg="completed challenge" Oct 27 23:43:42 volumio go-librespot[16492]: time="2025-10-27T23:43:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:44 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:44 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114. Oct 27 23:43:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:45 volumio go-librespot[16499]: go-librespot daemon starting... Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" level=debug msg="app state loaded" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" 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]" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" 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]" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" 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]" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" level=info msg="zeroconf server listening on port 43295" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" level=debug msg="obtained new client token: AACxTvaeGNBnqRLIXlEVFx+bIcbX/ZXqosGpddXJfnwgRtfDq3RCWeadNsFLhNwgpiZmAg2eHvboW7CUlXP7J+1S7bAzoJlZt4mbPe5zK3D6kKL0iN+AwDFN9m4RFIvfOIkYwDxU3hSw+wZE0VKhwkqULgL+H5catURkEslQdi51GF0xuK5h1YVGRI2VdekDJdyZG0Z8V7etGerkJjZx8OULoo0pv3n7FT4c0rPAmhgv5RNUDAEncPI=" Oct 27 23:43:45 volumio go-librespot[16500]: time="2025-10-27T23:43:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:46 volumio go-librespot[16500]: time="2025-10-27T23:43:46Z" level=debug msg="completed keyexchange" Oct 27 23:43:46 volumio go-librespot[16500]: time="2025-10-27T23:43:46Z" level=debug msg="completed challenge" Oct 27 23:43:46 volumio go-librespot[16500]: time="2025-10-27T23:43:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:47 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:47 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 115. Oct 27 23:43:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:49 volumio go-librespot[16521]: go-librespot daemon starting... Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=debug msg="app state loaded" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" 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]" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" 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]" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=info msg="zeroconf server listening on port 33203" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=debug msg="obtained new client token: AAAYQOgjg6L2HoRSFJ6P/7LnyF5ZGNoFbIVZonXOeUwwLg2a/MEQ4j63AwmWsF2PVukA3DsopiNjIy8Emz1p81XGozbdeR2T2ClTodVT6/xqafje/Si/lkgG+RCxmvB+hxHVTOgS9hlA2aI8YrbxmUXCQCwst1xWmaxT4TfBpwQmKZVyXtXFJ8MWSCPU+Tlsr76lTowBJbtj50K/9dPVh/QVyNIUN23NYpqBzERlKxi2omQV6u0TejE=" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=debug msg="completed keyexchange" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=debug msg="completed challenge" Oct 27 23:43:49 volumio go-librespot[16522]: time="2025-10-27T23:43:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:50 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:50 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 116. Oct 27 23:43:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:52 volumio go-librespot[16529]: go-librespot daemon starting... Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" level=debug msg="app state loaded" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" 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]" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" 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]" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" 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]" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" level=info msg="zeroconf server listening on port 41665" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" level=debug msg="obtained new client token: AABaU1QGgk1TdVLhYMg/Mqg4YlHGW+27prAgBD/L2qtuO8nQtxMA0DSMo8XaxUgOdbuzhX84gwqxlc59Mkmq+xjlurntNlk74ZhbZhyiDM+PNi0si0eGxvyWSSC6KO07W43QKNE1QOw0n7vECSTZQYLafg8VK0hPGrH/KwDaHb43Ge+LERh1hbs3+W09wt+Q5/m6pvEltB3yKaItS2vGer9l6D5wG61akRJ35wP3OP9Ufvdp+Djqrdk=" Oct 27 23:43:52 volumio go-librespot[16530]: time="2025-10-27T23:43:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:53 volumio go-librespot[16530]: time="2025-10-27T23:43:53Z" level=debug msg="completed keyexchange" Oct 27 23:43:53 volumio go-librespot[16530]: time="2025-10-27T23:43:53Z" level=debug msg="completed challenge" Oct 27 23:43:53 volumio go-librespot[16530]: time="2025-10-27T23:43:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:53 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:53 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117. Oct 27 23:43:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:56 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:56 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:56 volumio go-librespot[16541]: go-librespot daemon starting... Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="app state loaded" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=info msg="zeroconf server listening on port 36207" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="obtained new client token: AABKB9DPxeDJ13bHxmPhhIzx2rAUWTcdKTwLXdwqqQYtbslbOxlcKZmlMRsRtK2jPhcRu4U8eK3eYBHlWnzAq1oW8b9Tni9PIgyMGS1WdN9/UreLV9V+4ECQD13pK5n5vS5szgw9yIpqr4wEHygVEGEwgDVZ1JvSGqOn/4ymvfBszAGA1pdAlBTky/W9iBpd9f6yucs39p0lYwuM9cEYJ/qWv6knnKbKgN8LLv0oPv35Sps5+Wnga4g=" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="completed keyexchange" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=debug msg="completed challenge" Oct 27 23:43:56 volumio go-librespot[16542]: time="2025-10-27T23:43:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:43:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:43:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:43:59 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:43:59 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:43:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 118. Oct 27 23:43:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:43:59 volumio go-librespot[16563]: go-librespot daemon starting... Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" level=debug msg="app state loaded" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" 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]" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" 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]" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" 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]" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" level=info msg="zeroconf server listening on port 38967" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" level=debug msg="obtained new client token: AAA8XUH3cAtjCiq+A3vXyaQqQhmHOyKeSLXVwCjqM7ZOt1eP+zFOjjrASl56tJD2r5/AJcem2bDtSrTWIL3lK2IJXpeBB0VnFQ5kqtvGhVdg9mTlpjdHYkG2jQhdZChkcuZsSTjh9hovxhpcREG8tSXlk/sr+vHs2GFUEZ9UoU5HlvuNUD/DV7l9DpRnK0nItOO2ql95aYXabjh/TkO/EBSeroEtN/Nwzr+e1Uo/VNkcUAvxn5PRzlg=" Oct 27 23:43:59 volumio go-librespot[16564]: time="2025-10-27T23:43:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:00 volumio go-librespot[16564]: time="2025-10-27T23:44:00Z" level=debug msg="completed keyexchange" Oct 27 23:44:00 volumio go-librespot[16564]: time="2025-10-27T23:44:00Z" level=debug msg="completed challenge" Oct 27 23:44:00 volumio go-librespot[16564]: time="2025-10-27T23:44:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:02 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:02 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 119. Oct 27 23:44:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:03 volumio go-librespot[16571]: go-librespot daemon starting... Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="app state loaded" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=info msg="zeroconf server listening on port 41187" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="obtained new client token: AABoLIIVJ3lqHHRy9CiDH3c2s7DuskekmXxmTRTUDxRTFrbo2K9rNVeKISxNO3A6O2HE7uvoQqh3lQr72OefOZoZNthtJnrWBikKfxo5Zfo4qJFK7TX/LUSsHt2XX23VYBtmRx3mWf4RzteLBW5tpiMqEiptE2avBKDhs0VfvyPCNOoftNOFfXI4/ieTwIcHJQBb5s1Y+FleNfZrt1y0TZXl3VD9A4kNqUSjTFt0zsipvFuuBHf2L3g=" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="completed keyexchange" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=debug msg="completed challenge" Oct 27 23:44:03 volumio go-librespot[16572]: time="2025-10-27T23:44:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:05 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:05 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 120. Oct 27 23:44:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:06 volumio go-librespot[16579]: go-librespot daemon starting... Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" level=debug msg="app state loaded" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" 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]" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" 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]" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" 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]" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" level=info msg="zeroconf server listening on port 40519" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" level=debug msg="obtained new client token: AABiLAvx9ehHfOJXmvDu85l9vLL4NWMN6uJ/L8qpKFH6UpmSIKbY38EHmRPKLBAEDE+SzfJYsy44eoQrAwx2jnahNfCB7JIb6EHgD+ckeAjpbe/fDaFwPtkp68mvs+CZ3Wh5DkhJEX8SF73scop/qS8oUXBPbFthUWEMujx4ifRULuXEGEqMnUmFAnKQVTkmYD6zGtN/Myt5zaMMtDdEB7L6bi6j67EZIpC1seS8yrJdyHvKiXk3hwc=" Oct 27 23:44:06 volumio go-librespot[16580]: time="2025-10-27T23:44:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:07 volumio go-librespot[16580]: time="2025-10-27T23:44:07Z" level=debug msg="completed keyexchange" Oct 27 23:44:07 volumio go-librespot[16580]: time="2025-10-27T23:44:07Z" level=debug msg="completed challenge" Oct 27 23:44:07 volumio go-librespot[16580]: time="2025-10-27T23:44:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:08 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:08 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 121. Oct 27 23:44:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:10 volumio go-librespot[16601]: go-librespot daemon starting... Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=debug msg="app state loaded" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" 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]" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" 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]" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" 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]" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=info msg="zeroconf server listening on port 44543" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=debug msg="obtained new client token: AACpF64Rtspw4VRq2omZ/Kht7H85cUA4uiHU99oPJyCOqJBjzaNw7rXRoIoXr12EDkFF+vrYnOiRPB+dAxLY8gaexXCchxE9ZgPYUbvbzR3kyGIG+hbUv2klMScD+yJCb6q2qVYNbgCMr8nStIMbUtntJPHbmK/AlJyDSMNwDHax+XaV2xRbMi6+B+dxaR591q8Rpr8ZuPZeVwFQmcSkPby+0kvHm+LYhQldFxOmzn1FAzKVO0lvOP4=" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=debug msg="completed keyexchange" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=debug msg="completed challenge" Oct 27 23:44:10 volumio go-librespot[16602]: time="2025-10-27T23:44:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:11 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:11 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122. Oct 27 23:44:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:13 volumio go-librespot[16612]: go-librespot daemon starting... Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=debug msg="app state loaded" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" 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]" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" 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]" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=info msg="zeroconf server listening on port 36471" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=debug msg="obtained new client token: AACZiGpNvSOUNCCajOR36rJpmyeb89bDZFLV5F02Z+DORzrnqm7dwUiRxfof6aY4hzqMtT/GrnXQOBn+FOyJmogEfu/rRb8eFzpdhGM+OVMeUN87h9R9pU4YXKFzc7GBPtihuCLv5pYVYVuyAnSz285GlPLC4Ck10Wii+WEj8HdurGZdQnjHLiStMigpiMPcPwfrZ4HWs1kN8ygEISgcsIdDiPUslOjyajSLnnnrmDU+2rEKOJVFwuw=" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=debug msg="completed keyexchange" Oct 27 23:44:13 volumio go-librespot[16613]: time="2025-10-27T23:44:13Z" level=debug msg="completed challenge" Oct 27 23:44:14 volumio go-librespot[16613]: time="2025-10-27T23:44:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:14 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:14 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123. Oct 27 23:44:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:17 volumio go-librespot[16621]: go-librespot daemon starting... Oct 27 23:44:17 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:17 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=debug msg="app state loaded" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" 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]" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" 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]" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=info msg="zeroconf server listening on port 33133" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=debug msg="obtained new client token: AAC0ABWEtvheKc2eT7LE0rW6Z7OmHV0Mjndwh/UrYA4wOQwRpuRhQX0lF4DjseBuGbR6sjT3LYoyLwPtIKbO/Q0vSKYn2DOKuk0g4nYVS9Er0EvJfr7EJV99z0LfsCCesnm43uxWlupWoLVht5wQkoVBLMxky95x/eiUB4XvbbDhv5z/PVJQQDjfddjKQo1wiIFVoZ+qglguh8LCRnWyWcuGwKKPkuBnGbpGW3l4Ccn6nTRXu+PSfgo=" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=debug msg="completed keyexchange" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=debug msg="completed challenge" Oct 27 23:44:17 volumio go-librespot[16622]: time="2025-10-27T23:44:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:20 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:20 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124. Oct 27 23:44:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:20 volumio go-librespot[16644]: go-librespot daemon starting... Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=debug msg="app state loaded" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=info msg="zeroconf server listening on port 33333" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=debug msg="obtained new client token: AAC7XV0aD5WZG70ieo5ajrI3gLsHRFWnX7s9rLUeKBp+Nexd+Dl5jvJx6oTdh9Asr95BXIsIUYa72/dYoG9wt7NeYkiaKp9oUerl9at1Bi3PUCJKeiQxkxyWv6slwlAlphCUE9OX4LUhQiJ0nL/ZzCydHzjYuyzeX/vZzE+U718O1+8uxzdsglUv0J4tEj4b/+wgpVxmVYUCvaOP3rzCG1Zh0GRd/mG1SccMif3sek/+wDrMVGxxD8c=" Oct 27 23:44:20 volumio go-librespot[16645]: time="2025-10-27T23:44:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:21 volumio go-librespot[16645]: time="2025-10-27T23:44:21Z" level=debug msg="completed keyexchange" Oct 27 23:44:21 volumio go-librespot[16645]: time="2025-10-27T23:44:21Z" level=debug msg="completed challenge" Oct 27 23:44:21 volumio go-librespot[16645]: time="2025-10-27T23:44:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:23 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:23 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 125. Oct 27 23:44:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:24 volumio go-librespot[16652]: go-librespot daemon starting... Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=debug msg="app state loaded" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" 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]" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" 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]" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=info msg="zeroconf server listening on port 41463" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=debug msg="obtained new client token: AAD/6FtANZqYPqPw4CkZaI/S1lIImxz8N4+BpG6lbUN9YHwv/fz8LkhgPdlA2uhnxN6Iz/bw92yZON84+bNs+R+Z9BYlshZtwRqfPoqK9dg1cZKCv8C48glCTUzTSC32aaqbQl9hnInBa69Rt+yT2VmBcUHoUtBqQkyWSu6eVQGsp2gjnW9SqIJXPbBYiwL1kJeqCmpe10Vc2x1XqCJhmm7zgaIoY4pgiEE2FGqKpTdVksAOpoe4Bso=" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=debug msg="completed keyexchange" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=debug msg="completed challenge" Oct 27 23:44:24 volumio go-librespot[16653]: time="2025-10-27T23:44:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:26 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:26 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 126. Oct 27 23:44:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:27 volumio go-librespot[16674]: go-librespot daemon starting... Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=debug msg="app state loaded" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" 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]" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" 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]" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=info msg="zeroconf server listening on port 36107" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=debug msg="obtained new client token: AADCFr7uSi/6gqtLkrsoytHY16qhlYP0NL0tum7mGad1HGe/gkWV99I6vZij/EYm8Urt21HJIoi1RIT+kVcSlJiGJEHlanSN3o5GNUSRJ508B71f3iUfEF8B6LMOGN2IeoSqrEaY4GaM0eL+9g3b44H0zqpMYftnPR9xHzVmYOAHLzUYNQR+LJzJp5jkR95jygxQAcCqIY+n7WgcoBCQw1t2Y9YvUh8eEnQM6jM0JemG7Gzimfe6CK0=" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=debug msg="completed keyexchange" Oct 27 23:44:27 volumio go-librespot[16675]: time="2025-10-27T23:44:27Z" level=debug msg="completed challenge" Oct 27 23:44:28 volumio go-librespot[16675]: time="2025-10-27T23:44:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:29 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:29 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 127. Oct 27 23:44:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:31 volumio go-librespot[16682]: go-librespot daemon starting... Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="app state loaded" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=info msg="zeroconf server listening on port 35769" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="obtained new client token: AAA9eG9fRyHQCXAEmCEowsbcoas2Sqi4mpP18hm8iX+EPPgGo3l0LDuPWlY/FPtnbECOrixDUwe8flZwPTTZiFwm/GfSmneQGolTfMgX2Cgg+/VQL53hzSlcg2MvE76roIFUP3mr5qIjZ/AuM8NDE0BRp8E4rE9eXOq9I5lNvE7ZRjZi1X/SNXOvZJoq8iW4ZBzAFo7E+FJFyHMCnEg41l8/SsGZN3zwv2t9H21R6Ky879pB2givsMo=" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="completed keyexchange" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=debug msg="completed challenge" Oct 27 23:44:31 volumio go-librespot[16683]: time="2025-10-27T23:44:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:32 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:32 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 128. Oct 27 23:44:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:34 volumio go-librespot[16693]: go-librespot daemon starting... Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=debug msg="app state loaded" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=info msg="zeroconf server listening on port 43773" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=debug msg="obtained new client token: AADQSYhO6d+RUvF79a/XGx9pGmA0SH/D/rmOSWB+vym5QGmsVdvwgPgrdz1pCShmVKbH7aCdepDsWzOhOW1yqXEXFiRBRbzFf/41V7stmXbrewrQK6OzS2Af63Xs01c/re2pKnEK8L+6mpcPokAqblKFiUivMcTA6toh/CnseFHZGUZzwAxY6TfjHZQcfh8pzzucVx1GkEYWSY05GHo8WfnEx8COEPGRpG6HY99jGmon0xPLGSOy9Jk=" Oct 27 23:44:34 volumio go-librespot[16694]: time="2025-10-27T23:44:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:35 volumio go-librespot[16694]: time="2025-10-27T23:44:35Z" level=debug msg="completed keyexchange" Oct 27 23:44:35 volumio go-librespot[16694]: time="2025-10-27T23:44:35Z" level=debug msg="completed challenge" Oct 27 23:44:35 volumio go-librespot[16694]: time="2025-10-27T23:44:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:35 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:35 volumio volumio[15970]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:38 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 27 23:44:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129. Oct 27 23:44:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 23:44:38 volumio go-librespot[16728]: go-librespot daemon starting... Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=info msg="running go-librespot 0.4.0" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=debug msg="app state loaded" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" 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]" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" 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]" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" 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]" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=info msg="zeroconf server listening on port 35417" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=debug msg="obtained new client token: AACAAhGssP+DV3mZytu61NrdOlYJgJWNH+7k7MHZev5d7U1TNMbNKMmeZYxv9GunuanVaTx/H9i4UV3gxHkI0Gm6Walvux1nx73/6LnIHbGbZf4QdLuED27J/BcKTBFuBH6tqhIrbzv69xxkokNE40d4uyQ6kqPIJXTfI2WuNmoiHGZR5EbMb9uFAoTuA/o2yN/C8vq+Enll8+YoFn/pFPvnS/pQDucKAfG0JuyPlfv/DpiZX90X" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=debug msg="completed keyexchange" Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=debug msg="completed challenge" Oct 27 23:44:38 volumio volumio[15970]: info: Initializing connection to go-librespot Websocket Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=debug msg="new websocket client" Oct 27 23:44:38 volumio volumio[15970]: info: Connection to go-librespot Websocket established Oct 27 23:44:38 volumio go-librespot[16729]: time="2025-10-27T23:44:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 23:44:38 volumio volumio[15970]: info: Connection to go-librespot Websocket closed Oct 27 23:44:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:44:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 23:44:38 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 27 23:44:38 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 27 23:44:41 volumio volumio[15970]: info: Getting Spotify volume Oct 27 23:44:41 volumio volumio[15970]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 23:44:41 volumio volumio[15970]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 23:44:41 volumio volumio[15970]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 27 23:44:41 volumio volumio[15970]: errno: -111, Oct 27 23:44:41 volumio volumio[15970]: code: 'ECONNREFUSED', Oct 27 23:44:41 volumio volumio[15970]: syscall: 'connect', Oct 27 23:44:41 volumio volumio[15970]: address: '127.0.0.1', Oct 27 23:44:41 volumio volumio[15970]: port: 9879, Oct 27 23:44:41 volumio volumio[15970]: response: undefined Oct 27 23:44:41 volumio volumio[15970]: } Oct 27 23:44:41 volumio volumio[15970]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 23:44:41 volumio sudo[16763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-27 23:43' Oct 27 23:44:41 volumio sudo[16763]: 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"