Oct 30 09:10:00 volumio volumio[15172]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 30 09:10:00 volumio volumio[15172]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 30 09:10:00 volumio volumio[15172]: info: Streaming services startup Oct 30 09:10:00 volumio volumio[15172]: info: Starting Streaming Daemon Oct 30 09:10:00 volumio sudo[15468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 30 09:10:00 volumio sudo[15468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 30 09:10:00 volumio volumio[15172]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 30 09:10:00 volumio sudo[15468]: pam_unix(sudo:session): session closed for user root Oct 30 09:10:00 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:00 volumio volumio[15172]: error: Cannot start Volumio Streaming Daemon Oct 30 09:10:00 volumio volumio[15172]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 30 09:10:00 volumio volumio[15172]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 30 09:10:00 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:00 volumio volumio[15172]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 30 09:10:01 volumio volumio[15172]: info: MyVolumio token set successfully Oct 30 09:10:01 volumio volumio[15172]: info: MYVOLUMIO: Adding device Oct 30 09:10:01 volumio volumio[15172]: info: MYVOLUMIO: Evaluating Server Oct 30 09:10:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 530. Oct 30 09:10:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:01 volumio go-librespot[15480]: go-librespot daemon starting... Oct 30 09:10:01 volumio volumio[15172]: info: MyVolumio status changed Oct 30 09:10:01 volumio volumio[15172]: info: Streaming services startup Oct 30 09:10:01 volumio volumio[15172]: info: Starting Streaming Daemon Oct 30 09:10:01 volumio volumio[15172]: info: Removing browser output: myVolumio user plan is not superstar Oct 30 09:10:01 volumio volumio[15172]: info: Removing audio output: Oct 30 09:10:01 volumio volumio[15172]: info: Stoppping Tunnel 1 Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=debug msg="app state loaded" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:01 volumio sudo[15497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 30 09:10:01 volumio sudo[15497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 30 09:10:01 volumio sudo[15501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 30 09:10:01 volumio sudo[15501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 30 09:10:01 volumio sudo[15497]: pam_unix(sudo:session): session closed for user root Oct 30 09:10:01 volumio volumio[15172]: error: Cannot start Volumio Streaming Daemon Oct 30 09:10:01 volumio volumio[15172]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 30 09:10:01 volumio volumio[15172]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 30 09:10:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 30 09:10:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 30 09:10:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 30 09:10:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 30 09:10:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 30 09:10:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 30 09:10:01 volumio sudo[15501]: pam_unix(sudo:session): session closed for user root Oct 30 09:10:01 volumio volumio[15172]: info: Remote SSH Stopped Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" 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 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" 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 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=info msg="zeroconf server listening on port 40877" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=debug msg="obtained new client token: AADhEOV+IUKH66xLqFn2oFg5xFISnt7R/k9ZQeimua+D+llgrpY5VgAvEi/ImjtEJN9Zz/86D7ujS9c+D6w78Dr3er8XySkKEbG/tPLbSNFDiT6UEze+A5SfF7lQfcQmQ0zNqbd3CE7P8+hsG18z5cmufCjQ6N5/krUn2ZeXUbRdxJm2JJt4ReYpFvvFxxFlsC/TQz90OOvKKpOoft49stbpVOzXok4UsL5Wn3zqTndVsy9tBbRWu78=" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=debug msg="completed keyexchange" Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=debug msg="completed challenge" Oct 30 09:10:01 volumio volumio[15172]: info: Setting Geolocation for MyVolumio to eu3 Oct 30 09:10:01 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:01 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:01 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:01 volumio go-librespot[15493]: time="2025-10-30T09:10:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:02 volumio volumio[15172]: info: Updating MyVolumio device info Oct 30 09:10:02 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:02 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:02 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:02 volumio volumio[15172]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 30 09:10:03 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:03 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:03 volumio volumio[15172]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 30 09:10:03 volumio volumio[15172]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Oct 30 09:10:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 531. Oct 30 09:10:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:04 volumio go-librespot[15506]: go-librespot daemon starting... Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=debug msg="app state loaded" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=info msg="zeroconf server listening on port 34475" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=debug msg="obtained new client token: AADUPJQygcZYUnIBqSpVwylmixmpQ753/QxNY+E1OZnw9r7Eas0HIFB9RWhWKEBzY7cLLSDf9rZ4NhE6NL76kJPJbaHyNmdWbGbqoccXo6/4XwEzRbryHXOvcxmS49grn/+cCZnRm4ghYv9QHCpw/WPpMDd3fFypwt7+nOH3ahb1VRKGiekzHu+73MhOHpi+t1zMUSg4YAY1yp165eM2JpEuPfa9kacvfiIbD5fe54U/+GlhAkvND08=" Oct 30 09:10:04 volumio go-librespot[15507]: time="2025-10-30T09:10:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:05 volumio go-librespot[15507]: time="2025-10-30T09:10:05Z" level=debug msg="completed keyexchange" Oct 30 09:10:05 volumio go-librespot[15507]: time="2025-10-30T09:10:05Z" level=debug msg="completed challenge" Oct 30 09:10:05 volumio go-librespot[15507]: time="2025-10-30T09:10:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:06 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:06 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:06 volumio volumio[15172]: info: MYVOLUMIO: Adding device Oct 30 09:10:06 volumio volumio[15172]: info: MYVOLUMIO: Evaluating Server Oct 30 09:10:07 volumio volumio[15172]: info: Setting Geolocation for MyVolumio to eu3 Oct 30 09:10:07 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:07 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:07 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:07 volumio volumio[15172]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 30 09:10:08 volumio volumio[15172]: info: Updating MyVolumio device info Oct 30 09:10:08 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:08 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:08 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 532. Oct 30 09:10:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:08 volumio go-librespot[15529]: go-librespot daemon starting... Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="app state loaded" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:08 volumio volumio[15172]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=info msg="zeroconf server listening on port 39147" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="obtained new client token: AAC2iThImnGFVshymUdy6g7u1Zwems4niufvjKbnAP6qTILfIz9JaZGFxdSHQPNHpwe5PZYx04ADrovqXeM8rdEeetmStsPbcjjWLA/oVPcmjksYQfXn1LGvUwG0d9E/5NOzJbDmjvCxsEcX7MT/GDHwmODYQNjT0AnoJ6AiaJuazrUIKjVZD/HuZPrdbnRLfoYKwhaoXe8Q51h5p/j1dBLe+LjE3vSkN/ptMu+EJbrOF7F3N3E/K/A=" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="completed keyexchange" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=debug msg="completed challenge" Oct 30 09:10:08 volumio go-librespot[15530]: time="2025-10-30T09:10:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:08 volumio volumio[15172]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 30 09:10:09 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:09 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 533. Oct 30 09:10:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:11 volumio go-librespot[15552]: go-librespot daemon starting... Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=debug msg="app state loaded" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=info msg="zeroconf server listening on port 36053" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=debug msg="obtained new client token: AACVjSIVN6p6QZvK6M0zLH7YA4voCm1fvAbTvk/CduzJurIVRCXNIbsKNFNTH7SQ320XZRoaj6ZtrrMAV3EjUmSxFGV6lfMdHmbemgg+3Mpah/nxjDGLmEaUrDk0qa4S04Dk7KALQMFyXPA1+A44H7/nAt1qnKDG0N5rDBM0KMP36mlVyjUiTuScd23q7lucO/CatXiauVYcPjW1B7dSh/iD7JGB7/nV121g1fNRN4MusOmxrNQjoME=" Oct 30 09:10:11 volumio go-librespot[15553]: time="2025-10-30T09:10:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:12 volumio go-librespot[15553]: time="2025-10-30T09:10:12Z" level=debug msg="completed keyexchange" Oct 30 09:10:12 volumio go-librespot[15553]: time="2025-10-30T09:10:12Z" level=debug msg="completed challenge" Oct 30 09:10:12 volumio go-librespot[15553]: time="2025-10-30T09:10:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:12 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:12 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:14 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 09:10:14 volumio volumio[15172]: 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 30 09:10:14 volumio volumio[15172]: info: CoreCommandRouter::volumioGetState Oct 30 09:10:14 volumio volumio[15172]: info: CorePlayQueue::getTrack 0 Oct 30 09:10:15 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:15 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 534. Oct 30 09:10:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:15 volumio go-librespot[15564]: go-librespot daemon starting... Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=debug msg="app state loaded" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" 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 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" 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 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=info msg="zeroconf server listening on port 33635" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=debug msg="obtained new client token: AAAmN3d4s+7prGv1xZVrCUPgTPvTC6q6FUEJ/jXI/A8cWAu80zI7zmnWU8R/mm6Rzf3cP9cUnvQTz/T6vntNroHXCuJkfEJMLBUu3EBTF9tRvCnU9Okb1nrMqQSW+Qcz5FD+2QyPY3OXCgWkGMwyUrOTRBYdsRa8kDdK4Ehq62NRQT7FetB37pF4+JKuxoxs1DqmkzPlfL08b6qjG9z+B2Bm0Wb2fWDlPRVtyY24gPd1HVb+FGvkT/E=" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=debug msg="completed keyexchange" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=debug msg="completed challenge" Oct 30 09:10:15 volumio go-librespot[15565]: time="2025-10-30T09:10:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:18 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:18 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 535. Oct 30 09:10:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:18 volumio go-librespot[15586]: go-librespot daemon starting... Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=debug msg="app state loaded" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=info msg="zeroconf server listening on port 42903" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=debug msg="obtained new client token: AABn+ObaRcZMYmDGqoPz+B6mu6oLJt2RPQ+AOjzjLJCKW79gIBuZAknTjUleLZyQAFWkIFGAgKH3+vZO9MXrdJFBTCJMNgXJgrJK9WmP+tUtmDXGrA7eDEpkC8Rkljb2I5sSIM5DarA541rlZX256AW8AuICAje2lgSqlg1qsrC3BZgzJ5UffgVCQVEKP6yya++TXXM/GxOiFbsiQUnmDsi3u/eiw9UhlgVWlSHE41Ug+kr61wCb7R4=" Oct 30 09:10:18 volumio go-librespot[15587]: time="2025-10-30T09:10:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:19 volumio go-librespot[15587]: time="2025-10-30T09:10:19Z" level=debug msg="completed keyexchange" Oct 30 09:10:19 volumio go-librespot[15587]: time="2025-10-30T09:10:19Z" level=debug msg="completed challenge" Oct 30 09:10:19 volumio go-librespot[15587]: time="2025-10-30T09:10:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:19 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 09:10:19 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 09:10:19 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 30 09:10:19 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 30 09:10:19 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 30 09:10:19 volumio volumio[15172]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 09:10:19 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 09:10:20 volumio volumio[15172]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 30 09:10:20 volumio volumio[15172]: info: Completed starting MyVolumio Plugin Oct 30 09:10:20 volumio volumio[15172]: [Metrics] CommandRouter: 32s 17.73ms Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::volumiosetStartupVolume Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::Close All Modals sent Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::Close All Modals sent Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 09:10:20 volumio volumio-remote-updater[874]: Test mode disabled Oct 30 09:10:20 volumio volumio-remote-updater[874]: Alpha mode disabled Oct 30 09:10:20 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 30 09:10:20 volumio volumio[15172]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 30 09:10:20 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 30 09:10:21 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 30 09:10:21 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 09:10:21 volumio volumio[15172]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 30 09:10:21 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:21 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 536. Oct 30 09:10:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:22 volumio go-librespot[15603]: go-librespot daemon starting... Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=debug msg="app state loaded" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" 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 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" 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 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=info msg="zeroconf server listening on port 38889" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=debug msg="obtained new client token: AAD8rm8mnvb+ykzX9xBRfJzVDKgy4wsNCNdSVSi3hvTWX02l/V4zu6eYh8DUO9gDimlT0hnMSYcjG+2qeeLDLyIem9Od0yETwrIFwaAU6h+xW3Zqc2fWegj4tMrgamzMlEGJS0WTG340hs/3gatt/SaqGL02sjTYC0F/OzbABESoUF3134BN2QZd4f9qM/zQCyJipELnOLKbRJ7bY4OFrtXCL4txmsFvxc7Dqr4RrtEPM2gTOK7IgeU=" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=debug msg="completed keyexchange" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=debug msg="completed challenge" Oct 30 09:10:22 volumio go-librespot[15604]: time="2025-10-30T09:10:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:24 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:24 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 537. Oct 30 09:10:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:25 volumio go-librespot[15612]: go-librespot daemon starting... Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="app state loaded" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=info msg="zeroconf server listening on port 34551" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="obtained new client token: AADKbWykEL9bJw//dMjjuHbfUK43BI5YLdhIKoiPCztepvzDUwHOPjukhxAjEuBk28exclcNqmk5rhVCUDwcoSTNjwXmbERIbwMSfB8pLV28O+G+HqpPQvowJDWy5gDaA5AWQvzgXVdwSDnPinxf7MxXf2pOCjsWki0LIefxlTsji/FhPFn/YMsbvOFfh2JTUPFEW9qBEXryWyv/dRwIdbWy3ICmO/gVBmHAtKH7gMkdglz0NLXVxpk=" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="completed keyexchange" Oct 30 09:10:25 volumio go-librespot[15613]: time="2025-10-30T09:10:25Z" level=debug msg="completed challenge" Oct 30 09:10:26 volumio go-librespot[15613]: time="2025-10-30T09:10:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:27 volumio volumio[15172]: info: BOOT COMPLETED Oct 30 09:10:27 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:27 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 538. Oct 30 09:10:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:29 volumio go-librespot[15634]: go-librespot daemon starting... Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="app state loaded" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=info msg="zeroconf server listening on port 40107" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="obtained new client token: AACGqm6kx6NhcN+apqDCY505sbcVMeT78YHaeZoZVdvaVMcj7/dzYcSfSPpDPElzXh2ubugOOXmUYLMNTbGTI0ewIeazpkfyZNagJrXJZHQoQux3JIxbmBvnOubB+vxQxsNSRzsne+vkAbhyvyMQHcSg6QohlgQHAZl3qQzqZI2P0MBAZfd3W+qqFlhsX8ODHpJXLo+wl/w52yaY9YOmWFRxSh9MVNadSbX7g2FSrPl2hztHvqXwSDY=" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="completed keyexchange" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=debug msg="completed challenge" Oct 30 09:10:29 volumio go-librespot[15635]: time="2025-10-30T09:10:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:30 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:30 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 539. Oct 30 09:10:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:32 volumio go-librespot[15643]: go-librespot daemon starting... Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=debug msg="app state loaded" Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=info msg="zeroconf server listening on port 41981" Oct 30 09:10:32 volumio go-librespot[15644]: time="2025-10-30T09:10:32Z" level=debug msg="obtained new client token: AAAU+BlJV1fMheQK0Oh2wCAGM4GnvbdEh8unbQKEYOp3OhkR4vRzmJG9SltXH2pSCb6a8TQs6rLXEbTXQMrvhhMXuxnzBy/3L6qLV2cR5U1zMwdNaAgDLjBmf5bfPn2LDS9RWs2z+MaQ2oxO2Mgm5Txg9iCtqE0OknLsc+QcH4vo8AanqNg2lSsv20WyEF/3JiBimTTZDSdwZ8KVJCJu12UCQ2IXaN1kAMvmDlTsEIFNbJN3/PWZRp4=" Oct 30 09:10:33 volumio go-librespot[15644]: time="2025-10-30T09:10:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:33 volumio go-librespot[15644]: time="2025-10-30T09:10:33Z" level=debug msg="completed keyexchange" Oct 30 09:10:33 volumio go-librespot[15644]: time="2025-10-30T09:10:33Z" level=debug msg="completed challenge" Oct 30 09:10:33 volumio go-librespot[15644]: time="2025-10-30T09:10:33Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:33 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:33 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:36 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:36 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 540. Oct 30 09:10:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:36 volumio go-librespot[15654]: go-librespot daemon starting... Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=debug msg="app state loaded" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" 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 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" 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 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" 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 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=info msg="zeroconf server listening on port 42083" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=debug msg="obtained new client token: AADaVLAwgykuhpc0G+IDNWFQ1HUceXCyxLVc0XYODsdQXx7glp9zXllts2xbduQycomvG6ATU+0phQqN1hIevr8ZeWGE3FEqRH7LsOEMvCQAsuRN4EvaYo5KjPKUNXh4KE6I2LcbVVvcPdZPYwO91dQxYdIIz5boa/9Ua5mVR4nTsx2itwkoDGUPcQIwfXxtDtHdDBcWWc3zzrJMrOEsx+CZDsDXip/c/2qG54VGLb6pduDvVOzi6FU=" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=debug msg="completed keyexchange" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=debug msg="completed challenge" Oct 30 09:10:36 volumio go-librespot[15655]: time="2025-10-30T09:10:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:39 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:39 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 541. Oct 30 09:10:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:39 volumio go-librespot[15676]: go-librespot daemon starting... Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="app state loaded" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=info msg="zeroconf server listening on port 36061" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="obtained new client token: AAB1Y7kn7bC1EN81zotZaOHOBA+n9sox7JH0RsMhHSwEtcUBKAP0BFD992F1mrd6zifSldvCZoDSxposVhDrRyS10upxEP978GtgUgGyGugxbMt1rAfxSCkeV6bLW1agU5tAptD3KPUZkjBNUMnWR53JO65Qn/3Cz2l5aSSALpN58W9HzP/Z+wRN71OiT2xx/IU9YL1QIx6RlEktUgjRngw9lq9fqBT6x/gkWKngTHsuuFjXJdlHwEU=" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="completed keyexchange" Oct 30 09:10:39 volumio go-librespot[15677]: time="2025-10-30T09:10:39Z" level=debug msg="completed challenge" Oct 30 09:10:40 volumio go-librespot[15677]: time="2025-10-30T09:10:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:42 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:42 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 542. Oct 30 09:10:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:43 volumio go-librespot[15684]: go-librespot daemon starting... Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=debug msg="app state loaded" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" 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 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" 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 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=info msg="zeroconf server listening on port 45475" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=debug msg="obtained new client token: AADK5Sagh8eSzrN3uaUYpbH4PKxQvd6111mKDUCSqOttjyzNPV941+PTzrTwzQZOP7ZLvbogdPt+R12l+0j3CyMuzKijBZ3F/QOaZxCPHUOYNAxnUYP7Kb1r+079o55d7dd27K3EGsz9gdcKqE9UfZRCHQ4GXBVXyybEgPcB+NdZQxVjR8+cE0e48ZGLCjZciF1iI5VBGwKS3NPxZnhIXwi+JbkC2DwBMXZNvdCj2FsLShqApveD+Ac=" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=debug msg="completed keyexchange" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=debug msg="completed challenge" Oct 30 09:10:43 volumio go-librespot[15685]: time="2025-10-30T09:10:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:45 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:45 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 543. Oct 30 09:10:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:46 volumio go-librespot[15692]: go-librespot daemon starting... Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=debug msg="app state loaded" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=info msg="zeroconf server listening on port 37233" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=debug msg="obtained new client token: AACm/FJmvth6bTKXvxRf8EjXCw5WnH+9J3A2itP8h+9NsPlTyNwqbDPwkxbuukQn7hw6L+MgAvhbJR21+dDqluUYhZQgljwOQjdv8gQa0W/1A8JRDXNiIscFjS+6yczrJgHlYewEBpU0Inb7YON3tHccFUDWLaRE9pxW2CMR/9VMvuWjkkzOn4K4i9Yf67AyevgD5RrM81MEZ7cEhI2RJJUDa2zLi77Jue/l5MXvtcKwtBwiVMU7YGk=" Oct 30 09:10:46 volumio go-librespot[15693]: time="2025-10-30T09:10:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:47 volumio go-librespot[15693]: time="2025-10-30T09:10:47Z" level=debug msg="completed keyexchange" Oct 30 09:10:47 volumio go-librespot[15693]: time="2025-10-30T09:10:47Z" level=debug msg="completed challenge" Oct 30 09:10:47 volumio go-librespot[15693]: time="2025-10-30T09:10:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:48 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:48 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 544. Oct 30 09:10:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:50 volumio go-librespot[15714]: go-librespot daemon starting... Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=debug msg="app state loaded" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" 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 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" 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 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=info msg="zeroconf server listening on port 40337" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=debug msg="obtained new client token: AADxzWXxv5RrZAQB+UpqXV6tx6xRptFydaEpn043Orc05mZyuEIIDsuIkBXuuUAYJfG+bZmVg9ZejoFwJdGVO04OvnH51hZQO0c0lVrYUwzDsu17qEEVrFY4GJpWxC5ZGGP5/4Vb18iLzkX4QRlq540KeNe8BJlzE8vWwJE5YHAFNhrlXQpF5rQw3UVI4wT8FR3ZcKGkVWSccOp7HTNlTM1KJTSYS1EVs286Dl2Q3+2eTJ4rL6F1+w8=" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=debug msg="completed keyexchange" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=debug msg="completed challenge" Oct 30 09:10:50 volumio go-librespot[15715]: time="2025-10-30T09:10:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:51 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:51 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 545. Oct 30 09:10:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:53 volumio go-librespot[15722]: go-librespot daemon starting... Oct 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" level=debug msg="app state loaded" Oct 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" 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 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" 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 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" level=info msg="zeroconf server listening on port 38519" Oct 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" level=debug msg="obtained new client token: AADYYkr98Mub/Cqetc1IA2HfMMo0EvmUcQT14QdtjVK+YvtMJkosQ7oqz1gt3wSLZeFZMl/n8yOcz36ISvL97k+icTiuS6tDU22CTXgnm9aI4lDvD7/DIc9XKas3RZv6pEsw17Kk3ftafefMd/ikxEYLzYX9G2IFtTtVFxcbIRASCY6OE6EGmrEuOtrJtHFVO+OKwb/A3JaSt599kwx1BDqgSmerycJmezzhf9rgFB7gc0cyG052wPU=" Oct 30 09:10:53 volumio go-librespot[15723]: time="2025-10-30T09:10:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:54 volumio go-librespot[15723]: time="2025-10-30T09:10:54Z" level=debug msg="completed keyexchange" Oct 30 09:10:54 volumio go-librespot[15723]: time="2025-10-30T09:10:54Z" level=debug msg="completed challenge" Oct 30 09:10:54 volumio go-librespot[15723]: time="2025-10-30T09:10:54Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:10:54 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:54 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:57 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:10:57 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:10:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 546. Oct 30 09:10:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:10:57 volumio go-librespot[15734]: go-librespot daemon starting... Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=debug msg="app state loaded" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" 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 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" 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 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" 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 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=info msg="zeroconf server listening on port 38671" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=debug msg="obtained new client token: AAD1ADBp+IJMNxhP38Ut2a7UoKZUJ07jcG84PMe5UCBMq4IGAHFzCKCIEoBEfssHkpmL1kFilT82KszX+K4o/TktIys89fFxIC3sqR3LQuJGF7GyyA0YMkR6nCNWAhAQ4wL/KpvXWhEIDkfHQA2UTyCppnIC0TUqaJqxXHBDCSe5aU9FB5oTRuhHmNXML3FIq8FwZQrQlQ/yplS+hii5F/BSDTIJmTeSDBAX2U2koVSBoZKKRigmXSQ=" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=debug msg="completed keyexchange" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=debug msg="completed challenge" Oct 30 09:10:57 volumio go-librespot[15735]: time="2025-10-30T09:10:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:10:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:10:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:11:00 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:11:00 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:11:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 547. Oct 30 09:11:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:00 volumio go-librespot[15756]: go-librespot daemon starting... Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="app state loaded" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=info msg="zeroconf server listening on port 42035" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="obtained new client token: AADVcFKGwXKlYJtJ68WO/QC2qX7BxczBl8ZcjwvgDN1Vvu1YzgvOB5eK0qCYuWM+8bZzJfnVOkMO0Iwj/YFnK+myevri3johx1Abn/2u0yv3vfYLzzj8NTc8DN/a58fmIjdavRV3cW/4bmoZIRzLmBFt7dqxK1YqNaPk6zdb7wADxwffaMYj7gy6m1EwkXin0SEVePT8D58OIW1u8pj+bTtsqioWgwFWoBWz2dkRlwSkJQMS2TMsfGU=" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="completed keyexchange" Oct 30 09:11:00 volumio go-librespot[15757]: time="2025-10-30T09:11:00Z" level=debug msg="completed challenge" Oct 30 09:11:01 volumio go-librespot[15757]: time="2025-10-30T09:11:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:11:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:11:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:11:03 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:11:03 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:11:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 548. Oct 30 09:11:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:04 volumio go-librespot[15764]: go-librespot daemon starting... Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="app state loaded" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=info msg="zeroconf server listening on port 44315" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="obtained new client token: AABLVy4jD4r1ly/81sTrptmxQqX+UYX7fdZVI29mTGxonbQTpRpWETF6jIuAQlCGT0x77/+sJo18QPAmXWPFKgc5eS9O+VwOTN6kq6saqRRwOkswWnEZ5hICydXPPnL9DfWBWcu2PpZK6gOiskkJ3em9ncxXquEqZL30+uaO5Ro4xUp++gE/IbdClWH95khfreDBDhoWErBfKhl/0UJO+z7D74r+VQmwGYxkYTu/i3OhRMrOu9zF" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="completed keyexchange" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=debug msg="completed challenge" Oct 30 09:11:04 volumio go-librespot[15765]: time="2025-10-30T09:11:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:11:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:11:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:11:06 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:11:06 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:11:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 549. Oct 30 09:11:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:07 volumio go-librespot[15772]: go-librespot daemon starting... Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="app state loaded" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=info msg="zeroconf server listening on port 39571" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="obtained new client token: AADGpAo5kuV10M4InqCOUOY2+PI2b4hrSIT/FsjeX/natD3Y8P6fJUeg0aQWrm4umg2iymgeXeP+AI3bq+pCeJLuQjZwfqjaGkzE/iKgH+b55SLBrUKlJf7Rd7UtPOOkj9j5OrCWuYlUB0MN1ZFRs+i4SCg67teeTsT3/gmcxazRbZXuw+r8+QUwlSPKWAWrjVzuW2tAEQ0cepmovIH5civYhiH7u8dgjl5tDDT180mjvi62+r1t7bA=" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="completed keyexchange" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11:07Z" level=debug msg="completed challenge" Oct 30 09:11:07 volumio go-librespot[15773]: time="2025-10-30T09:11: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 30 09:11:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:11:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:11:09 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:11:09 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:11:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 550. Oct 30 09:11:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:11 volumio go-librespot[15794]: go-librespot daemon starting... Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="app state loaded" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=info msg="zeroconf server listening on port 44833" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="obtained new client token: AAC71pUh/8ZjCD9lfV6UDCTGr+czukS2ZSzEeW2sOFciURbY+QXfWOTat42SRihSVQfPmGrTGJ0bUuNqeg1zHSNeTlofJDAbdlP5H9FoAnfb/7OGy0DacuL5hapX+q3Sd5Q2uPDaE2DBU/esgJQDZYmmfAVvQ7oDa8H8pLl9/EH2hBYGX5vM7cewFZHRL+DeimO29ijDT8jW4wCbXmTG/tG70PfptGyByzGiLr2XXQL/JpV6UFdC" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="completed keyexchange" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=debug msg="completed challenge" Oct 30 09:11:11 volumio go-librespot[15795]: time="2025-10-30T09:11:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:11:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:11:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:11:12 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:11:12 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:11:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 551. Oct 30 09:11:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:14 volumio go-librespot[15806]: go-librespot daemon starting... Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="app state loaded" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=info msg="zeroconf server listening on port 42339" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="obtained new client token: AAAUb2gQKG+SNidFsUColrcr/BJiQYyoAk32vqE6tjoW7xNGE/ciDDroyio0WEBMw+84rOZ8gXZozuP4iIUkL9NAsSwmYYaUaxnZ9Z6jB/klpeYgfa503zVY2E5964Q8d0cJdyAVhGE3WITO3blH/mo6lwq2BoZrEZ2KAng/MRKE7zY+Zf4ExosT/qzmQWuUQaJaFgjukqBhSGg5T2WHEhLlBCwKJNqqYhnXjVAjZakMBq90O8XFXIg=" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="completed keyexchange" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11:14Z" level=debug msg="completed challenge" Oct 30 09:11:14 volumio go-librespot[15807]: time="2025-10-30T09:11: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 30 09:11:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:11:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:11:15 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:11:15 volumio volumio[15172]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:11:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 552. Oct 30 09:11:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 09:11:18 volumio go-librespot[15816]: go-librespot daemon starting... Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=info msg="running go-librespot 0.4.0" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="app state loaded" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=info msg="zeroconf server listening on port 44023" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="obtained new client token: AACWPZYwSxdaUKH54XVrtjWpEbHcMDHMmAI//Jc+TLs4AC9YkqSLMTaP3r+xTQ7HXXnt46eJqNs7h9LqXYUkv1kMqfZdNSb8Oh2RLgV1jFjiOS4IV2w6VLT57lv0dgJrTdH7ZtM1423G5ltHCVw7u8KpAysLiZ4IdQMNYUBbgD0ccEXN048SLICKjK3ZNC0v77J7SUciIwDgJmIFmoazE9YdXGie77NfXKXGClkb7SQKA5cD9iYM" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 09:11:18 volumio volumio[15172]: info: Initializing connection to go-librespot Websocket Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="new websocket client" Oct 30 09:11:18 volumio volumio[15172]: info: Connection to go-librespot Websocket established Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="completed keyexchange" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=debug msg="completed challenge" Oct 30 09:11:18 volumio go-librespot[15817]: time="2025-10-30T09:11:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 09:11:18 volumio volumio[15172]: info: Connection to go-librespot Websocket closed Oct 30 09:11:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 09:11:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 09:11:21 volumio volumio[15172]: info: Getting Spotify volume Oct 30 09:11:21 volumio volumio[15172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 09:11:21 volumio volumio[15172]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 09:11:21 volumio volumio[15172]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 30 09:11:21 volumio volumio[15172]: errno: -111, Oct 30 09:11:21 volumio volumio[15172]: code: 'ECONNREFUSED', Oct 30 09:11:21 volumio volumio[15172]: syscall: 'connect', Oct 30 09:11:21 volumio volumio[15172]: address: '127.0.0.1', Oct 30 09:11:21 volumio volumio[15172]: port: 9879, Oct 30 09:11:21 volumio volumio[15172]: response: undefined Oct 30 09:11:21 volumio volumio[15172]: } Oct 30 09:11:21 volumio volumio[15172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 09:11:21 volumio sudo[15852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-30 09:10' Oct 30 09:11:21 volumio sudo[15852]: 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"