Oct 28 20:48:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Oct 28 20:48:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:00 volumio go-librespot[5352]: go-librespot daemon starting... Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=debug msg="app state loaded" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48: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 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48: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 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48: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 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=info msg="zeroconf server listening on port 45919" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=debug msg="obtained new client token: AADwON/qxCA/V3S7uclCeMNSJTzQYy5hZ8+XX/orFmQ0Y1nA74g7+aCpIzgdOq8i38uPrA/tslLaY7C8uPVq9AjmBPHUH1iS+VthFGnJynB/3PHjnKIxIs8gF4IsqGgBiHXZpb1k5QmrD2I8MFenLTYtR7Al4l9tixqNICNlcKcn+L5H57km+MPfEjikR5ZT0tgvJFW7Dbl+GcJE3uhwyFQJnBk1k64j1YTXolVkhttvrMUBX3mRpeQ=" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=debug msg="completed keyexchange" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48:00Z" level=debug msg="completed challenge" Oct 28 20:48:00 volumio go-librespot[5353]: time="2025-10-28T20:48: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 28 20:48:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:00 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:00 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:03 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 20:48:03 volumio volumio[4976]: 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 28 20:48:03 volumio volumio[4976]: info: CoreCommandRouter::volumioGetState Oct 28 20:48:03 volumio volumio[4976]: info: CorePlayQueue::getTrack 0 Oct 28 20:48:03 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:03 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 28 20:48:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:03 volumio go-librespot[5363]: go-librespot daemon starting... Oct 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48:03Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48:03Z" level=debug msg="app state loaded" Oct 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48: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 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48: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 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48: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 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48:03Z" level=info msg="zeroconf server listening on port 43019" Oct 28 20:48:03 volumio go-librespot[5364]: time="2025-10-28T20:48:03Z" level=debug msg="obtained new client token: AACtwWRBUhCpGr5iic9hKO/rA5Vin2VO2HU9jN0W3Ksoxb0jdUToCWBY5YJ/27iH4oTf7/O/EVF2UFirrUfTvPpDyAj80I+M/B0DXfJhuxiG2nhdHecZZKI6y4QFAAIf3lvoZRQvclOGW8ZzdUz0BQhWIDjnFZ/X/8sEh5W3+kFbupPAFJgEDKjm0F5guORQbMmfjBAlLZGdQsSOgTOw/xjUwoUOgYk0/xrPsqA04Zmu9sqep5G9Cbc=" Oct 28 20:48:04 volumio go-librespot[5364]: time="2025-10-28T20:48:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:04 volumio go-librespot[5364]: time="2025-10-28T20:48:04Z" level=debug msg="completed keyexchange" Oct 28 20:48:04 volumio go-librespot[5364]: time="2025-10-28T20:48:04Z" level=debug msg="completed challenge" Oct 28 20:48:04 volumio go-librespot[5364]: time="2025-10-28T20:48: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 28 20:48:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:06 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:06 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 28 20:48:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:07 volumio go-librespot[5386]: go-librespot daemon starting... Oct 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48:07Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48:07Z" level=debug msg="app state loaded" Oct 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48: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 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48: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 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48: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 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48:07Z" level=info msg="zeroconf server listening on port 36879" Oct 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48:07Z" level=debug msg="obtained new client token: AAAVjSLpaRH2xLcXZ1lHnfkylSO1X8EJp02p93kcQkCxAXSfxokXUGC1Ae0Co/C8ouKy2KlEulODsV+hXAYSCjr172HESBYAvJt+4ZSG48g7iPueFBl/oBpL5lfHg1HCcJwHQif8jHYg3199U8wt3AnLpGG29awmaRiYkf0vWJ8q2+u1IdYzIZXuO+ooz6YRqAfqsOZCLYdZVJRxZ6dzYS0arNFlRQOizZkZ68jKBqUu7TDOg7qOEP8=" Oct 28 20:48:07 volumio go-librespot[5387]: time="2025-10-28T20:48:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:08 volumio go-librespot[5387]: time="2025-10-28T20:48:08Z" level=debug msg="completed keyexchange" Oct 28 20:48:08 volumio go-librespot[5387]: time="2025-10-28T20:48:08Z" level=debug msg="completed challenge" Oct 28 20:48:08 volumio go-librespot[5387]: time="2025-10-28T20:48: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 28 20:48:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 20:48:08 volumio volumio[4976]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 28 20:48:08 volumio volumio[4976]: info: Completed starting MyVolumio Plugin Oct 28 20:48:08 volumio volumio[4976]: [Metrics] CommandRouter: 31s 940.85ms Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::volumiosetStartupVolume Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::Close All Modals sent Oct 28 20:48:08 volumio volumio[4976]: info: CoreCommandRouter::Close All Modals sent Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 28 20:48:09 volumio volumio-remote-updater[874]: Test mode disabled Oct 28 20:48:09 volumio volumio-remote-updater[874]: Alpha mode disabled Oct 28 20:48:09 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 28 20:48:09 volumio volumio[4976]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 20:48:09 volumio volumio[4976]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 20:48:09 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:09 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 28 20:48:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:11 volumio go-librespot[5404]: go-librespot daemon starting... Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=debug msg="app state loaded" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" 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 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" 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 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" 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 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=info msg="zeroconf server listening on port 40479" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=debug msg="obtained new client token: AACyLNmrEfULyrTdNrcLK2U17ZFKtJoPJPPQWGduEEreSsO6ZGafbd/d2rdMfzGgJY9cIFvw+pWv88MjZmdWKreogFp2KGheGIhkza638oSv71uhfLK5d2V4dpwKWPRqBuG7RynybpHkWmQeHbRVMOGBHWj4w3JJ5Gi93OG6gr+0RNgpwE0bj/3uXd0ePl+m0q9HySQtvmW13ngfk4tQ9WUvzG2iibrwWuyML5ghSxDYjtpJagim/bw=" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=debug msg="completed keyexchange" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48:11Z" level=debug msg="completed challenge" Oct 28 20:48:11 volumio go-librespot[5405]: time="2025-10-28T20:48: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 28 20:48:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:12 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:12 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 28 20:48:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:14 volumio go-librespot[5412]: go-librespot daemon starting... Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=debug msg="app state loaded" Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=info msg="zeroconf server listening on port 42389" Oct 28 20:48:14 volumio go-librespot[5413]: time="2025-10-28T20:48:14Z" level=debug msg="obtained new client token: AABU78upYxpT0gkVf+bcll2c7tj55iEqpOmUDEdF8q6IeEgpmWRRiMP3PkdcdNM/Ilchvy9xlrDpRn7bgBV3be1Io+wGP8SlBthyS32z8m2i/NAVg8CRdIUfzWg/hlonsVDXbcp36oRPGgLxN36RcqDIBZd8RsILUW+uAcP8z4Nbrl6k8Qu81VfUfJfIUSEE71eATxpaDwcY91WQiFwnMoI5XglZGsAZKytugovpbglGCZRMDJG9/sA=" Oct 28 20:48:15 volumio go-librespot[5413]: time="2025-10-28T20:48:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:15 volumio go-librespot[5413]: time="2025-10-28T20:48:15Z" level=debug msg="completed keyexchange" Oct 28 20:48:15 volumio go-librespot[5413]: time="2025-10-28T20:48:15Z" level=debug msg="completed challenge" Oct 28 20:48:15 volumio go-librespot[5413]: time="2025-10-28T20:48: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 28 20:48:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:15 volumio volumio[4976]: info: BOOT COMPLETED Oct 28 20:48:15 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:15 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 28 20:48:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:18 volumio go-librespot[5434]: go-librespot daemon starting... Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="app state loaded" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=info msg="zeroconf server listening on port 39737" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="obtained new client token: AAD5rg/eLjc67jfeZVPO5RHf/J2LyCoYe8xjoM20kHAb1UbZMEdvPad3rMFMuUNwAm/+28+0AYDrSv073ySkOVUwdDCGaVtHNFgAAghTRxPM0RovGZ4CNs7A/zbmt5ZoA0wF5iW3fZMEIK8kMeucEO6JJzzIQApmwa9R6WaJMwealMZrBrm4TzyieJr++szluEWQAknU0r+Q41XNV+BCvp3Cj+4czN5lTnRdZZKxcvcaNx5HWoTdeVY=" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="completed keyexchange" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48:18Z" level=debug msg="completed challenge" Oct 28 20:48:18 volumio go-librespot[5435]: time="2025-10-28T20:48: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 28 20:48:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:18 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:18 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:21 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:21 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 28 20:48:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:21 volumio go-librespot[5442]: go-librespot daemon starting... Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=debug msg="app state loaded" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" 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 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" 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 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=info msg="zeroconf server listening on port 38701" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=debug msg="obtained new client token: AABVICRueY2P8T0CBnaMER6S7MvXwE2g2UKxWfvy+r981QxAkukNQNLeE0Ta2R97Dme9tjEkRQE882GfCe82sVW7mO1qCnUtcd4iA82drpeQSq8BqfyNK8Yj224v6DzvkiOrJHSYP9O3uFGeeZGBAX9L0bzHlJ4jSmvqCWFrJrRfbZS6h3y7dIxSo2Lnml6ldXQd42Fh68CO/0LD2luzE9DOJVQVKHw2sZfzHaj0ux0iYLNxARWQw10=" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=debug msg="completed keyexchange" Oct 28 20:48:21 volumio go-librespot[5443]: time="2025-10-28T20:48:21Z" level=debug msg="completed challenge" Oct 28 20:48:22 volumio go-librespot[5443]: time="2025-10-28T20:48: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 28 20:48:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:24 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:24 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 28 20:48:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:25 volumio go-librespot[5453]: go-librespot daemon starting... Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=debug msg="app state loaded" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48: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 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48: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 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48: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 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=info msg="zeroconf server listening on port 35429" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=debug msg="obtained new client token: AAA+AcByG2WdzpCcENwupn6rbPOZHagS7FAt6D0pSsZHvgjy1fc+tdIgRj0Vd4spxDnMh63JV7by4pM00DMQ+K4hsvUQPK40/2bv98phzVC5NVbtwa6/KYzmqzZ91dbSxJfayb6lShZsU+oK2p808cGibatJCnciRvS79CVrUnoEHbjtGvmDfg0yZereT27Ia8hfCZszOmPrgUtGckzuUNxubUOPh7DkXiYbodDE9hSDt470cR7R7UU=" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=debug msg="completed keyexchange" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=debug msg="completed challenge" Oct 28 20:48:25 volumio go-librespot[5454]: time="2025-10-28T20:48:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 20:48:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:27 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:27 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 28 20:48:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:28 volumio go-librespot[5476]: go-librespot daemon starting... Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=debug msg="app state loaded" Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48: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 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48: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 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48: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 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=info msg="zeroconf server listening on port 36039" Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=debug msg="obtained new client token: AABdJdAcZSaXaJaH5HGJUG38bb/18TVBLDjp+QS98bHAPdsdZiV/6vgADbGIUa6yjbIX0BqpBK7+qvf2lEhZEXEEWYLk6gR4vhtDZIJ07+NCIh3wOZVX/13Ma6c0nBk8P0FjOSqwohIHpa92d0G2Nq94aNAv56Dkg4lQBHoVWr1Um9NOUFiVtc+Zf/kQPLajXavYq0j8wV0G0l4T50xIRwn5hI2ZzYeg/YnULN0dhmYl1J5UxU9lRQU=" Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=debug msg="completed keyexchange" Oct 28 20:48:28 volumio go-librespot[5477]: time="2025-10-28T20:48:28Z" level=debug msg="completed challenge" Oct 28 20:48:29 volumio go-librespot[5477]: time="2025-10-28T20:48: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 28 20:48:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:30 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:30 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 28 20:48:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:32 volumio go-librespot[5484]: go-librespot daemon starting... Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="app state loaded" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=info msg="zeroconf server listening on port 41057" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="obtained new client token: AAAyxMjaYcp+GcE/y2C8CeYreppJhd5faySX7Ramq6mF+EjjU2lEu1Oc77anATB+yKd/ufdOkHREp4ld5wAxnPosIlnwOaGPQ3WZTnpbwma0GbU3n4lmad9fhmDWmvq6xP10nXd1+sNHEFbMPpaHG9P3DvkyP/s6d2kSZpJe/+ax5B3kmvm6NMLqIV0NKUwpmaZfltZbdbDmc1L8NpLhE488R4u8QYQofCzeQpNHeNlvs+fOu0kZlqg=" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="completed keyexchange" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=debug msg="completed challenge" Oct 28 20:48:32 volumio go-librespot[5485]: time="2025-10-28T20:48:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 20:48:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:33 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:33 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 28 20:48:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:35 volumio go-librespot[5492]: go-librespot daemon starting... Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=debug msg="app state loaded" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=info msg="zeroconf server listening on port 39433" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=debug msg="obtained new client token: AAAp9Oz+D4R1gy9gxHBPP2mosLNvEyBSdulKabXNBsQ5J2I3Wp5E5oZJ3FrrTA6kvQGV4cdyo3AqpZJSaQSZeb6TpIyJhc0uUMt+TyT7/KFwwts/SAdvSvpDvXYO06i5/QshzQSFsIb2A2OZCAsItHN/c+KswPn7/Um+om+ZbCLumPmp9acZpVDDPkhR7YzQHlKSnt9HxEJ0B6xjQy5qq5uIgTaXXT+WhBnpPaZJrndOJfAmLYFvJLw=" Oct 28 20:48:35 volumio go-librespot[5493]: time="2025-10-28T20:48:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:36 volumio go-librespot[5493]: time="2025-10-28T20:48:36Z" level=debug msg="completed keyexchange" Oct 28 20:48:36 volumio go-librespot[5493]: time="2025-10-28T20:48:36Z" level=debug msg="completed challenge" Oct 28 20:48:36 volumio go-librespot[5493]: time="2025-10-28T20:48: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 28 20:48:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:36 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:36 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 28 20:48:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:39 volumio go-librespot[5514]: go-librespot daemon starting... Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="app state loaded" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=info msg="zeroconf server listening on port 37655" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="obtained new client token: AAB75EbarzGXO45HKBWgVaq1WeA42WoT1UzEb/SsD8lhqMa5kTC3n9cgq08+MFu422wCPSqFHaZc+UEolWgiuNJMl5VE09x8eumOibJtIP6UPSPVQe8xt4sLkp+9Yh4heYGjRDWa/uoidVVapZ9Y83KNqJBhWn9XsUnZ6n6pPafJDrh1bmSlszDYYouiUiMl6W4AVpsVqKUfme7DBfsBk87yJyt/jjobXlsM5KRyVpb/G4/HpAfcxwM=" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="completed keyexchange" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=debug msg="completed challenge" Oct 28 20:48:39 volumio go-librespot[5515]: time="2025-10-28T20:48:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 20:48:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:39 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:39 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:42 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:42 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 28 20:48:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:42 volumio go-librespot[5525]: go-librespot daemon starting... Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=debug msg="app state loaded" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=info msg="zeroconf server listening on port 42515" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=debug msg="obtained new client token: AAAR8x8/0uZWr1IOjKOPgArkN8S4L7+ZqS9WpCvmpvDsRuPlBb+5snTh3ZJBGIRDG241rkyi2+a7VWDJtF28KeB14V4Inu8AiMrS6p84YrLwzQODP4Z0ewkTclPmWtUtJjZZu2aAPfdot6lMu/eSlUhm0ux+9v/GfS6B+WNISuvoLBxsljmLUChBK4DO8kc3zklQjd8n3gJ1i6vF8/YLTLHAiw8lhrznZhdsTS5R1skMaINlGVvWgvg=" Oct 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" 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 28 20:48:42 volumio go-librespot[5526]: time="2025-10-28T20:48:42Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 20:48:43 volumio go-librespot[5526]: time="2025-10-28T20:48:43Z" level=debug msg="completed keyexchange" Oct 28 20:48:43 volumio go-librespot[5526]: time="2025-10-28T20:48:43Z" level=debug msg="completed challenge" Oct 28 20:48:43 volumio go-librespot[5526]: time="2025-10-28T20:48: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 28 20:48:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:45 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:45 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 28 20:48:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:46 volumio go-librespot[5533]: go-librespot daemon starting... Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=debug msg="app state loaded" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" 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 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" 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 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=info msg="zeroconf server listening on port 38505" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=debug msg="obtained new client token: AACHCeCF03uVjPsuN1nxEsyB8b3o4I4gNh1oSFhVFYh9mrttuZ838iPPuCKUjaJ5UeUE4DJHxYhrThd4SAQmxo8JjZE8dKA1SlNvFpPdFv9JFTAXBkPN0iUasgR0OnIQiV6I6CikHePv9vpS6yRC3/ZRgZjzvDYEA60M9JVKpUuGc0nFXqw149f9kNbICY9mfAAwmEme6TqY8kM/KVhIpE1Axa7QcJ1YdIbj/6UyN9e18kEEFWCipiU=" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=debug msg="completed keyexchange" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=debug msg="completed challenge" Oct 28 20:48:46 volumio go-librespot[5534]: time="2025-10-28T20:48:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 20:48:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:48 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:48 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 28 20:48:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:49 volumio go-librespot[5556]: go-librespot daemon starting... Oct 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48:49Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48:49Z" level=debug msg="app state loaded" Oct 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48:49Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48: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 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48: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 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48: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 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48:49Z" level=info msg="zeroconf server listening on port 38317" Oct 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48:49Z" level=debug msg="obtained new client token: AAAD4ivddySTxyyyGiw0hHgiBO0eeX/+9RcxztIXuaDwulCkrr+0s0RpWSuCSUS4WMOqeTT8vZ3mb5uef+N9IMLRm3UYSyy27q8zifVsznp4ybFnwHgfFipVNdgI+8DHlJj7IDVR3bUCpQlPqWFkpoBJfSpIhdhZVgTfrLQZTKJ5qZUIR00jdi4+LApaiiTzE/lG3CK58OdoHZ9WiJ56MNsX0Ya43xJq25VgIV6pn4CrRZvWrCx1v7I=" Oct 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:49 volumio go-librespot[5557]: time="2025-10-28T20:48:49Z" level=debug msg="completed keyexchange" Oct 28 20:48:50 volumio go-librespot[5557]: time="2025-10-28T20:48:50Z" level=debug msg="completed challenge" Oct 28 20:48:50 volumio go-librespot[5557]: time="2025-10-28T20:48: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 28 20:48:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:51 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:51 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 28 20:48:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:53 volumio go-librespot[5564]: go-librespot daemon starting... Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="app state loaded" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=info msg="zeroconf server listening on port 38211" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="obtained new client token: AAB6CKvRTlcb/KxlyuZb42sQgKylQ30FZNtiZ94wkKie56Lj4tcJrqU1YOfbz0u3PkpHSX8s/XXM8Gt9XJXaw+Sb4m2XfMl8v90451aO/HXdfQzoh9DwjMP0QXYNXDxfVB6haX3GiJ0zVglOUgjiWnN5A0uKil2XScL95NFF9xtpbFifk/h8DkwpC/m9cwN3HumZXPcQSuxcrLaJIkrSo6Ft/coEMWq8XCfnjXoCDbXhF7oY2LYiJCM=" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="completed keyexchange" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=debug msg="completed challenge" Oct 28 20:48:53 volumio go-librespot[5565]: time="2025-10-28T20:48:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 20:48:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:54 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:54 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:48:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 28 20:48:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:48:56 volumio go-librespot[5572]: go-librespot daemon starting... Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=debug msg="app state loaded" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=info msg="zeroconf server listening on port 35189" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=debug msg="obtained new client token: AADSTzNTXMzZ/l1OAbw/afeAzqD/vOE0GvHqrktiflOsk184dZNoOLQNaVZPrFnzMHAwoTyahV5GIzIpa5vJf2+ONSfKPjgBPM7NSlZoIdpdnTyigh0Vx3GHRsv66z2RyPL1MOhsn1EoiloZBr8DXY9nVCc6TCnA7gKIgx90ePP+l1GUxCKh8rVug+xKmRgWFhuSyIZX+F+zGi5m9Upxd0bpVCcbwOwT4Kv6Z3emWExjKkBQVK4kfrA=" Oct 28 20:48:56 volumio go-librespot[5573]: time="2025-10-28T20:48:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:48:57 volumio go-librespot[5573]: time="2025-10-28T20:48:57Z" level=debug msg="completed keyexchange" Oct 28 20:48:57 volumio go-librespot[5573]: time="2025-10-28T20:48:57Z" level=debug msg="completed challenge" Oct 28 20:48:57 volumio go-librespot[5573]: time="2025-10-28T20:48: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 28 20:48:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:48:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:48:57 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:48:57 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:49:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 28 20:49:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:49:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:49:00 volumio go-librespot[5595]: go-librespot daemon starting... Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=debug msg="app state loaded" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49: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 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49: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 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49: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 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=info msg="zeroconf server listening on port 34719" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=debug msg="obtained new client token: AACLVHiMEku+BLMnvJ+Sr25II7dTG4Hjj+PI7rDCEVfGkkDIZf9gtozy2KUDmZrrxKfSodg2KIwTsXPFBjGMt/PlxY5SFn7iTkm27Zl395hEjoj8/1cd9Ga6CCvxrM9NrE+U2UqVVpZI5ILQbWC2Rdei0HpbCbTGeeBlOYl3LjWetRQJQty3iCpeBjvTp0flazs4bq6ozdvipvGEgsR6zOUXSIgnrlzJXd9nfL0zIwgpmAYLqNu9eYM=" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=debug msg="completed keyexchange" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49:00Z" level=debug msg="completed challenge" Oct 28 20:49:00 volumio go-librespot[5596]: time="2025-10-28T20:49: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 28 20:49:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:49:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:49:00 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:49:00 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:49:03 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:49:03 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:49:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 28 20:49:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:49:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:49:03 volumio go-librespot[5606]: go-librespot daemon starting... Oct 28 20:49:03 volumio go-librespot[5607]: time="2025-10-28T20:49:03Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:49:03 volumio go-librespot[5607]: time="2025-10-28T20:49:03Z" level=debug msg="app state loaded" Oct 28 20:49:03 volumio go-librespot[5607]: time="2025-10-28T20:49:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:49:03 volumio go-librespot[5607]: time="2025-10-28T20:49: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 28 20:49:03 volumio go-librespot[5607]: time="2025-10-28T20:49: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 28 20:49:03 volumio go-librespot[5607]: time="2025-10-28T20:49: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 28 20:49:03 volumio go-librespot[5607]: time="2025-10-28T20:49:03Z" level=info msg="zeroconf server listening on port 39853" Oct 28 20:49:06 volumio go-librespot[5607]: time="2025-10-28T20:49:06Z" level=debug msg="obtained new client token: AAAMgN8lf1WPXn1pK9EgubaqD0gGimKaQpu+qNGrz7a84UaYsLzLOehTaNUHQypG85S69DdLRTARTZWi3swx77/q7X+iPAdG65Gqwt/Jq186tWbt76mkNo45f6ZXDdgNxM0UuWSuKLxm2l1yvxs0B/FEJT7xko92uZPtQfIBrM0CFJZJNeiuKG4wMFZ+ffO5ViPLZri3ZtO1BKOexEZETL+kqyPRwK4dFo0GLfre7KhcSPf//Xway7o=" Oct 28 20:49:06 volumio go-librespot[5607]: time="2025-10-28T20:49:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:49:06 volumio go-librespot[5607]: time="2025-10-28T20:49:06Z" level=debug msg="completed keyexchange" Oct 28 20:49:06 volumio go-librespot[5607]: time="2025-10-28T20:49:06Z" level=debug msg="completed challenge" Oct 28 20:49:06 volumio go-librespot[5607]: time="2025-10-28T20:49: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 28 20:49:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:49:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:49:06 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:49:06 volumio volumio[4976]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:49:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Oct 28 20:49:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:49:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 20:49:09 volumio go-librespot[5628]: go-librespot daemon starting... Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=info msg="running go-librespot 0.4.0" Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=debug msg="app state loaded" Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 20:49:09 volumio volumio[4976]: info: Initializing connection to go-librespot Websocket Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=debug msg="new websocket client" Oct 28 20:49:09 volumio volumio[4976]: info: Connection to go-librespot Websocket established Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" 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 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" 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 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" 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 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=info msg="zeroconf server listening on port 40503" Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=debug msg="obtained new client token: AABf+C7pGum1kSdIW8VOZtrezFhoZbaB/up5lz0JAFdNpDfZ6/9m1hxV28fG67EJv8xSU6l/L/9RBjWAe3MOGd4kWBmQiJI+fhvy5UZu31fUhsmSN4V6J5JzoLigymD5QvvzirXW3DgTPMTSN4PUDwXA4PE+zciNGDxueoPazFbXU8wZ5E1yF5Qq2j2gnhIxneoK0Sy17yHMnLZ8iVWRnHnAN5QHJopqu1WzBYwmgxfikmUzaKx+GDI=" Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=debug msg="completed keyexchange" Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=debug msg="completed challenge" Oct 28 20:49:09 volumio go-librespot[5629]: time="2025-10-28T20:49:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 20:49:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 20:49:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 20:49:09 volumio volumio[4976]: info: Connection to go-librespot Websocket closed Oct 28 20:49:12 volumio volumio[4976]: info: Getting Spotify volume Oct 28 20:49:12 volumio volumio[4976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 20:49:12 volumio volumio[4976]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 20:49:12 volumio volumio[4976]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 28 20:49:12 volumio volumio[4976]: errno: -111, Oct 28 20:49:12 volumio volumio[4976]: code: 'ECONNREFUSED', Oct 28 20:49:12 volumio volumio[4976]: syscall: 'connect', Oct 28 20:49:12 volumio volumio[4976]: address: '127.0.0.1', Oct 28 20:49:12 volumio volumio[4976]: port: 9879, Oct 28 20:49:12 volumio volumio[4976]: response: undefined Oct 28 20:49:12 volumio volumio[4976]: } Oct 28 20:49:12 volumio volumio[4976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 20:49:12 volumio sudo[5650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 20:48' Oct 28 20:49:12 volumio sudo[5650]: 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"