Nov 05 15:15:00 volumio go-librespot[11479]: time="2025-11-05T15:15:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:01 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:01 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:01 volumio volumio[11135]: info: MYVOLUMIO: Adding device Nov 05 15:15:01 volumio volumio[11135]: info: MYVOLUMIO: Evaluating Server Nov 05 15:15:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Nov 05 15:15:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:03 volumio go-librespot[11501]: go-librespot daemon starting... Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="app state loaded" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:03 volumio volumio[11135]: info: Setting Geolocation for MyVolumio to eu3 Nov 05 15:15:03 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:03 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:03 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=info msg="zeroconf server listening on port 40473" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="obtained new client token: AAB4RCDaBwwx/CIxwj5N2IQ96pUNan9ZgIKwGRtGFp4gKiLA7UpTYfj8r/6PyNmUhvB6EKE7ER13Sk+HdDlWAkgAXIuBeYIfYM2tLHxeBEa4clV5/qtkxk+XcQ3/bG9tMrB/+yYS0yJA2j1H7PTq3gmIPjvcSKN5uEsNlBwk2hGZAzq7+JvRrrjbUAWoxfxfTx5pQaInHvb7TCVSWnwk9wzYN0Cp3HIFE2jovSqx/h4z6nuE4gn0aAo=" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="completed keyexchange" Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=debug msg="completed challenge" Nov 05 15:15:03 volumio volumio[11135]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 05 15:15:03 volumio go-librespot[11502]: time="2025-11-05T15:15:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:03 volumio volumio[11135]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 05 15:15:04 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:04 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:04 volumio volumio[11135]: info: Updating MyVolumio device info Nov 05 15:15:04 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:04 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:04 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:04 volumio volumio[11135]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 05 15:15:05 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:15:05 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:15:05 volumio volumio[11135]: info: Listing playlists Nov 05 15:15:05 volumio volumio[11135]: info: Listing playlists Nov 05 15:15:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Nov 05 15:15:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:06 volumio go-librespot[11523]: go-librespot daemon starting... Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="app state loaded" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=info msg="zeroconf server listening on port 36197" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="obtained new client token: AAC05i4sgz8YC072pVI4MJPUxxo/FUB21aa4wVeHfKufd618u6zgjKHJLqI9y7XDa974C2jO3+oNg0NNU0VDM+6muKnoG61rc0uj8NPT1auvwMP0W6XhXXEFhg++IepnESd4ancCg7r0ltrqaXDp8VgQ4/Zn2olynEL95l6ZXVJ8xfLhSZXZ1Xg+RGOcdVeGj18dFxASQz4zqb1lueHbOjr25jRST7LJ6/XuQyv7k3IEEe9oohfFaD4=" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="completed keyexchange" Nov 05 15:15:06 volumio go-librespot[11524]: time="2025-11-05T15:15:06Z" level=debug msg="completed challenge" Nov 05 15:15:07 volumio go-librespot[11524]: time="2025-11-05T15:15:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:07 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:07 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:10 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 05 15:15:10 volumio volumio[11135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 05 15:15:10 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:15:10 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:15:10 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:10 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Nov 05 15:15:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:10 volumio go-librespot[11534]: go-librespot daemon starting... Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="app state loaded" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=info msg="zeroconf server listening on port 44771" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="obtained new client token: AAAun5phFbBtEQQ7o7f4zz28bUjN2601Jjc/mxpodpXFyKPSb53wMTJhKsigNiWzdSqqcX4TC3iFYSx4JXt4GoE6j0O0G+jlcRfEyDtZhkUqvW+3zkT/FJ3E8NnEh2ATagNsJKqo4ZodWZap0+JtP99j4aV4y9lKggcA8QxCXTnFYQ2bR49En9xHz6BFW5vCCvU+u2DWF1tJrLNmtKUHKoRz3cUO0pJVGb0Rc7/IHvCiL1c82N6qhWc=" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="completed keyexchange" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=debug msg="completed challenge" Nov 05 15:15:10 volumio go-librespot[11535]: time="2025-11-05T15:15:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:13 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:13 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Nov 05 15:15:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:13 volumio go-librespot[11557]: go-librespot daemon starting... Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="app state loaded" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=info msg="zeroconf server listening on port 33379" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="obtained new client token: AAAqzvKors8/A+XZbpFYnNwXwGoQ+z1q9/ibe9eZNoiRVE3NdmdBr90r0HzJud86nnP7VZD/0UeaBRjyExVD1FiZXe16kX/iSrirKMJaVRufdyE4FrCZpEXJ8+/4sJHZBklOHPk2jzgzCfNnzxTYWCOV/iQQ+tPt5O3FpZx8lt6BbduJz7diGRq4bWx/+cIpDDWnAgk3DJfSrIJ0S1WVRFRa7IaRxmRo4h39RX6n0oFTxnVEikNWjx8=" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="completed keyexchange" Nov 05 15:15:13 volumio go-librespot[11558]: time="2025-11-05T15:15:13Z" level=debug msg="completed challenge" Nov 05 15:15:14 volumio go-librespot[11558]: time="2025-11-05T15:15:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::volumioGetBrowseSources Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 05 15:15:15 volumio volumio[11135]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 05 15:15:15 volumio volumio[11135]: info: Completed starting MyVolumio Plugin Nov 05 15:15:15 volumio volumio[11135]: [Metrics] CommandRouter: 32s 62.72ms Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::volumiosetStartupVolume Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::Close All Modals sent Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::Close All Modals sent Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 05 15:15:15 volumio volumio-remote-updater[874]: Test mode disabled Nov 05 15:15:15 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 05 15:15:15 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 05 15:15:15 volumio volumio[11135]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 05 15:15:15 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:15:15 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:15:16 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 05 15:15:16 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 05 15:15:16 volumio volumio[11135]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 05 15:15:16 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:16 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Nov 05 15:15:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:17 volumio go-librespot[11575]: go-librespot daemon starting... Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="app state loaded" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=info msg="zeroconf server listening on port 33095" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="obtained new client token: AAB7iUcuTBl++zjY1UwJWoHzBPBcrri8KgN2kbyQk1pnlqq3jl6AiAw6M+zbEUpJoDuUb8Xkj99cfpMn5ddxBCmnBFbJrnCz+TqfnZ9Gr0L+UClXG8BtuKCRSK+V2EzOnVN508SsJd/S0itICfatN5qnoZO2TvONJuzuiNyANLo3WbH5W7sFpu75/qFpDuiGyzH/gZ0CxLTmtsq24PWDYH9MMhDj+DMvf3s2bfWrjyhg4BsF4F39YK4=" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="completed keyexchange" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=debug msg="completed challenge" Nov 05 15:15:17 volumio go-librespot[11576]: time="2025-11-05T15:15:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:19 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:19 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Nov 05 15:15:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:20 volumio go-librespot[11583]: go-librespot daemon starting... Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=debug msg="app state loaded" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=info msg="zeroconf server listening on port 45077" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=debug msg="obtained new client token: AACL8oCnvGOmEu//NiEUfSkRZJeeWnxhaLZj5xi2KapGHiWjRK+BpwDwgDPxP8QiY27G1K57PMLeJC1RUbHEKEcckb4dJgnql2Jxbml89cH32UlSptYdTPKTxkLHoVP2NOhi/eSwdvlV36JFm5EmORPYDnRMx2MrZ/s41k4HoffkNdNfqXjtgc9QHIp5itXP0/Ng+ZGa69sujMkBjd7c9mBuZ4YAg8aLNyXHkeWlrvMjU5AYGXzv84Q=" Nov 05 15:15:20 volumio go-librespot[11584]: time="2025-11-05T15:15:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:21 volumio go-librespot[11584]: time="2025-11-05T15:15:21Z" level=debug msg="completed keyexchange" Nov 05 15:15:21 volumio go-librespot[11584]: time="2025-11-05T15:15:21Z" level=debug msg="completed challenge" Nov 05 15:15:21 volumio go-librespot[11584]: time="2025-11-05T15:15:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:22 volumio volumio[11135]: info: BOOT COMPLETED Nov 05 15:15:22 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:22 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Nov 05 15:15:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:24 volumio go-librespot[11606]: go-librespot daemon starting... Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="app state loaded" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=info msg="zeroconf server listening on port 33353" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="obtained new client token: AAChLFnNI+ntLvcpZDMiQXJxBFr+/ImXoA7R4A6HMouMvVt7oQ+QPWG8ZEfYOHxjCKTRqs5Q2JbRGzLcsS11A7O5+zLzeA7eN32QQu0TsfZY2m9Zy3lpawEnx0OTYIf1jHQFD2BVuBLa+t0VBGIJ8mPMjMP0D3e207o3slRp9WK7j0tapMEKTm+LGx8/T85q8wJVxa7D6gv8sNAcr5TGRA0jqwppjEzXDjRzDGNbatJTyCKkp8xrtVw=" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="completed keyexchange" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=debug msg="completed challenge" Nov 05 15:15:24 volumio go-librespot[11607]: time="2025-11-05T15:15:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:25 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:25 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:25 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:15:25 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:15:25 volumio volumio[11135]: info: Listing playlists Nov 05 15:15:25 volumio volumio[11135]: info: Listing playlists Nov 05 15:15:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Nov 05 15:15:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:27 volumio go-librespot[11614]: go-librespot daemon starting... Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="app state loaded" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=info msg="zeroconf server listening on port 37609" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="obtained new client token: AAC8ITi4yyl7NwmQrvoOmbWvkcNpYycd0HscyeMBAvz1ORvBSwHZmFuVzwTtGQYSZhTpKm09g7FyhCEqhPOdH0E/hSGUs930SFjPqa/hFq/Bj9ZVikTUudaeQcfY4e4K6s9PTHTlns0i9lG13tzfC336lw5GziOZDEUryPANHBPiK12I01LnqWlLjodnOkgd98jRLHyXFWvXiMfr9SwBqP539kyiPC/HotqU1lVNatzpK7JG0UokwdQ=" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="completed keyexchange" Nov 05 15:15:27 volumio go-librespot[11615]: time="2025-11-05T15:15:27Z" level=debug msg="completed challenge" Nov 05 15:15:28 volumio go-librespot[11615]: time="2025-11-05T15:15:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:28 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:28 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:31 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:31 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Nov 05 15:15:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:31 volumio go-librespot[11626]: go-librespot daemon starting... Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="app state loaded" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=info msg="zeroconf server listening on port 34343" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="obtained new client token: AADrGNNCASej878OkyxaF5IAKuOc8uwNPtZTN7Ls9q7JPkWBVjcJdHHUSQrNcwX1jWs0DcO+EcLJUjR/LFCnHYJtSXc6EeM3AjT80Bo3q/To8+fBEwn5uc3m+KW0hKQlVshEUO7ZrWz3IAs0zCjoKcJyVHljfkWCQBfadAW9b/Bs2OmMA/ZteyyukphHb5nBsxCsawJVrxhPhw8WXQfWdGQQjIOkCzY2Adb6N6ZEmNS1XhMdmptxrlA=" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="completed keyexchange" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=debug msg="completed challenge" Nov 05 15:15:31 volumio go-librespot[11627]: time="2025-11-05T15:15:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:34 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:34 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Nov 05 15:15:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:34 volumio go-librespot[11649]: go-librespot daemon starting... Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=debug msg="app state loaded" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=info msg="zeroconf server listening on port 39893" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=debug msg="obtained new client token: AAAuhVvZrxsXCM0C0Y3n4O7krrvsx8c2abD+2ulc6N3LL6Vx75IJesp+2layg17BF9LhJkE+lHkE8w4R/ycXvtB8aW8bY7iQ+FTaKfnnieCpyBx3GVjyh43c7htjBIb2joOGXXgXMsS3Y+drOg7CdIjirsG/bDBfj0EX8UdlFbFhSmrHHOznwuHGdWFncgTV/pzu6xzv9Z+yLnYHZa1Gqtme+QHBkBEik/OcDpEw7YbCXjNq9u9zSuc=" Nov 05 15:15:34 volumio go-librespot[11650]: time="2025-11-05T15:15:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:35 volumio go-librespot[11650]: time="2025-11-05T15:15:35Z" level=debug msg="completed keyexchange" Nov 05 15:15:35 volumio go-librespot[11650]: time="2025-11-05T15:15:35Z" level=debug msg="completed challenge" Nov 05 15:15:35 volumio go-librespot[11650]: time="2025-11-05T15:15:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:35 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:15:35 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:15:37 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:37 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Nov 05 15:15:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:38 volumio go-librespot[11658]: go-librespot daemon starting... Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="app state loaded" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=info msg="zeroconf server listening on port 32817" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="obtained new client token: AADWMBrBL60Sv0On5l4p0zYiv23E5Q8n86KiuZ1lTiMqmekzTOZOKp/Y5fWiHN/exnXTASa1wvdeP8b1iITS/CkpmmBAezaSqJDmt7XeimTbsA3GxaFGn9wSKHki3G9ui9f/cj2H6nLGH8iRVZ2rN+r+SFQryuNIcB/tFpEjPvhrnekjF3/3SZbwH072iaOoqO+YIhiR3+9ZbQLnhSAj/fdIbcIRFREwHJApOhp8FVF9DXDT0tAk7kM=" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="completed keyexchange" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=debug msg="completed challenge" Nov 05 15:15:38 volumio go-librespot[11659]: time="2025-11-05T15:15:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:40 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:40 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Nov 05 15:15:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:41 volumio go-librespot[11666]: go-librespot daemon starting... Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="app state loaded" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=info msg="zeroconf server listening on port 43563" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="obtained new client token: AACch902zFm0EdIvmBdC9frlgTHnedIAZ54rU1akRMHSSbX2CAZPuD8Bq7P1nPHp72QpxAhoKU4gPXxSQ5TZMyL7SmPpOo+vB8aoD74L/VixkHmMFG7+IcFUx52JwX94Kjzu3xuXovInsp1N9u46tnjlX4ChQqt7hT09+i2gP7m3mA9Pkq/otwzWRvElkMQSt0XBhtGb1u4909/HQzypG7AXV5hpSURX9KrTBf4kf4BByaelqf9SuVY=" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="completed keyexchange" Nov 05 15:15:41 volumio go-librespot[11667]: time="2025-11-05T15:15:41Z" level=debug msg="completed challenge" Nov 05 15:15:42 volumio go-librespot[11667]: time="2025-11-05T15:15:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:43 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:43 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Nov 05 15:15:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:45 volumio go-librespot[11689]: go-librespot daemon starting... Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="app state loaded" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=info msg="zeroconf server listening on port 37073" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="obtained new client token: AABvRFhnewcf7ln1/M2pzvHXO55cFPRyKz7oG//YetwKYHB8nt20oyhiUJFoUxgG6qpoARl1kVKeFu9flVVovprsiWAAWXpAROC0XKhTYMgT0diqc2CmoQZZ+NYU9E0ljGieoJmppGxUWx2nCXWuVvEoILO8ZymuXCWavNf2pHotUI8CeOiKKMjin1BZ6xFhM9Bddz6KQkwYVXzxhvze3dpjrqS6tFWX/6xuRw394QwPZpEBELKaxDw=" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="completed keyexchange" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=debug msg="completed challenge" Nov 05 15:15:45 volumio go-librespot[11690]: time="2025-11-05T15:15:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:45 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:15:45 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:15:45 volumio volumio[11135]: info: Listing playlists Nov 05 15:15:45 volumio volumio[11135]: info: Listing playlists Nov 05 15:15:46 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:46 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Nov 05 15:15:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:48 volumio go-librespot[11697]: go-librespot daemon starting... Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="app state loaded" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=info msg="zeroconf server listening on port 46083" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="obtained new client token: AADvlf1Qp0YAeMKWjkby+8B0qakFJ5vCOFg3or27v6GKx9l0B8hjT51B+BuY4OE4PV1mQgjQA4DuzdapmR7u+ks2Lhw1DWF60looLB885P38+gwL5LxzU4vjXMjdSF5xQsYur00BhFHZPCvSgyTBcF7ybFWzxyVnQO6rLD5OmVsjLmZPEa5CxLbkMWcBRbK6pAvEMDNHNRKLtPICg3b+oMFXniGFrYcqg8tqX+g6Fsdh5B+T0q/oz7E=" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="completed keyexchange" Nov 05 15:15:48 volumio go-librespot[11698]: time="2025-11-05T15:15:48Z" level=debug msg="completed challenge" Nov 05 15:15:49 volumio go-librespot[11698]: time="2025-11-05T15:15:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:49 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:49 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:52 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:52 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Nov 05 15:15:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:52 volumio go-librespot[11710]: go-librespot daemon starting... Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="app state loaded" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=info msg="zeroconf server listening on port 44939" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="obtained new client token: AABYV4E21u1J2QjV8/oCXBDZal1CJFOA3YF1GRFM2+IAMUDB45up61HhAnwyrq9VRitiz3ch2IzBPdEKNUMJ+8AwJ/p9rytl1NN9muoHH4XGqDH6o9c/eHfXiqYteSxuvOMDoaaUum3fSiocz60n/qNmAB1B8uEaRDf2Ora/9RZmVfS5S4HbsojmE4d1mIGun3uoeQrsWnZ9auSi2PN+kna1vQZChYyWiunSejOLLtRcySoMOqqBAAo=" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="completed keyexchange" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=debug msg="completed challenge" Nov 05 15:15:52 volumio go-librespot[11711]: time="2025-11-05T15:15:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:55 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:55 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Nov 05 15:15:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:55 volumio go-librespot[11733]: go-librespot daemon starting... Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=debug msg="app state loaded" Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:55 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:15:55 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=info msg="zeroconf server listening on port 44593" Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=debug msg="obtained new client token: AACzXRkVQcfo2ppWgcBIwO0MAFadv5+Hc4jfSG+7P9WUCgpuHtyjBCIfQsX6DGbEFqn88ppdD/aPctTzxmSMs8Y+hS2Vjciw8mZ3+ahRgRwpS4b/tOh2Isjsg8p/t/u16ic8RptSWUG+iEicBCuSRCctfrg9hrMXkQqhGwmQaFERyk7n200ZIsrQuapRo0kM/cSYN6ou+GbB0rc5N4dcfcYlIYmGi8hvQkPUh8SSrjywCtz11vBfGpo=" Nov 05 15:15:55 volumio go-librespot[11734]: time="2025-11-05T15:15:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:56 volumio go-librespot[11734]: time="2025-11-05T15:15:56Z" level=debug msg="completed keyexchange" Nov 05 15:15:56 volumio go-librespot[11734]: time="2025-11-05T15:15:56Z" level=debug msg="completed challenge" Nov 05 15:15:56 volumio go-librespot[11734]: time="2025-11-05T15:15:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:15:58 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:15:58 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:15:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Nov 05 15:15:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:15:59 volumio go-librespot[11741]: go-librespot daemon starting... Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="app state loaded" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=info msg="zeroconf server listening on port 39393" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="obtained new client token: AACB+csFRMfevU9w7rKDMPiPGhXM00DsSlOnOHI8IicLSEpApW8aOVpIrwAFBKCsqKysyVhkhRRKSvkIk5aNbSwVz9oQmNzxP/Yl8xfcyRtt1j7XgwOqvYrI3/vL0KH0E1CyFH51BanMra+YcdEHd71fX4ojXycETxMjexz49KIxKxuytKXlALO3FWwzpN777LslRG73uWFI3a5MhHRY4yJKswPc/y6uYykRqOkD+3gx4IOSBee/rSU=" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="completed keyexchange" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=debug msg="completed challenge" Nov 05 15:15:59 volumio go-librespot[11742]: time="2025-11-05T15:15:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:15:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:15:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:01 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:01 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Nov 05 15:16:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:02 volumio go-librespot[11750]: go-librespot daemon starting... Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=debug msg="app state loaded" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=info msg="zeroconf server listening on port 38435" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=debug msg="obtained new client token: AABGJIrv/r1xj7k2EVF4K7KJIPWj+PayOKwZeNzMXBZIGzDnPF6YFTC/HwMSDftncb2V83rvwlDvpTU6H8ATPZhWuo4aO9AsCW0F1SLg0zzz5/ZeXijA8wZ0PPiYlyJG/2/URanCsiil5UEikV7gFC9O/KmKvLgROdhp/S2vh9MZt8saC6erPwDvr5l1+uCwDfbVjs4keMSownEJg3e+Str+WoO/55mVKA3EU2fUojfIU2fMTZN2Qb4=" Nov 05 15:16:02 volumio go-librespot[11751]: time="2025-11-05T15:16:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:03 volumio go-librespot[11751]: time="2025-11-05T15:16:03Z" level=debug msg="completed keyexchange" Nov 05 15:16:03 volumio go-librespot[11751]: time="2025-11-05T15:16:03Z" level=debug msg="completed challenge" Nov 05 15:16:03 volumio go-librespot[11751]: time="2025-11-05T15:16:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:04 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:04 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:05 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:16:05 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:16:05 volumio volumio[11135]: info: Listing playlists Nov 05 15:16:05 volumio volumio[11135]: info: Listing playlists Nov 05 15:16:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Nov 05 15:16:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:06 volumio go-librespot[11773]: go-librespot daemon starting... Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="app state loaded" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=info msg="zeroconf server listening on port 34153" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="obtained new client token: AADvXiWQlKpywwk+PZ4BBzS2Q9qoYWqzMXp8lrbPriphnSyk4Ys7WKLx6aD7PRzIuxkjjhTpILtbPMLM1bNSFTR6aqJyeyf/yXG7oZ+ulA34Fcliica/vtYQ7S68iZCbyBrnpu3iC9LWGYvPV/1R5mZKCsnvdMmY7DT3gj82m+xzXTyCEKGZLA2S/xYHrjilLTqh45bVAj/VcmcBbWHeyIpNPqruF63kKAXF5qYsVEvIox592rCe3xA=" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="completed keyexchange" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=debug msg="completed challenge" Nov 05 15:16:06 volumio go-librespot[11774]: time="2025-11-05T15:16:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:07 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:07 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Nov 05 15:16:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:09 volumio go-librespot[11784]: go-librespot daemon starting... Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16:09Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16:09Z" level=debug msg="app state loaded" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16: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]" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16: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]" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16: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]" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16:09Z" level=info msg="zeroconf server listening on port 37917" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16:09Z" level=debug msg="obtained new client token: AAAHUlgiUzM8zWS76X03BYJHhbsXycGGfIEa88kqIjdSDZy+3lHBRZZWhlOl2LQOyRihhcPMqMElarLvigoX2MwCBY0mpy4cVlPuH/aaXT5EMNWXMn4oVRwpiHvyrR6xQQNQr7/yR/T5A4e8JjuQqMk1xjIcqbLyQPf8C9l/NE+jXU2vg1N9wHif+UZuKMVqNyCkpkTxV09YjlHU53l1Og7StowTy3hgvcvwVynMj1aBab1fhW6e1RI=" Nov 05 15:16:09 volumio go-librespot[11785]: time="2025-11-05T15:16:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:10 volumio go-librespot[11785]: time="2025-11-05T15:16:10Z" level=debug msg="completed keyexchange" Nov 05 15:16:10 volumio go-librespot[11785]: time="2025-11-05T15:16:10Z" level=debug msg="completed challenge" Nov 05 15:16:10 volumio go-librespot[11785]: time="2025-11-05T15:16:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:10 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:10 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Nov 05 15:16:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:13 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:13 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:13 volumio go-librespot[11792]: go-librespot daemon starting... Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="app state loaded" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=info msg="zeroconf server listening on port 39553" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="obtained new client token: AAA1SAqmHr+9ttMIQRcpAmHRPVT/wvdLr+ZBFZXC4AMoREdXjjLAdD+AOIUl6TWmMQ8LWeQHFkAniBNifaT/V1zpWm7dNlnj/Igun9+0poKEHNO/rPtb+aow7sa89SW3By2TrVzLwtR6b1k+30Hg4g3hmOjDv5KQCsVJNwyySrqCO6ZQWtb0h/425UCwXOjbmnOaGbYr937PV2IUUcyZM3cDEb4+rgjq/ppLPDusC+HF6jiz7kmuwlc=" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="completed keyexchange" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=debug msg="completed challenge" Nov 05 15:16:13 volumio go-librespot[11793]: time="2025-11-05T15:16:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:15 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:16:15 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:16:16 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:16 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Nov 05 15:16:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:16 volumio go-librespot[11814]: go-librespot daemon starting... Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=debug msg="app state loaded" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=info msg="zeroconf server listening on port 44091" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=debug msg="obtained new client token: AAA46wnFoSElCv5gDUatBO1DQq7IOPpPeMUGcTOT1FiUXQFQXZvbV3GCO/kvyGCqJBAB2g+W1UDhAu7hsjyrOnBU2W+Ve//U+j5elw/bx4t9edrP1N+YFsGw+YUtLW9iqKtpwMHB+iuw9tSUB2rF220Kqc+bVQruaPCZ+sl2njrIO1AcSWaUa2MYqgBJ0QE60gIKgBoe874qEKOYSD5yS7tZj65Gw12b7+BnOcqyHp1UMN6UXY4sKMg=" Nov 05 15:16:16 volumio go-librespot[11815]: time="2025-11-05T15:16:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:17 volumio go-librespot[11815]: time="2025-11-05T15:16:17Z" level=debug msg="completed keyexchange" Nov 05 15:16:17 volumio go-librespot[11815]: time="2025-11-05T15:16:17Z" level=debug msg="completed challenge" Nov 05 15:16:17 volumio go-librespot[11815]: time="2025-11-05T15:16:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:19 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:19 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Nov 05 15:16:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:20 volumio go-librespot[11822]: go-librespot daemon starting... Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="app state loaded" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=info msg="zeroconf server listening on port 46649" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="obtained new client token: AACe4MCq89zBMIzuNJ1h8ceIykOhyjWqNGrHfdMmOMYf9UArVmndO99KNczkUTUV1P4viJiObBafb27+IQk+GYgQy0/Fn+/45QOuav4uRiYu4biv/ica4VY+M5Oa4wmcw8ApipDFls+rOxgp2uUYUUMv/akQkJxqu5MtpJ19zhdl1+e2OfIStdIXBX+j/i9tLFvzj4DTCSaIcESkvZ6Ta/bk0nAeuimqTsKq2Y8RAz5B8r9WScK9P98=" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="completed keyexchange" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=debug msg="completed challenge" Nov 05 15:16:20 volumio go-librespot[11823]: time="2025-11-05T15:16:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:22 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:22 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Nov 05 15:16:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:23 volumio go-librespot[11846]: go-librespot daemon starting... Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=debug msg="app state loaded" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=info msg="zeroconf server listening on port 37193" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=debug msg="obtained new client token: AADwgihHMjK0qusXLgvFY0CLLt3GewC2jj7JCfZWYgXULtuugZzqqpi3fkc087PgU0WhcDb28DBDo/UK5aTMWc+Y/WyZTo/ASDGJpdoLiwxFgstN87TPAI58uvj6kxip0VmiWKyFIZm2mpGaQCgkiT+GLPQfZ4/nlJYxVZssigr58hkyVPtwDNeJa6o1iaUKtqbyoTKnGPYf4/5H+u8Gu5IzLQWSzFFNAC1r1G4+ZfYaW/cwVRK6NxM=" Nov 05 15:16:23 volumio go-librespot[11847]: time="2025-11-05T15:16:23Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 05 15:16:24 volumio go-librespot[11847]: time="2025-11-05T15:16:24Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 05 15:16:24 volumio go-librespot[11847]: time="2025-11-05T15:16:24Z" level=debug msg="completed keyexchange" Nov 05 15:16:24 volumio go-librespot[11847]: time="2025-11-05T15:16:24Z" level=debug msg="completed challenge" Nov 05 15:16:24 volumio go-librespot[11847]: time="2025-11-05T15:16:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:25 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:25 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:25 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:16:25 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:16:25 volumio volumio[11135]: info: Listing playlists Nov 05 15:16:25 volumio volumio[11135]: info: Listing playlists Nov 05 15:16:27 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Nov 05 15:16:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Nov 05 15:16:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:27 volumio go-librespot[11869]: go-librespot daemon starting... Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="app state loaded" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=info msg="zeroconf server listening on port 46297" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="obtained new client token: AADWiZ3vTXVEhm56sauPtC9uHY1zLolwcpDbF9XIA44DCdE8PHfnaeIAp9E+d8MROzKhoH5S2eT2FjTtjbjEhuqXcqLKTBM+LeJ9+ou1Ckink4Xsd0rvN2l9HDSTRsX7jKiaqq0Q9D+4x7JEy/O8UYAIXh+t3imDvjk2RFBlKqID2DhSR2PfgdwrZzblYmuQQnNW+9wJ9MxhlEgxXqQee4Etbl+dYeeXNOFGOIY0byjV40JXHOq9" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="completed keyexchange" Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=debug msg="completed challenge" Nov 05 15:16:27 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Nov 05 15:16:27 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Nov 05 15:16:27 volumio go-librespot[11870]: time="2025-11-05T15:16:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:28 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:28 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Nov 05 15:16:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:30 volumio go-librespot[11891]: go-librespot daemon starting... Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="app state loaded" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=info msg="zeroconf server listening on port 40131" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="obtained new client token: AAB9mB7MsgAw26BITnLg1RsL5Mlp+uSpjw40RVrFhsYCdBSDz3Xk/r/62MI9c0hQAtkiCNaegaQFn7jfGsJpDS4Qv/f6taN1XjVAuVITun1UWlBxcAzxdR90suzwDR62zraUJK3B68Uhw0yEOpYGXozJUevZvI9l1IIBTfWUL2FOcP/xhJ8G6qGd5xJJCDmg2vxChnj7iViUGYCMMiSac49HReJk6AO7gnfz6KPjLzN9jdaubFVLF5s=" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="completed keyexchange" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=debug msg="completed challenge" Nov 05 15:16:30 volumio go-librespot[11892]: time="2025-11-05T15:16:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:31 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:31 volumio volumio[11135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Nov 05 15:16:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 15:16:34 volumio go-librespot[11913]: go-librespot daemon starting... Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=info msg="running go-librespot 0.4.0" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="app state loaded" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=info msg="zeroconf server listening on port 33249" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="obtained new client token: AAATZyDH6dumT6/FECBxv6CjNRdOhGZ0kiVTxCvdDjR0XATzkG4MlzyVZ0QLQa3NufwakDt7riqZpC3cAjFORSA3gRyWDA7fIpSaBeJ2/fjjZIZZoAJauhGRM0d42cMwUGOWYv2lSBHP/NSB1tqpeE/aINgUSbkd1H6bNyC2Rx3wACZLX1U+2PnvCWCNwpRsLrl/H1VymTVAlb5afKZpiE5dBzM/qtVOpANbsUPwNAxkCpaKTOhc" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 15:16:34 volumio volumio[11135]: info: Initializing connection to go-librespot Websocket Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="completed keyexchange" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="completed challenge" Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=debug msg="new websocket client" Nov 05 15:16:34 volumio volumio[11135]: info: Connection to go-librespot Websocket established Nov 05 15:16:34 volumio go-librespot[11914]: time="2025-11-05T15:16:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 15:16:34 volumio volumio[11135]: info: Connection to go-librespot Websocket closed Nov 05 15:16:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 15:16:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 15:16:35 volumio volumio[11135]: info: CoreCommandRouter::volumioGetState Nov 05 15:16:35 volumio volumio[11135]: info: CorePlayQueue::getTrack 0 Nov 05 15:16:37 volumio volumio[11135]: info: Getting Spotify volume Nov 05 15:16:37 volumio volumio[11135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 15:16:37 volumio volumio[11135]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 15:16:37 volumio volumio[11135]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 05 15:16:37 volumio volumio[11135]: errno: -111, Nov 05 15:16:37 volumio volumio[11135]: code: 'ECONNREFUSED', Nov 05 15:16:37 volumio volumio[11135]: syscall: 'connect', Nov 05 15:16:37 volumio volumio[11135]: address: '127.0.0.1', Nov 05 15:16:37 volumio volumio[11135]: port: 9879, Nov 05 15:16:37 volumio volumio[11135]: response: undefined Nov 05 15:16:37 volumio volumio[11135]: } Nov 05 15:16:37 volumio volumio[11135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 15:16:37 volumio sudo[11935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-05 15:15' Nov 05 15:16:37 volumio sudo[11935]: 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"