Oct 29 23:05:00 volumio sudo[16762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 29 23:05:00 volumio sudo[16762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 23:05:00 volumio sudo[16760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 29 23:05:00 volumio sudo[16760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 23:05:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 29 23:05:00 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 29 23:05:00 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 29 23:05:00 volumio sudo[16760]: pam_unix(sudo:session): session closed for user root Oct 29 23:05:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:00 volumio volumio[16438]: error: Cannot start Volumio Streaming Daemon Oct 29 23:05:00 volumio volumio[16438]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 29 23:05:00 volumio volumio[16438]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 29 23:05:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:00 volumio go-librespot[16765]: go-librespot daemon starting... Oct 29 23:05:00 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 29 23:05:00 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 29 23:05:00 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 29 23:05:00 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 29 23:05:00 volumio sudo[16762]: pam_unix(sudo:session): session closed for user root Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="app state loaded" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:00 volumio volumio[16438]: info: Remote SSH Stopped Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=info msg="zeroconf server listening on port 38457" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="obtained new client token: AAAVU9CLCyonJjhkU790wRGvzdY9ha3Dxr3fUBovAGVdKpoc3j0ZXOeGUQxNR+f5KZAzg4sI2VL/JGfiUIYsYbX9kNVJwjrHLkUobjPwfc3lbzVbNb9yXo39hrA49J5q9AHZV2SaA7fF9DwfMtbESu9Y6G1aQWhif/mi0+z9Wy10PN1hE3yUnDSWcUUdaNCSrxI1YQpKiVjk81dDBjsYnQn3So9n4VxEEZgfMuP85mtKCAfo0Pgs" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="completed keyexchange" Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05:00Z" level=debug msg="completed challenge" Oct 29 23:05:00 volumio volumio[16438]: info: Setting Geolocation for MyVolumio to eu3 Oct 29 23:05:00 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:00 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:00 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:00 volumio go-librespot[16766]: time="2025-10-29T23:05: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 29 23:05:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:00 volumio volumio[16438]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 29 23:05:01 volumio volumio[16438]: info: Updating MyVolumio device info Oct 29 23:05:01 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:01 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:01 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:01 volumio volumio[16438]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 29 23:05:02 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:02 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:02 volumio volumio[16438]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Oct 29 23:05:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 29 23:05:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:03 volumio go-librespot[16773]: go-librespot daemon starting... Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=debug msg="app state loaded" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05: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 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05: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 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05: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 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=info msg="zeroconf server listening on port 43383" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=debug msg="obtained new client token: AAALFsePAjgVngCbp3GkvGK38o/jYDBeB6QmFJAX1M50y5I0C1S47hXpAnPiPNiXg3o1m2KheQ44tqPt3pXOTsbcx9t7wIC575zTzHvHQmpr5dano8rfE5HhvQu6iRl6DBgRKU+Pj52AnEJfcFIkyv1zhkLOiFXmNztCNzf9be0l1woBrhru54x+K+kaxLie3OCmgjTHzX9hl67vZsQw8VUrRry+LQqonhRa3EZywtRLbE59ynKCNyU=" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=debug msg="completed keyexchange" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05:03Z" level=debug msg="completed challenge" Oct 29 23:05:03 volumio go-librespot[16774]: time="2025-10-29T23:05: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 29 23:05:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:04 volumio volumio[16438]: info: MYVOLUMIO: Adding device Oct 29 23:05:04 volumio volumio[16438]: info: MYVOLUMIO: Evaluating Server Oct 29 23:05:04 volumio volumio[16438]: info: Setting Geolocation for MyVolumio to eu3 Oct 29 23:05:04 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:04 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:04 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:05 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:05 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:05 volumio volumio[16438]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 29 23:05:05 volumio volumio[16438]: info: Updating MyVolumio device info Oct 29 23:05:05 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:05 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:05 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Oct 29 23:05:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:07 volumio go-librespot[16796]: go-librespot daemon starting... Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="app state loaded" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=info msg="zeroconf server listening on port 46317" Oct 29 23:05:07 volumio volumio[16438]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="obtained new client token: AADZumzgyReUmXbFgUpCn872ga+EAI31SDkaHGXxb0GuPdPpZsZs9R/P/G27KgWAdVR6meb/TQCZFMj0LTeVZi5or4yvsHcZ6PZYBsGrlBAY/f2bMGwLwT+3NqWTadPam6NlY/yvN+8zllAGq/O8he9+lhAyUE7j1C8M7GjGM8mD8MsyN0Q7JBlJcw30fVlwkU+TSfy+j5cB2iWIr+yMrKCgc+G3w0QJ/SDzXNky6lK2RLsEfHM4" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:07 volumio volumio[16438]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="completed keyexchange" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05:07Z" level=debug msg="completed challenge" Oct 29 23:05:07 volumio go-librespot[16797]: time="2025-10-29T23:05: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 29 23:05:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:08 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:08 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 29 23:05:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:10 volumio go-librespot[16819]: go-librespot daemon starting... Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=debug msg="app state loaded" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" 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 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" 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 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=info msg="zeroconf server listening on port 38437" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=debug msg="obtained new client token: AACwWjCiEXTuauCxocRrE0hBj4F4Fgl58m3Mm7yYYAToc503mZQGwReBWN6nTbf6iTFszFmYFJbfNaJ0uxsxmIpcVn+KB+58kywUU5m2acs6qgEmAQdY5cZvX3rbakukqsNVGK1f8j/UibwLjLtL1YLxvJeLnd21Ibj4eSn2MgLDEqCdA1T4nzQ4eaAJ/NHVKVd+bQkpvSo1OwV9hHxCwcwe8HN9cnkau+rOxqz6jXzO5Bjnf9sz9jE=" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=debug msg="completed keyexchange" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05:10Z" level=debug msg="completed challenge" Oct 29 23:05:10 volumio go-librespot[16820]: time="2025-10-29T23:05: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 29 23:05:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:11 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:11 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:13 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 23:05:13 volumio volumio[16438]: 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 29 23:05:13 volumio volumio[16438]: info: CoreCommandRouter::volumioGetState Oct 29 23:05:13 volumio volumio[16438]: info: CorePlayQueue::getTrack 0 Oct 29 23:05:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 29 23:05:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:14 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:14 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:14 volumio go-librespot[16832]: go-librespot daemon starting... Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="app state loaded" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=info msg="zeroconf server listening on port 43121" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="obtained new client token: AAAFa0tqM75nH7ATpDCbsWIU6MsBKl5tCXpLS83/E5fEUuLspaT1R86eWd3q1kT8ZVVXbkLGrUV0C2cUgYjWfQ/qtgUPLxc7nCe1QCEaBlzaSd2jSCivDXH0pRhnaFhWOA+JIo3HWRTZ7nkqWc2DWQe7LAPVgJykdyDGzCp35qSeJAvbIqtXqCZX5uC1aPvu4gPhkB2+ab28y4tLVJCTw38IO0vWzpTvYbeneb+CdYQbTd6UBsWo" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="completed keyexchange" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05:14Z" level=debug msg="completed challenge" Oct 29 23:05:14 volumio go-librespot[16833]: time="2025-10-29T23:05: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 29 23:05:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:17 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:17 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 29 23:05:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:17 volumio go-librespot[16854]: go-librespot daemon starting... Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=debug msg="app state loaded" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" 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 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" 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 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" 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 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=info msg="zeroconf server listening on port 43591" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=debug msg="obtained new client token: AAAjijxEn9F9jEyK6KsXtUB9xd5rVC1WuFpL+j2/n9v22MEBZ1YEpLVDyL70MrJ5la48sETDElKki1ICeXAlbO52ahquEH8y736BZYG76+GFZto28rmq8FEExDpJ7gexT47cmbiQem+qDNWLsTZ9rLiLDPVK4e+R3GDxFWE6wUCDEUJ4xdVOuQqq2leEUd8AtVZlZ7SYOyYuQ/wcsMHb9cJWSt6fjZY3EREi1NMlNcLyV/F/bNgpJik=" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=debug msg="completed keyexchange" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05:17Z" level=debug msg="completed challenge" Oct 29 23:05:17 volumio go-librespot[16855]: time="2025-10-29T23:05: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 29 23:05:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::volumioGetBrowseSources Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 29 23:05:18 volumio volumio[16438]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 29 23:05:18 volumio volumio[16438]: info: Completed starting MyVolumio Plugin Oct 29 23:05:18 volumio volumio[16438]: [Metrics] CommandRouter: 32s 82.31ms Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::volumiosetStartupVolume Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 23:05:18 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::Close All Modals sent Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::Close All Modals sent Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 23:05:19 volumio volumio-remote-updater[874]: Test mode disabled Oct 29 23:05:19 volumio volumio-remote-updater[874]: Alpha mode disabled Oct 29 23:05:19 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 29 23:05:19 volumio volumio[16438]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 29 23:05:19 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 23:05:20 volumio volumio[16438]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 29 23:05:20 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:20 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 29 23:05:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:21 volumio go-librespot[16872]: go-librespot daemon starting... Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="app state loaded" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=info msg="zeroconf server listening on port 41877" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="obtained new client token: AADiYLlunP5BnGb3UjgKVMH+d2Ru3HuBpjY3xi/QJc0AxblMcNGxclyqJgFbjzETyVaZiJj/bfsU+qCWGs6K0o6dRaqJkjYxqaUy4b1FMKv4nQ7KEXm1w2guchpRaFORb1asVELkLXSK10FsOWumM8eCdpv8ry9sqxLsfkVNyjUrHOGhdczY/YcMGAALyYe4ED4nPdA9HyT73kxPdS4PcZzuwGnSlLWMSfl3da2aZBZs1tP2OJhw" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="completed keyexchange" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05:21Z" level=debug msg="completed challenge" Oct 29 23:05:21 volumio go-librespot[16873]: time="2025-10-29T23:05: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 29 23:05:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:23 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:23 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 29 23:05:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:24 volumio go-librespot[16880]: go-librespot daemon starting... Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=debug msg="app state loaded" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05: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 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05: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 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05: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 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=info msg="zeroconf server listening on port 38213" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=debug msg="obtained new client token: AAAgs2XSHxpqsT56i7h3DNgWxCov7INDeQAO+R4TKJU/50aDy6jH82S1dF+bhwbRw0I8o6ITXhbYpXkW+YeHrss97vv1tjSqbvdzaFrFIZ52Z055ctHHvO6qWGLCFNXX+xzkqT7jyo8fwJKvRZ3Lf8PxdYJiWagCu+b0egZJY5LnEDBGlohguFhw3PFl3KoKgA27ymqjXCdRMhxMqzgK9Bdtu5Z25AOqwTS2Ia+KUOgbHaQg1te2Tr8=" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=debug msg="completed keyexchange" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05:24Z" level=debug msg="completed challenge" Oct 29 23:05:24 volumio go-librespot[16881]: time="2025-10-29T23:05: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 29 23:05:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:26 volumio volumio[16438]: info: BOOT COMPLETED Oct 29 23:05:26 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:26 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 29 23:05:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:28 volumio go-librespot[16902]: go-librespot daemon starting... Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="app state loaded" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=info msg="zeroconf server listening on port 36627" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="obtained new client token: AAAAuMmcjX2sKPSojExJy6wqKWdTEx3VhmPsTbAHHSUZYHNUdekTo3jwa1FgTsTRopoRKQYJsFpl7sBVkYe0g9aONjArwh9ppie4Zc0nIZhS1A+t0u2ubfay1Og7XjNXclwNcVrzyuM3G3FuYO06gOT5SJVKSiRXmTw5XQ099DgBLtYWNjxoUe05R5EJvzYPwQY/8/fN7EBI56M4Ueio1GDe6j3FvHBuO9uzmNErAtKpAcZyzVOD" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="completed keyexchange" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05:28Z" level=debug msg="completed challenge" Oct 29 23:05:28 volumio go-librespot[16903]: time="2025-10-29T23:05: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 29 23:05:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:29 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:29 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 29 23:05:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:31 volumio go-librespot[16910]: go-librespot daemon starting... Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=debug msg="app state loaded" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05: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-gae2.spotify.com:80]" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" 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 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" 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 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=info msg="zeroconf server listening on port 46081" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=debug msg="obtained new client token: AADjF6CfMtDMKelr3/aXRCirXYcije0+Blh+rXHhcz+xCYZnNRCYKEQz+QaSrcolS+lnWJUsVhM7M9zEaA8jmrzd0y+5onYHcbcbdbImR46B1zOowMON4ZRTPBqiOPfGJgzcHLiFKzzUHtNwtsp1MPqdoD5VxGrEcoCXRHaL4dnp9yKdX4OdWJounJC2PfI4w7xBO8NRzXlHmwos3RDrscO2IXCChegAAjHNe2zfvz7sTJ+NibcAS5g=" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=debug msg="completed keyexchange" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05:31Z" level=debug msg="completed challenge" Oct 29 23:05:31 volumio go-librespot[16911]: time="2025-10-29T23:05: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 29 23:05:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:32 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:32 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 29 23:05:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:35 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:35 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:35 volumio go-librespot[16922]: go-librespot daemon starting... Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=debug msg="app state loaded" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" 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 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" 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 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=info msg="zeroconf server listening on port 36523" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=debug msg="obtained new client token: AACZPFu2rCA+C+C9SCLIusHO4XN3+xau+WyoqDlPrHoza/zFN01vEWb9R6mnEePMYQZKgRxZGICudymPxU61Q4mg+nmA+488RIeZVY7uSe2EL7k4mGRNvTZ4hoVnQx4hWOb1JEe1dkRb2CtF8pNgZS1GfIZ5MOciGm/larVcrRwJMAJp+acAIvEiGzPeSpy/sOKkGGV3j9QVDf4EAtCznoLi6EHMrv/NTFY0a05prYtHdqHrssSg" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=debug msg="completed keyexchange" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05:35Z" level=debug msg="completed challenge" Oct 29 23:05:35 volumio go-librespot[16923]: time="2025-10-29T23:05: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 29 23:05:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:38 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:38 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 29 23:05:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:38 volumio go-librespot[16944]: go-librespot daemon starting... Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=debug msg="app state loaded" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" 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 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" 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 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=info msg="zeroconf server listening on port 33141" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=debug msg="obtained new client token: AADRjnuPe2JSfU22ur5qmfctoWYKuUOqpv8PPv+ScgB/80OofZnlnhakJTabHuZsgsw/tevg9nBSDHR5Ih+6Dcy/tlnsucqZnF8BE2FXoAUyzZKA3mhgDNuVq4KBJjch01C6dEiNmxxMWl44KLRNo7sXz7Umw5oUnkYruR5SBw+nEaZ+syLZvlaW51NCz3u7fAdECHUkZPlD23bbm1Tq332uVeE0OpOVfJqkQn9gyGFGiqM2lJTxc0I=" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=debug msg="completed keyexchange" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05:38Z" level=debug msg="completed challenge" Oct 29 23:05:38 volumio go-librespot[16945]: time="2025-10-29T23:05: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 29 23:05:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:41 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:41 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 29 23:05:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:42 volumio go-librespot[16952]: go-librespot daemon starting... Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="app state loaded" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=info msg="zeroconf server listening on port 33459" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="obtained new client token: AAB7J2ilEoaqWhwCwL9P1b28yxNForg79LFVuaesOdPQI1KsBDXb0QP7yZ5wPfzgyNNczJiqkG50Y9XVgvm3anTymlK/sfSXSLhcaoW04p4jgEHH9MgavW4ZtTacu4ia7jqcEtpyxOkB9bJ/JLAZ7EU5T5wNcWVRnQ+ptRwJShoKqN0hiB3zx93L3Kqda/QK1AB171rbYoo/9QKunfPoTl58EkGlcgcvZ+aV9nGcMVZsMNB4G5cs" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="completed keyexchange" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05:42Z" level=debug msg="completed challenge" Oct 29 23:05:42 volumio go-librespot[16953]: time="2025-10-29T23:05: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 29 23:05:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:44 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:44 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 29 23:05:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:45 volumio go-librespot[16962]: go-librespot daemon starting... Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=debug msg="app state loaded" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" 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 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" 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 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" 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 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=info msg="zeroconf server listening on port 45813" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=debug msg="obtained new client token: AACOBs0j94TRUGM53mtvyONsi8jQtXBUyP5ekeJTu/hfDmXH5V//OSjpvqwWclInmtcVvVpqbMohX9ri/Axddjr5Q2QlkkG81xDgDS4gcWXFBghpWszAeyxA9GbYoav0NbWiobL/DTYlaAj96MovgyIkK++LbgDNw/h6wMX60zRCwWQbCxITUtVRi6MWhnI1+cin6O14Q9+pBqvDxc+LVoaJ+ue6UTXQqlO/99xwFzA/XHg/B3prIlQ=" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=debug msg="completed keyexchange" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=debug msg="completed challenge" Oct 29 23:05:45 volumio go-librespot[16963]: time="2025-10-29T23:05:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:05:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:47 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:47 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 29 23:05:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:49 volumio go-librespot[16984]: go-librespot daemon starting... Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=debug msg="app state loaded" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05: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 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05: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 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05: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 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=info msg="zeroconf server listening on port 37569" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=debug msg="obtained new client token: AADOIc97VILsOJM+4AJ8O4h6PH+WnAdE+sEzPCDgy8laeENxUmm0RvKXOAYdJj1jLfg2oabxy8Oct9e28+U93mxGktgz9WRyY8AZWAs1LdkcFQvawQoTORONnfPTGMdgchcjx0om7CE5lUZpYbWphR1s+K5Fi6eJApCIJfc6dgK4iyDONrVDwhLS7zGYBJih5JPj7NKhkXI8cPNSzZg1GWZijE0Ybd/dSGTGmvtgr5fFkHBxcWgV" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=debug msg="completed keyexchange" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05:49Z" level=debug msg="completed challenge" Oct 29 23:05:49 volumio go-librespot[16985]: time="2025-10-29T23:05: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 29 23:05:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:50 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:50 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 29 23:05:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:52 volumio go-librespot[16992]: go-librespot daemon starting... Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=debug msg="app state loaded" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05: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-gew4.spotify.com:80]" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" 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 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" 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 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=info msg="zeroconf server listening on port 44647" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=debug msg="obtained new client token: AACPbmAx6l4+By7ihxBcgKdf0HzhTEcxhdxcG0G+1No8AD1Rjezd/r7dpZV+p3bdhsEKDhGjCC5t8clpeyntkO8S0N53uJl9aTq8GRTFKf+rShOm/UK60nMKel7fEij7VTIldl9lph/cHX7wZEZB4LyDLpzez48ynat5dmk+Ct20+ExDd2j7qz+Yfg3lRi2foTyh2QhqeURQ6giB8+ydCpWYj5gMlkiy5D/7AVae/eRSWt7J10VmSxg=" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=debug msg="completed keyexchange" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=debug msg="completed challenge" Oct 29 23:05:52 volumio go-librespot[16993]: time="2025-10-29T23:05:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:05:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:53 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:53 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 29 23:05:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:56 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:56 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:56 volumio go-librespot[17004]: go-librespot daemon starting... Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=debug msg="app state loaded" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05: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-gae2.spotify.com:80]" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" 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 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" 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 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=info msg="zeroconf server listening on port 35757" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=debug msg="obtained new client token: AAB5yx6OK7AfbEKwwrp8DPyDUNnxWTH1vE+Y9xz4WXGBGxyhMpyqKdD0SjKWnbeCqnh0u5sAH3wX+GL5ATRiDTZ7IaXWdI2cumq+Kwld0aChb17qFQfLakBzsLdyeqXT/OpG3b6n5xi7PRD/CDIrLWfT6KnUZdmTSI6+/3R/klVFW/TWPBTXQIQW7Ed0pTIP7CWXUlQZFWihWl9jUEZxvKll64d/S8boEqM9Z/sDrUck/kDLQqNF" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=debug msg="completed keyexchange" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05:56Z" level=debug msg="completed challenge" Oct 29 23:05:56 volumio go-librespot[17005]: time="2025-10-29T23:05: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 29 23:05:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:05:59 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:05:59 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:05:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 29 23:05:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:05:59 volumio go-librespot[17026]: go-librespot daemon starting... Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=debug msg="app state loaded" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05: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-gew4.spotify.com:80]" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" 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 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" 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 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=info msg="zeroconf server listening on port 43347" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=debug msg="obtained new client token: AABjGJoOUknxWiRN8zca9e2thVn6Y8E2KXE4vmA3h31DP27/arhUpZvJU94uxaLwq4X/3JrYIUPDZJAp9vKiWkWDT9ZwJhgE3JAmC/ixmme9Y3NUsBWkeAjd4yU9RDXJvhE+uXDbvZQKJHDUJpDzqPPWsPOS0s+fp7bkozjEHE0bpIQsBIpFCBW91tss/sJWxnAPCyqw8pzYPoBMlLtOzXvrowDhik3l8gALfjyVLPFxoC60nK+iRLQ=" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=debug msg="completed keyexchange" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=debug msg="completed challenge" Oct 29 23:05:59 volumio go-librespot[17027]: time="2025-10-29T23:05:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:05:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:05:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:02 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:02 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 29 23:06:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:03 volumio go-librespot[17035]: go-librespot daemon starting... Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=debug msg="app state loaded" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06: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-gew4.spotify.com:80]" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" 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 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" 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 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=info msg="zeroconf server listening on port 40869" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=debug msg="obtained new client token: AACXISE76BbDBUtdsUYTzyb/BcH8vPCzh6/4spfpDWFqnQfd4r4wnpFSDS27Re6jhnKXxYa77yhwcQf+1x0M54QJA33ewIFbpbhMWvLg/tOe1rh9D2JxeQ/rDgRJ+llPHLt68xXz/ixgiHLUH6PAktrxnvgGBLUuRZPtlcDiakBMdZGDzrRwRj0b5pdG2mgJamI9yIMWL6CsL8hQZiB7bZL48Lf4T0MJXpKYqxSFNA6OMnnQsG/V" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=debug msg="completed keyexchange" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06:03Z" level=debug msg="completed challenge" Oct 29 23:06:03 volumio go-librespot[17036]: time="2025-10-29T23:06: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 29 23:06:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:05 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:05 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 29 23:06:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:06 volumio go-librespot[17043]: go-librespot daemon starting... Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="app state loaded" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=info msg="zeroconf server listening on port 44347" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="obtained new client token: AAAc6lFzvcUf3Q8FjKYXVRcSt9GwsHGP/8dqmmS/r/RkpqEn5ux9hdrGmgQps+6qI8BhxhddUaACQQy5WDyq13V4iCbkiPpWdgcqbb1iSA4ZL+uBnNztwaa89BxMWezjk94gs6vLbhKBI5+U412Bd9rMiruaZMiiHxa6XKD+2waBw9y6H1rCrQEda3liiOY9B984vldhyJh8CbobCsdM+1ch82PGHGZtToCYpkoLIvmoYFiKKr1iDxc=" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="completed keyexchange" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=debug msg="completed challenge" Oct 29 23:06:06 volumio go-librespot[17044]: time="2025-10-29T23:06:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:06:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:08 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:08 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 29 23:06:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:10 volumio go-librespot[17065]: go-librespot daemon starting... Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=debug msg="app state loaded" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" 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 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" 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 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=info msg="zeroconf server listening on port 37275" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=debug msg="obtained new client token: AACyNs7mOi8DdaehhwPQSO4Qz+hLtDc2V6Cjd9N/iMLqPytJxnff5zF20RGHNergRqMH3ItvhUKLp0lTV+66/EVamfQgndK0g3SaYlTY/APgti/3JAFJeHezreSM0GzfBm1XApiiRrHRrfiBtrkSFmoVbzW4lXKdLoQ0Gvx+wXEIhpJ3KBhQ9SerHUDrEHVKffmTUVmbBk6b4CgPh0hS1PMZb950ro4jmOnkzgCAd4pF332vDwfc" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=debug msg="completed keyexchange" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06:10Z" level=debug msg="completed challenge" Oct 29 23:06:10 volumio go-librespot[17066]: time="2025-10-29T23:06: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 29 23:06:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:11 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:11 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Oct 29 23:06:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:13 volumio go-librespot[17076]: go-librespot daemon starting... Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="app state loaded" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=info msg="zeroconf server listening on port 38755" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="obtained new client token: AABUs7qjgWKqeAPfxxxUOrGmEdzHdm06LtPW57vhD/b8AbXyVGvODgokfI42IYJbXbzT7Rgux8eCX6Cndloruv4znoKhuuEDvCFonPGukAELn5zKnbDPDdFhkHLSoYAYhBjnClGiZdd17f0bQtHL7HQI0q5nucml/HEjJU0EdXN2EkwBfVkO3XCmZv8D39ewdV2xhiEm3QTUt0c/z/3Jn75ZhN8HtDgdqdY3z49qeTnM4AHDFZ0QesI=" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="completed keyexchange" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=debug msg="completed challenge" Oct 29 23:06:13 volumio go-librespot[17077]: time="2025-10-29T23:06:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:06:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:14 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:14 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Oct 29 23:06:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:17 volumio go-librespot[17084]: go-librespot daemon starting... Oct 29 23:06:17 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:17 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=debug msg="app state loaded" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06: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 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06: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 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06: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 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=info msg="zeroconf server listening on port 44369" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=debug msg="obtained new client token: AAAcFIS6NETMCaCGGWcRXh+Qd+p10V6Bqn0wDyelezrUmQLGxIIdV++/9z+lD5OL8IsAZTVENvFn9zMpPVfihnVCEFrFMj1z6WOIxWnCN+cO8K3sKicpKKBbyOR0orgG9QwPqXpJEReAIHHgx0OUhe44psX7o+hUWAaTLzhMwvJ/+FEiJZnAsxfMqZ+N1awkPoCxwDwYcnU2rzlylDfqqImGhD5XxmO5QrtzrhPD4s5cXsTp+s7M" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=debug msg="completed keyexchange" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06:17Z" level=debug msg="completed challenge" Oct 29 23:06:17 volumio go-librespot[17085]: time="2025-10-29T23:06: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 29 23:06:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:20 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:20 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Oct 29 23:06:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:20 volumio go-librespot[17106]: go-librespot daemon starting... Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=debug msg="app state loaded" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06: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 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06: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 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06: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 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=info msg="zeroconf server listening on port 45935" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=debug msg="obtained new client token: AACvAqssan4Dlq6lwCQ9XQyPgjsk8cxgxQgJUUsJTGWs5eWdfjsUYLS3S4Kge+i+1SZ3EFnNXAztu5d9ELysInf1HRh74tBaktkKjHRB6Dm6uVfIImdTFvk+O88CJa6VjJ12SRLlI2AC49baDsdVjZR+Qj+T3C4fNFZ0Ax3REFW6+hfQWatlyKQLySufcbxtECEkKL2wStjV7fCUtZysjVIKiJxrc2tsO+YfYcVSXnN/p1EaaJdjyEs=" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=debug msg="completed keyexchange" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=debug msg="completed challenge" Oct 29 23:06:20 volumio go-librespot[17107]: time="2025-10-29T23:06:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:06:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:23 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:23 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Oct 29 23:06:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:24 volumio go-librespot[17114]: go-librespot daemon starting... Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=debug msg="app state loaded" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06: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 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06: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 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06: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 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=info msg="zeroconf server listening on port 38695" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=debug msg="obtained new client token: AADDq6067W4KyNtlkzME30pywGQjkCrpsxD36rvQTRu8lCFioxy6U/v89Jqkn68wFqCXMB53utiQOd+8UxmTf48GrDC5jClZY9o97I/oR+cgeNIUuI7cmJfQaAvQcIPUauF8gHaWy1ndZXqJivtSpN4lDUhLrj/CmEw/aGsaUCtWEgLD3PDrWAfx5ADTSJUpkF/xnPfOm4NhK/IGZvZu9s40aNpqDy4Bu0r9lLbha8gSM+2VWQNL" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=debug msg="completed keyexchange" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06:24Z" level=debug msg="completed challenge" Oct 29 23:06:24 volumio go-librespot[17115]: time="2025-10-29T23:06: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 29 23:06:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:26 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:26 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Oct 29 23:06:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:27 volumio go-librespot[17136]: go-librespot daemon starting... Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="app state loaded" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=info msg="zeroconf server listening on port 34951" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="obtained new client token: AACT4W2t7zXX3pG7hEy7q6MTu410kXnmYNcWxc+QGmbjVpCNpip6XnNO3NZ8J5MMppnDNqhzWckcq1Rg8NIZ9ybwa27k+WSMYXSH/adJ9auxdv43Ls8r1xpSq3Bs+YCZze0KetFC6R5LEwTHvMxEcIAZjrM9bNHLLAdMOGjpftn4KmuhzT1eZes0P/vmF7epQT3ojokXDFeqXDPj41thl38taW3KJ+Dzi5oEts61b0/njBEcIixeWa8=" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="completed keyexchange" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=debug msg="completed challenge" Oct 29 23:06:27 volumio go-librespot[17137]: time="2025-10-29T23:06:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:06:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:29 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:29 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Oct 29 23:06:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:31 volumio go-librespot[17144]: go-librespot daemon starting... Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=debug msg="app state loaded" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06: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 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06: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 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06: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 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=info msg="zeroconf server listening on port 33957" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=debug msg="obtained new client token: AABl2kEIoQ+Rc+qtOGzO+Fh0edM7lRZiox5K8gdCgrF55hQi0lyg6z+fVRHTXp9L6LclcpfIXnjTUmYQCxRi1JS3G9mdpB6ulZ3AfvysEfKmuetSRIKPGj9Wc4Wd4SPC45YqgY+m7pDUR3iQRYjRmgX/pFSDAmlzzHFLBQh0gCkFEll2rC6Qtz7QwvioonBv2Xw4V1F4ZF3v4G+8LX+37hmenMpvAR+jMQQ2fodSuPixZl3etpM7" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=debug msg="completed keyexchange" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06:31Z" level=debug msg="completed challenge" Oct 29 23:06:31 volumio go-librespot[17145]: time="2025-10-29T23:06: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 29 23:06:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:32 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:32 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Oct 29 23:06:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:34 volumio go-librespot[17156]: go-librespot daemon starting... Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=debug msg="app state loaded" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06: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-gae2.spotify.com:80]" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" 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 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" 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 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=info msg="zeroconf server listening on port 39943" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=debug msg="obtained new client token: AAAYrYx07FC944sv4dT+SgIVeMtnixsPN995eb1mXCB4WN6FJ1NkpWuznqlCK//vDV87Z+GG5UZdJB/FBaK9XFTKnfUdQO61cN37OpQr7PpVUB4+6yN/Zd8/tFX65GBw9nQYQZLH9Fg2iQq39qljWU1NWtv96kWfl2kXqzFgmsSf1kzBA8xwFtem7bt6T6+NrMypGaIKilgJUsp7uwQQjX2iJWnKFi+NBXhbUOQO/fQpefboM3TbTA8=" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=debug msg="completed keyexchange" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=debug msg="completed challenge" Oct 29 23:06:34 volumio go-librespot[17157]: time="2025-10-29T23:06:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 23:06:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:35 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:35 volumio volumio[16438]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Oct 29 23:06:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 23:06:38 volumio go-librespot[17178]: go-librespot daemon starting... Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=info msg="running go-librespot 0.4.0" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="app state loaded" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 23:06:38 volumio volumio[16438]: info: Initializing connection to go-librespot Websocket Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="new websocket client" Oct 29 23:06:38 volumio volumio[16438]: info: Connection to go-librespot Websocket established Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=info msg="zeroconf server listening on port 35499" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="obtained new client token: AADZxjaCZurQOJtohtfbpVpX+1M9ox05+WHsPUH4m8o+6V5dq/OwTnqNkwXWgXPhWNB/CN10HNTb8BepbUqBr6C1SMnI7P7j2YhinfneDMfYOx3FnT1bVMd8wt45VTaHOvlXd1OrnJaDRfY/EMeGw9KPHnZE8G1rwcF6X9K9OrHcvDhKrCsjZu9CmaChMWZWL6jZuy6gFD3V+uo0p3hSkbEUZ0hGk6/gCmP6mKGtXIwkf0+asAtl" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="completed keyexchange" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06:38Z" level=debug msg="completed challenge" Oct 29 23:06:38 volumio go-librespot[17179]: time="2025-10-29T23:06: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 29 23:06:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:06:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 23:06:38 volumio volumio[16438]: info: Connection to go-librespot Websocket closed Oct 29 23:06:41 volumio volumio[16438]: info: Getting Spotify volume Oct 29 23:06:41 volumio volumio[16438]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 23:06:41 volumio volumio[16438]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 23:06:41 volumio volumio[16438]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 29 23:06:41 volumio volumio[16438]: errno: -111, Oct 29 23:06:41 volumio volumio[16438]: code: 'ECONNREFUSED', Oct 29 23:06:41 volumio volumio[16438]: syscall: 'connect', Oct 29 23:06:41 volumio volumio[16438]: address: '127.0.0.1', Oct 29 23:06:41 volumio volumio[16438]: port: 9879, Oct 29 23:06:41 volumio volumio[16438]: response: undefined Oct 29 23:06:41 volumio volumio[16438]: } Oct 29 23:06:41 volumio volumio[16438]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 23:06:41 volumio sudo[17201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-29 23:05' Oct 29 23:06:41 volumio sudo[17201]: 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"