Nov 04 23:26:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 139. Nov 04 23:26:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:00 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:26:00 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:26:00 volumio volumio[28188]: info: Listing playlists Nov 04 23:26:00 volumio volumio[28188]: info: Listing playlists Nov 04 23:26:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:00 volumio go-librespot[28521]: go-librespot daemon starting... Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="app state loaded" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=info msg="zeroconf server listening on port 37727" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="obtained new client token: AAAtG8IiGgjgPqvWBaHW2Xuse5yr7Dem1tnsxbYmdJXLfQK+6mpKlSV6TVehBUneU2AKGUvsr+zgRxG5JMg7W7+WR/e0gR0LLMQzV+fF81FMr3slu1gOM1P0naCvmRwt/hfBUuWBqCoyv1WPtdbHAgPY03dVtrFO8x1qXdZX6iTktEoZ5szGpw05zAcAXlK9aBUfRnvC2BFdhcAcrp9HKcNcmXUuRz6NB4aXEIPJBimPwnhlzCc0" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="completed keyexchange" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26:00Z" level=debug msg="completed challenge" Nov 04 23:26:00 volumio go-librespot[28522]: time="2025-11-04T23:26: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 04 23:26:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:01 volumio volumio[28188]: info: MYVOLUMIO: Adding device Nov 04 23:26:01 volumio volumio[28188]: info: MYVOLUMIO: Evaluating Server Nov 04 23:26:01 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:01 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:01 volumio volumio[28188]: info: Setting Geolocation for MyVolumio to eu3 Nov 04 23:26:01 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:01 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:01 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:01 volumio volumio[28188]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 04 23:26:02 volumio volumio[28188]: info: Updating MyVolumio device info Nov 04 23:26:02 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:02 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:02 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:03 volumio volumio[28188]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 04 23:26:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 140. Nov 04 23:26:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:03 volumio go-librespot[28544]: go-librespot daemon starting... Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="app state loaded" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=info msg="zeroconf server listening on port 46061" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="obtained new client token: AAAGlvxFJzDeI+fPJEF6wdJN0+hhr5ewrKM40AWwcGizSoygQYEaGRMtzQEpiSf+zQy3JEQAIHZMWpMGNZRnl+Vqv3fty9sad6XW733JMG3Ob5M7yf4RviGWIT6CayiHdmS5BIjPlpPJoAj28MgTF4FgXfRp0qzlK0ZJZMBUQYyao+KPOh+LzckUEwHkViFEhdxCRMXlSXrdZnrWCSBef/RGyKYam87M8rz53Rc6mqKwENZ6bVlAmNc=" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="completed keyexchange" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26:03Z" level=debug msg="completed challenge" Nov 04 23:26:03 volumio go-librespot[28545]: time="2025-11-04T23:26: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 04 23:26:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:04 volumio volumio[28188]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 04 23:26:04 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:04 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 141. Nov 04 23:26:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:07 volumio go-librespot[28567]: go-librespot daemon starting... Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="app state loaded" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=info msg="zeroconf server listening on port 32975" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="obtained new client token: AAAc/txiSCyKMuIcuwVZqKWC0ZxUUoEBi1Oa5jQ8njMpamAhyY6Atuq/BN8nLGrGYD9H3DqjwQH+bNbvaksBoj26Oc62IFYlmZnq0P4U0SlyvLugkaN1Ache3wj71TDn4I1vhs+1ThmJkJX22czrMtuzG7W2l2RuWnhvZYfpEVurxgjqV+6r1a2zmySs7b6xLwe2tPnKZIMe308tkXvJtP3yiger2HfMkWM2GUaoh3L1NboR3dqC" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" 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 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.65.9:80: connect: connection refused" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="connected to ap-guc3.spotify.com:4070" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="completed keyexchange" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26:07Z" level=debug msg="completed challenge" Nov 04 23:26:07 volumio go-librespot[28568]: time="2025-11-04T23:26: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 04 23:26:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:07 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:07 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:10 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:26:10 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:26:10 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 04 23:26:10 volumio volumio[28188]: 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 04 23:26:10 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:26:10 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:26:10 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:10 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 142. Nov 04 23:26:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:10 volumio go-librespot[28578]: go-librespot daemon starting... Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=debug msg="app state loaded" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26: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-gae2.spotify.com:80]" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" 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 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" 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 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=info msg="zeroconf server listening on port 38573" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=debug msg="obtained new client token: AAAtcLkZGuBK29uzH9i3hkg4avZ6EWhgYNRdr0LY8NTWcsaFB5V33NDJHJl7Ye1IYI3TIVs86orgH2DKO58m/EzvuqU/bl9rsSCSLTaTrPTEm8MJqgLyLwukwgx4hFJR60VjX8maSgX3+OVt1LQ3rAQOdC3WDcY8Z2dFhir9NccChm9jdPa48un9Ekk2QXpXH7dzEiNQAb/0avcwU7HeTU59j77V8/F0jFRtrRLxcV3FISj9IzGhVoQ=" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=debug msg="completed keyexchange" Nov 04 23:26:10 volumio go-librespot[28579]: time="2025-11-04T23:26:10Z" level=debug msg="completed challenge" Nov 04 23:26:11 volumio go-librespot[28579]: time="2025-11-04T23:26:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:26:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:13 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:13 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 143. Nov 04 23:26:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:14 volumio go-librespot[28600]: go-librespot daemon starting... Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="app state loaded" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=info msg="zeroconf server listening on port 37463" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="obtained new client token: AADjOgkEC2gDvmAmhAVOpT/bHJr7gkzf7gZZ2eo4MIK4VzMY/r1NroPLg0Qi4YngXevNWsaRqQPb4ZNUvLlu2cKGVaOL3ExlWzSQ5gThFRc6FJ1J8SalIDYQ/A7UU3Quv+He4NaLqvpeIBsFQSd1T3S6BIV1mO3lB6OfL+TIKE6GC2va5htTewYZJCbur6NDwlXX3TySruGq+PJaKAoEJCGu2Ryb4gGNLLt15kpgZKMkZLpXcHuJjZU=" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="completed keyexchange" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26:14Z" level=debug msg="completed challenge" Nov 04 23:26:14 volumio go-librespot[28601]: time="2025-11-04T23:26: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 04 23:26:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::volumioGetBrowseSources Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 23:26:15 volumio volumio[28188]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 04 23:26:15 volumio volumio[28188]: info: Completed starting MyVolumio Plugin Nov 04 23:26:15 volumio volumio[28188]: [Metrics] CommandRouter: 32s 32.07ms Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::volumiosetStartupVolume Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:26:15 volumio volumio[28188]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 23:26:16 volumio volumio-remote-updater[874]: Test mode disabled Nov 04 23:26:16 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 04 23:26:16 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 04 23:26:16 volumio volumio[28188]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 04 23:26:16 volumio volumio[28188]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 04 23:26:16 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:16 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 144. Nov 04 23:26:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:17 volumio go-librespot[28617]: go-librespot daemon starting... Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=debug msg="app state loaded" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" 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 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" 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 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=info msg="zeroconf server listening on port 33473" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=debug msg="obtained new client token: AABWFqLFclYcn9yz+68y3l9NNJd9NKgmSXBWJBgwN9foXFCUhj6BcwKiVOKK4owzqtFqTmEhdRhOH+e7xnCE3yDTcrTzkw63q6Qo8HnQuCAV0Pw+kVlkCSXEZttyJ8fOEju/XC1k4mdZ4EZdadZZmgA2tvowiG4wTsbhgb0mdX1M3xcLfvL2TfHQUpnJMzRRuk6rr7icxNQ/ohK3C6SJ6/IGqEGWgi8DE1KmHTFjVAtaAjiWNI28Gb0=" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=debug msg="completed keyexchange" Nov 04 23:26:17 volumio go-librespot[28618]: time="2025-11-04T23:26:17Z" level=debug msg="completed challenge" Nov 04 23:26:18 volumio go-librespot[28618]: time="2025-11-04T23:26:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:26:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:19 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:19 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:20 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:26:20 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:26:20 volumio volumio[28188]: info: Listing playlists Nov 04 23:26:20 volumio volumio[28188]: info: Listing playlists Nov 04 23:26:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 145. Nov 04 23:26:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:21 volumio go-librespot[28626]: go-librespot daemon starting... Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="app state loaded" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=info msg="zeroconf server listening on port 35335" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="obtained new client token: AACGYLPF0fQpFHP5DptzfX+cXMYEU66ADdXUlUClMoVT3LXFay4lLnWc1qXBWkI3/NusWegLPr6Rok8ilJK00AFFdS6tmQsTrfp1WDzEnBbxcmBVwlS/azp2Hbrb0iWJfF1rTIAtdwdaooRoLMazgPEAvhpcwHhPARgJo/lmJQ+CZUsk0LG8f5+TsS9Xes+ZryA8XtpwgnbmVQhYXZ3mGlKqh/fUFYb5id/rzxJ6Rvqhlv47IuaHwh8=" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="completed keyexchange" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26:21Z" level=debug msg="completed challenge" Nov 04 23:26:21 volumio go-librespot[28627]: time="2025-11-04T23:26: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 04 23:26:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:22 volumio volumio[28188]: info: BOOT COMPLETED Nov 04 23:26:22 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:22 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 146. Nov 04 23:26:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:24 volumio go-librespot[28649]: go-librespot daemon starting... Nov 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" level=debug msg="app state loaded" Nov 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26: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-gae2.spotify.com:80]" Nov 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" 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 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" 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 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" level=info msg="zeroconf server listening on port 44863" Nov 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" level=debug msg="obtained new client token: AAB0Ih8iQtyCXCRBKCuvqSuD5z2VPh3I2XXRLuFckCBLjdaDGx4DUkuqFtAL+kKWQbColcoEeBasXA/c1wwJEbRtEDZogRzoTlaCvksYbVfb37NjBzzX7RbUnnznZsZtQRzeMcZ0lMcJ7dov09ksTi6R6ud/MU8nsWt5Fn1kEHu20/+gF+5/kEm9uvpWgOwh1w35osT4eiL72EdbFbfx4fwmwV0LdrFAw2Cg9w4G8AqzQtzFbaitwNo=" Nov 04 23:26:24 volumio go-librespot[28650]: time="2025-11-04T23:26:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:25 volumio go-librespot[28650]: time="2025-11-04T23:26:25Z" level=debug msg="completed keyexchange" Nov 04 23:26:25 volumio go-librespot[28650]: time="2025-11-04T23:26:25Z" level=debug msg="completed challenge" Nov 04 23:26:25 volumio go-librespot[28650]: time="2025-11-04T23:26:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:26:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:25 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:25 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 147. Nov 04 23:26:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:28 volumio go-librespot[28657]: go-librespot daemon starting... Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=debug msg="app state loaded" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" 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 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" 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 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=info msg="zeroconf server listening on port 42931" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=debug msg="obtained new client token: AAC4qoWtmDpqhQcKl2NmDgMYSPArMqZ2fBoxiBcC+PGVSElqKfYcFKB2ZDbETZE0W50CnZu0fwgEZ2MHnnnKotKxmHB7LCnkkSM1PvJHWo8Ds/WrNQn0COIPfVGyIHWE1ckrnLf36JjdARuzQj9jQxLGMYVfiRldVqtJuT4FRDExbHSATJEpEU0PZjbS1QSN+YrgYOrIVjngu5LiCpxbRU+r+0ysdsA59anVbREMbZprRZ5DoNvP/NA=" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=debug msg="completed keyexchange" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26:28Z" level=debug msg="completed challenge" Nov 04 23:26:28 volumio go-librespot[28658]: time="2025-11-04T23:26: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 04 23:26:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:28 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:28 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:30 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:26:30 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:26:31 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:31 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 148. Nov 04 23:26:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:31 volumio go-librespot[28668]: go-librespot daemon starting... Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="app state loaded" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=info msg="zeroconf server listening on port 33921" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="obtained new client token: AADlhw8Ap9oZyG594qIwg2Xzsdtw/4wEJxdj6MJVLROdHhbu/Jue/hWO6KCv2aT3xaNgrHs8b0ImepwgHvdeLTqOYgSVeQcbeRDIYkVxRump3xVtjPBVbcUmN5ZDsR4iDQtNM2pDUlICAGE7BY9chA9NL0dNx7wNwVeH2aF51bWI+3WZBJj0DHzeqRxdOEbGlCnr3Ct/9Jp3z/8Qw+6Xsdlf1KV9Vh/6zYxXLCNYH1fDsa5XmG9upsM=" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="completed keyexchange" Nov 04 23:26:31 volumio go-librespot[28669]: time="2025-11-04T23:26:31Z" level=debug msg="completed challenge" Nov 04 23:26:32 volumio go-librespot[28669]: time="2025-11-04T23:26:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:26:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:34 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:34 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 149. Nov 04 23:26:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:35 volumio go-librespot[28690]: go-librespot daemon starting... Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="app state loaded" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=info msg="zeroconf server listening on port 42077" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="obtained new client token: AACoty3fMvO9FFm8UF4JUpHmVu8CYi1ESC5/bnG3AifYzjWNaiSmAo6I1dALW6hDUIIPNktpMQKYJsPSaRwNAV36K6cLjdElLI0D64BD7HJ8Nq3UnEta9h4DbHtXP0xXvNtdI057kC6+YosP1k2NScU1Nv/EoDYSDWUawfkCF2+Jq9UnuIQeC331dyXHWtvSKeUvgh/KhKNog/Q9RgsRWhIpJ/IehkznJYP/rSNwUS0Kj6gampq+Rr4=" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="completed keyexchange" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26:35Z" level=debug msg="completed challenge" Nov 04 23:26:35 volumio go-librespot[28691]: time="2025-11-04T23:26: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 04 23:26:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:37 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:37 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 150. Nov 04 23:26:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:38 volumio go-librespot[28698]: go-librespot daemon starting... Nov 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26:38Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26:38Z" level=debug msg="app state loaded" Nov 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26: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 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26: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 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26: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 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26:38Z" level=info msg="zeroconf server listening on port 33085" Nov 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26:38Z" level=debug msg="obtained new client token: AAAdpsV2sLSmf3ZtSrWfXhDvEpOb7tO/zqC54PaVuLKdWoxwznFW8p6CMeuA1BSsvSIurss5D+s0vRYS9HzmBwDJk3UbFtDvOCXEHnNmtTH/SyIVDEvn8EIOwFRY4TnZzXjfnjonG/XwXnc6l45q2/K7qXvKYPRtzrtnZcoije4vMykOHKW0r7zvo2yviAaUAfUNLdXFifY8lw6N6YC8dQWwTfavcr9ks+S/i2tpvw+av9ApzjcImic=" Nov 04 23:26:38 volumio go-librespot[28699]: time="2025-11-04T23:26:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:39 volumio go-librespot[28699]: time="2025-11-04T23:26:39Z" level=debug msg="completed keyexchange" Nov 04 23:26:39 volumio go-librespot[28699]: time="2025-11-04T23:26:39Z" level=debug msg="completed challenge" Nov 04 23:26:39 volumio go-librespot[28699]: time="2025-11-04T23:26:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:26:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:40 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:26:40 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:26:40 volumio volumio[28188]: info: Listing playlists Nov 04 23:26:40 volumio volumio[28188]: info: Listing playlists Nov 04 23:26:40 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:40 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 151. Nov 04 23:26:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:42 volumio go-librespot[28706]: go-librespot daemon starting... Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="app state loaded" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=info msg="zeroconf server listening on port 34371" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="obtained new client token: AABqbaNC0nswqUcguqXwmrdnIrpE7Rr5DoxSSdTv0iUqaR7aaq66I3zkO4G2neM1ayx7bJC/wCjE9Ls2gcdBRDcqYec0L5bdSrQtpgkfwKPJ7IlsMXLfSblmuxexPh1auOiDlaNJYSllj4k1+I23mygYZ4usu7Qq5thEMVJ4vpD0iWZhnC3gQnUs120SeyEgG71kwmmAxHNxTt6F8IU2T5HFfILZNKT5/YlJzLw0426T3Y524xUu1dE=" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="completed keyexchange" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26:42Z" level=debug msg="completed challenge" Nov 04 23:26:42 volumio go-librespot[28707]: time="2025-11-04T23:26: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 04 23:26:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:43 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:43 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 152. Nov 04 23:26:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:45 volumio go-librespot[28729]: go-librespot daemon starting... Nov 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26:45Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26:45Z" level=debug msg="app state loaded" Nov 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26: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 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26: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 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26: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 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26:45Z" level=info msg="zeroconf server listening on port 36359" Nov 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26:45Z" level=debug msg="obtained new client token: AADU0F4M2O+b/ot8hNuB71R472bSbi6N7tDUaS0+45e72DR2g6yBvgOVtzFfJ8PkF2/7vBcdVLC5vOCOXHp8P9cV4JHbAvoNJo2jo3lbKkjl8IjpYjvLhA7ZyNLvwgrYO2aUO5Ha+oK3y5wcWrvIlnacWu6C9rPt7ME2Lc86i/Ja4CSqxYSZUAomLy2on1I79Sx1a0NKTZUWgRtlYEeZcBDC8rHxG8CqyiE/cQZdpJFCvoSq6sQUaEg=" Nov 04 23:26:45 volumio go-librespot[28730]: time="2025-11-04T23:26:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:46 volumio go-librespot[28730]: time="2025-11-04T23:26:46Z" level=debug msg="completed keyexchange" Nov 04 23:26:46 volumio go-librespot[28730]: time="2025-11-04T23:26:46Z" level=debug msg="completed challenge" Nov 04 23:26:46 volumio go-librespot[28730]: time="2025-11-04T23:26:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:26:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:46 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:46 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 153. Nov 04 23:26:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:49 volumio go-librespot[28737]: go-librespot daemon starting... Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="app state loaded" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=info msg="zeroconf server listening on port 45133" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="obtained new client token: AACoBctnjQIIzctvbx7w5PzVc1WVP3xYXRI2kSThaS80Z5I0tImDaOUbSmLWu8EliiYB2HVdheQ2aAZaVcxVayuBODIoI3IqAcm07AJIodtuz6trvsrUUHQKSIz0vbGZBL3QlOQ5cZd/6JIThJARsFZre611lTxQsHDnXlQxJAenwxy6L7vOwuXUahajewpfMZipTAA9d65jRqTh1ptyo/bgKFYOPKxGPNQCgTZ7tdxWgIjiy87JzRs=" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="completed keyexchange" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26:49Z" level=debug msg="completed challenge" Nov 04 23:26:49 volumio go-librespot[28738]: time="2025-11-04T23:26: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 04 23:26:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:49 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:49 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:50 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:26:50 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:26:52 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:52 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154. Nov 04 23:26:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:52 volumio go-librespot[28749]: go-librespot daemon starting... Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="app state loaded" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=info msg="zeroconf server listening on port 43187" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="obtained new client token: AADBJXJCw+1nySty6eBosyXIw1lbCSpJ9dfd9+7WPxykGteTmfHdLL8Qao7sqr2fQzrCHUhsZWfY2Y2rkuJVW49wskfnPlJr8glqG0qw4YFcWxAcXvupTA4Ufamy4WElAVIV++lY5rzCj5bnw1LIQmyVVn5D9Cs3hbndpxIr8V+aXBTOxzlkAXuBNNhA8Wk5TuvOnxnj4BecDb0jv0/i1fRFea8slE8mS7dCovMNdgPJgVMu7FnK3Es=" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="completed keyexchange" Nov 04 23:26:52 volumio go-librespot[28750]: time="2025-11-04T23:26:52Z" level=debug msg="completed challenge" Nov 04 23:26:53 volumio go-librespot[28750]: time="2025-11-04T23:26:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 23:26:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:55 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:55 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 155. Nov 04 23:26:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:56 volumio go-librespot[28771]: go-librespot daemon starting... Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=debug msg="app state loaded" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" 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 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" 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 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" 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 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=info msg="zeroconf server listening on port 33141" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=debug msg="obtained new client token: AADwFJQ5WK3EzUpJeZBrIJiFv88+LE1wSvnSL5iiWdxTqAFHeiVP1gmDk9OkYAs71D0C4DpceToQdhE7grGrzgYSncll9WhuwKd7hqTeZi8LBqN+oMZyIHyKBEdZHyoORGJsl4e9ncU/+hU3vgvDRA2fTvdU9a5XPy/VwpaOkRozlafMFinVwVN0vyo4ziWqtXCzjM4SQUK3JGMn2VkbWYN/yNdzE8Xyf+72ycwuRtxCXijCT+z6D/c=" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=debug msg="completed keyexchange" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26:56Z" level=debug msg="completed challenge" Nov 04 23:26:56 volumio go-librespot[28772]: time="2025-11-04T23:26: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 04 23:26:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:26:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:26:58 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:26:58 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:26:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 156. Nov 04 23:26:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:26:59 volumio go-librespot[28780]: go-librespot daemon starting... Nov 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" level=debug msg="app state loaded" Nov 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" 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 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" 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 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" level=info msg="zeroconf server listening on port 41789" Nov 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" level=debug msg="obtained new client token: AACnXbJQf1NWdLtPi5TIj5kYKDyH5S2nDQdNtp3ULotN2obcBrjBgvIjuIqP4up6/ff4yIbgTL1bb2CUrP9VbU8DwR0c94pBVlGSnBO2lIyGtRrr9xDR/ZmNZjfgJbSjSmLj2f3AFJXpOQmu3P9kTKvazgZWLh64smwVAFIBTXKq1QtW8x0kUukSCvdYQc0Sr7oonMbzIQ3dofjt6QFG0dgI2JoK8U6aGDRqykmyqtT/vegKXAlsAQw=" Nov 04 23:26:59 volumio go-librespot[28781]: time="2025-11-04T23:26:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:00 volumio go-librespot[28781]: time="2025-11-04T23:27:00Z" level=debug msg="completed keyexchange" Nov 04 23:27:00 volumio go-librespot[28781]: time="2025-11-04T23:27:00Z" level=debug msg="completed challenge" Nov 04 23:27:00 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:27:00 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:27:00 volumio volumio[28188]: info: Listing playlists Nov 04 23:27:00 volumio volumio[28188]: info: Listing playlists Nov 04 23:27:00 volumio go-librespot[28781]: time="2025-11-04T23:27: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 04 23:27:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:01 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:01 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 157. Nov 04 23:27:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:03 volumio go-librespot[28788]: go-librespot daemon starting... Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=debug msg="app state loaded" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27: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 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27: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 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27: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 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=info msg="zeroconf server listening on port 41801" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=debug msg="obtained new client token: AABxp27oDI280cOLn2C379U/nk03uEVesY/1e4w31YOl6zNUSqSxsZneIeHC7JtsOpB/aafNB7DA+DZBgloJZEjtE1kaVFOTKXPWc6u/V3VWOf7jxZOyG/Sd4Mmp80FJC+IKGUzsi32jk7AnNV4dXCCvTEHBkWwfpu15mIaeDT5YdwzLwLOUngwor9JqlDThfENixico6I5J2e3doKHEOthy0OGPDSBPF/cU7IJ7TtjWAPH3DB25yus=" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=debug msg="completed keyexchange" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27:03Z" level=debug msg="completed challenge" Nov 04 23:27:03 volumio go-librespot[28789]: time="2025-11-04T23:27: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 04 23:27:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:04 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:04 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 158. Nov 04 23:27:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:06 volumio go-librespot[28812]: go-librespot daemon starting... Nov 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27:06Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27:06Z" level=debug msg="app state loaded" Nov 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27: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 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27: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 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27: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 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27:06Z" level=info msg="zeroconf server listening on port 42625" Nov 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27:06Z" level=debug msg="obtained new client token: AADS3sucKPUqozqflnD2fk1w5vlOJOITUwJJ384c2HkUuxGDsm0zLDA2K2HOHETippMelZR6BW184ODuELBtWijYmE1Ycp4eXvpZkLXpWh/RcSiGJZsKcLkB8iYUAA7BGz/FiupAPqvVuwuP1u93gMgM4u9cyUfvp8uwFE5WYLfI+uQ2QtDvC1n6afiyjxzRp9soXph8Yh9r27tegdbckEzKfPGp1bju/qCDCJQE2Ii8jPxbMugERkI=" Nov 04 23:27:06 volumio go-librespot[28813]: time="2025-11-04T23:27:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:07 volumio go-librespot[28813]: time="2025-11-04T23:27:07Z" level=debug msg="completed keyexchange" Nov 04 23:27:07 volumio go-librespot[28813]: time="2025-11-04T23:27:07Z" level=debug msg="completed challenge" Nov 04 23:27:07 volumio go-librespot[28813]: time="2025-11-04T23:27: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 04 23:27:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:07 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:07 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:10 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:27:10 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:27:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 159. Nov 04 23:27:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:10 volumio go-librespot[28823]: go-librespot daemon starting... Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=debug msg="app state loaded" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27: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 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27: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 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27: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 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=info msg="zeroconf server listening on port 35349" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=debug msg="obtained new client token: AAAu5d4jtFSgG/wxNFJ8qz0RpYC+VhrqpzG2FALeYhK6RAhVctyw9sGU4AZF6+6lmdIS1ZZN8HUkW65NuQMbHjBSoey57jVm3hQ3RF75G+uj/TJ5gBWrJ42G/Mi3G2YEhUGT1Fgcs1aiiA49RObODkAnZI1qPw8gzNqTqIiC0iH1E9fDx2xrNBc3sejlGamgjSapj5m2prq6QBph+bmOkLBCT5igKJcUxwnhQYJJ4bEMAzvzT6bGZXs=" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=debug msg="completed keyexchange" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27:10Z" level=debug msg="completed challenge" Nov 04 23:27:10 volumio go-librespot[28824]: time="2025-11-04T23:27: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 04 23:27:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:10 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:10 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:13 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:13 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 160. Nov 04 23:27:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:13 volumio go-librespot[28831]: go-librespot daemon starting... Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=debug msg="app state loaded" Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" 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 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" 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 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" 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 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=info msg="zeroconf server listening on port 36067" Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=debug msg="obtained new client token: AABfJ+mtqA925ZBiPqVyTJ8qPe18ZYdNJ5uqLaxlFsNh1nU1kJI4VCVk8LoAorOBQyvoVNkvnZd3eZWZsSWIpGawAbe49vx/Xr0sMZTHtDMVikKegEHZjNOm/vvVlB1+i+UFwzhVc0+2EZOG2tBs00JzQDsD9Fw56ZMwujfk2giLqVgmx7DGJvuq3BEFamtUJUXLzVbdnfpFUzgxGsqa1np9gRW5FkFtD7j7d68Qjtk6LZPkTDptNu0=" Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=debug msg="completed keyexchange" Nov 04 23:27:13 volumio go-librespot[28832]: time="2025-11-04T23:27:13Z" level=debug msg="completed challenge" Nov 04 23:27:14 volumio go-librespot[28832]: time="2025-11-04T23:27: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 04 23:27:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:16 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:16 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 161. Nov 04 23:27:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:17 volumio go-librespot[28853]: go-librespot daemon starting... Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="app state loaded" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=info msg="zeroconf server listening on port 36271" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="obtained new client token: AACbUSQf4HFlrAT3ZvMZzA8/AL9w1P+5o5ooTYUXcBKpaUsBdY68ntzvV7tBjHq8QMoONpQODRUVDiqpHuF9ky/cn89G4cDLDbQFqOoE6Xd52xtu9zviVeqUNtnNWin2yQH1tLz58mUMBzySYtpdKgzk95vdW4nK2O3oHh9rDy0+YymB7FtQm1Cilm4YsSB3Vc6a0pdMPsTd4CoywLsEibVeTz/NRAiy2yN91EFl7Igla3/DWudvTCQ=" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="completed keyexchange" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27:17Z" level=debug msg="completed challenge" Nov 04 23:27:17 volumio go-librespot[28854]: time="2025-11-04T23:27: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 04 23:27:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:19 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:19 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:20 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:27:20 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:27:20 volumio volumio[28188]: info: Listing playlists Nov 04 23:27:20 volumio volumio[28188]: info: Listing playlists Nov 04 23:27:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 162. Nov 04 23:27:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:20 volumio go-librespot[28861]: go-librespot daemon starting... Nov 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" level=debug msg="app state loaded" Nov 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" 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 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" 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 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" level=info msg="zeroconf server listening on port 40729" Nov 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" level=debug msg="obtained new client token: AAA3JYXpwmzSyghdFqix6s22/6J0K/Lxko5h5obXiC9MA3KyyvdIj+SAek8xShucS1pCwiierONoK3kTMKKFzvQ+PH67nFftZjp2wRHmMv74OVH+M7mJPYDpzwxUm65PwbCIHTMJjSydI8M8e8gHXYACxvP2hLG/UkfooFS5JZGcBW4TyhxhA6RILVkqrTq7kJ2HEGDCMsLO7Kf2bHUVRGHwxwmrsE56VvNgSkHWu1W3Sq37lxwnCQo=" Nov 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" 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 04 23:27:20 volumio go-librespot[28862]: time="2025-11-04T23:27:20Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 04 23:27:21 volumio go-librespot[28862]: time="2025-11-04T23:27:21Z" level=debug msg="completed keyexchange" Nov 04 23:27:21 volumio go-librespot[28862]: time="2025-11-04T23:27:21Z" level=debug msg="completed challenge" Nov 04 23:27:21 volumio go-librespot[28862]: time="2025-11-04T23:27: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 04 23:27:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:22 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:22 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 163. Nov 04 23:27:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:24 volumio go-librespot[28883]: go-librespot daemon starting... Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="app state loaded" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=info msg="zeroconf server listening on port 33531" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="obtained new client token: AADp5SEam5hz/0coQgsHqNQmduMv+HJyxkKYgAZQvxp1mC5HWju+smk34i3W6b3H3Nf3rUjZDRwvVuDuuhUk/53YN5O7c9O7lC46OflLoJ/CeT6Fmp42YI9uZIBzlIRYdFCaVu00Scp2AiZFBDv9yZu6VZDtSgEs3vCZ6c4fveG3TsWU6H+r3rx5s6XMT+M1JC82oS6l8fFREybNyQPQt2TdC/q4YyxVjYiaQ4jViW+mRqkfGyisDCc=" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="completed keyexchange" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27:24Z" level=debug msg="completed challenge" Nov 04 23:27:24 volumio go-librespot[28884]: time="2025-11-04T23:27: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 04 23:27:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:25 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:25 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 164. Nov 04 23:27:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:27 volumio go-librespot[28891]: go-librespot daemon starting... Nov 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27:27Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27:27Z" level=debug msg="app state loaded" Nov 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27: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 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27: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 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27: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 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27:27Z" level=info msg="zeroconf server listening on port 44135" Nov 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27:27Z" level=debug msg="obtained new client token: AACfjK39hluVKe6+umrh3Qnrq9pp3EkGyLWWgNEmvLOS6pyIBcSOT/5MhDtk8+O7mH2o2ok1RONrG2RA9QDe6Elp7A4k7k0z3I9vkjzOI4mw/ZBNKBh7QgoirRMROPsXN9FZrJ/GjlwGY1pwbYNASKEOL0ToNCh0qAm7kKcxMaBxlYpJJP0auCeQty4C1FfcdTiHgDaDmR/wSAIIi+gFa+1SdOzzljmFjYkbCM8eh7UNaNr4kLW8Y3g=" Nov 04 23:27:27 volumio go-librespot[28892]: time="2025-11-04T23:27:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:28 volumio go-librespot[28892]: time="2025-11-04T23:27:28Z" level=debug msg="completed keyexchange" Nov 04 23:27:28 volumio go-librespot[28892]: time="2025-11-04T23:27:28Z" level=debug msg="completed challenge" Nov 04 23:27:28 volumio go-librespot[28892]: time="2025-11-04T23:27: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 04 23:27:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:28 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:28 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:30 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:27:30 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:27:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 165. Nov 04 23:27:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:31 volumio go-librespot[28902]: go-librespot daemon starting... Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=debug msg="app state loaded" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" 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 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" 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 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=info msg="zeroconf server listening on port 35301" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=debug msg="obtained new client token: AAChAKSF2SVXU9yrFhSh8WB25XsoEnZrodYjqYzE5I781BHKFlSiLm+clj3cpAT6dUROZ2R9MHgykgTl1whXFoeWvLYirfRHXu6W8D7uTOttG1pxn+1F3g4nIUa83J3TaIwCl7SuNRGPwlJ9zO2PE096ktONYaAl8nZIXINqm8IBXkF4+bWBYNcoOjDs28o0FRD/6ulUWSfjkgWULDD62iOLaUQe6YmfIIf4UGxVv5iKGLW1nxKC9QI=" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=debug msg="completed keyexchange" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27:31Z" level=debug msg="completed challenge" Nov 04 23:27:31 volumio go-librespot[28903]: time="2025-11-04T23:27: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 04 23:27:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:31 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:31 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:34 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:34 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 166. Nov 04 23:27:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:34 volumio go-librespot[28925]: go-librespot daemon starting... Nov 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" level=debug msg="app state loaded" Nov 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" 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 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" 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 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" level=info msg="zeroconf server listening on port 37245" Nov 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" level=debug msg="obtained new client token: AAArlfh4uduG4a1dodVQdAqJuWC27/c6M2OwcmcFdqBpcf9VoGAgRY+f3yLItoM6wf167SuMgFLRaUXhII1H8CzUhN2cBG6pHeM4xa3i7QAYWKlP0kNllzbTir0UYS2oVUJGFECVjgnc/BajYWNGuG3cb8LaJkkUoeg8279nAUGZjCrcjYT3wL6wDztuY5QjJO1lpCGYd4ZsUuAxjH9QiS+plckdV3G9yomfw4vxQ6qu9dDF4iDvrGo=" Nov 04 23:27:34 volumio go-librespot[28926]: time="2025-11-04T23:27:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:35 volumio go-librespot[28926]: time="2025-11-04T23:27:35Z" level=debug msg="completed keyexchange" Nov 04 23:27:35 volumio go-librespot[28926]: time="2025-11-04T23:27:35Z" level=debug msg="completed challenge" Nov 04 23:27:35 volumio go-librespot[28926]: time="2025-11-04T23:27: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 04 23:27:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:37 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:37 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 167. Nov 04 23:27:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:38 volumio go-librespot[28933]: go-librespot daemon starting... Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="app state loaded" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=info msg="zeroconf server listening on port 44415" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="obtained new client token: AACaD2oTWCAgAhvpl+X69o6N5TVL6JgX4FWK5ivJmpRs/OAutqzIWsZdvoZpR+lYGI2WLBYn8wHOwp8p+3EZb2nJOdDnpCuaY8y9BykNuGPVQcnP1qDvcaPsuplUVu2lChGZxIRrCUUtBxgPZMq9TUBWivQaNC1RGjeQ3+2Z8wRLvCJpPFFOiIZMtc3wAz+mHv2ebhJzKSF66LrxKnkJ0khmxjbG40JE7jM0sFszXO3Ul4B2rY5hREI=" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="completed keyexchange" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27:38Z" level=debug msg="completed challenge" Nov 04 23:27:38 volumio go-librespot[28934]: time="2025-11-04T23:27: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 04 23:27:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:40 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:27:40 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:27:40 volumio volumio[28188]: info: Listing playlists Nov 04 23:27:40 volumio volumio[28188]: info: Listing playlists Nov 04 23:27:40 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:40 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 168. Nov 04 23:27:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:41 volumio go-librespot[28941]: go-librespot daemon starting... Nov 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27:41Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27:41Z" level=debug msg="app state loaded" Nov 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27: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 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27: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 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27: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 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27:41Z" level=info msg="zeroconf server listening on port 42317" Nov 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27:41Z" level=debug msg="obtained new client token: AAAF1xTUnEfnoj++X0LTQB6WZJ6WW60W5cXB/WVD/bbUtkUJEawugGFU17JXartrGAh1dp1aLsqAU6F8W6xIpDQ1fEL/qiegVcfzEsC4Yqe1OX9vPHemsBRbOpyld8r4UnT9jPQ+KGEZLt72W+m33BSQnfkqvcvrA26fSZJUQ3KnL1glvKnaX+UAPpl7+WXXEpnavzi6BeZ8VcGm4Tu6S75P3kHEwyLz4lsMsdRzFFpRL3AxilVuipM=" Nov 04 23:27:41 volumio go-librespot[28942]: time="2025-11-04T23:27:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:42 volumio go-librespot[28942]: time="2025-11-04T23:27:42Z" level=debug msg="completed keyexchange" Nov 04 23:27:42 volumio go-librespot[28942]: time="2025-11-04T23:27:42Z" level=debug msg="completed challenge" Nov 04 23:27:42 volumio go-librespot[28942]: time="2025-11-04T23:27: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 04 23:27:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:43 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:43 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 169. Nov 04 23:27:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:45 volumio go-librespot[28963]: go-librespot daemon starting... Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="app state loaded" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=info msg="zeroconf server listening on port 40317" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="obtained new client token: AAC/uOlzV/kK35yPFHyRurdFzy4tSX+e2j3/Go0A0R/imSlr/H06NhnLhF8xDRuxmjzuDo7Xaj40JZcoA24xdWyEiAq9QmsE7YWUICySufI7r9fT/vfFMbJgof4py6I5tSaXHHVXFiCjQyFBL4xJaXpzN1G6Ho/EoDphkru9xkuLwq0fFvLyTf8dVbeCJytptrHE18u9toMFfAp2kV/kSaxAhA0yu3BipMp85zPavxDw6RszSmNIGFg=" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="completed keyexchange" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27:45Z" level=debug msg="completed challenge" Nov 04 23:27:45 volumio go-librespot[28964]: time="2025-11-04T23:27: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 04 23:27:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:46 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:46 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 170. Nov 04 23:27:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:49 volumio go-librespot[28971]: go-librespot daemon starting... Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=debug msg="app state loaded" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" 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 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" 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 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=info msg="zeroconf server listening on port 41783" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=debug msg="obtained new client token: AACFbtnmb8qoJaW8W6O4LxiBmRlohM/tOMRedvXJ0c6gaOfFz/g39wAt/he0KaogTg3Te76FE3S1ZO4HGPkPHvNx4gimNfaGH2FHybPhdwejrb4FyWvzYlRtXHGfZeNG/Hn+WnIiKrIio58MJWptZNsYmaat1aO0HDfhW2n8UIOYzs1j9Xj/idaMI8heg+ZLKy6hlNQXUGouhu/NMtXp/HZjwUQNIF0odiuP3IqPKW3jKuSVrSmA" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=debug msg="completed keyexchange" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27:49Z" level=debug msg="completed challenge" Nov 04 23:27:49 volumio go-librespot[28972]: time="2025-11-04T23:27: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 04 23:27:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:49 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:49 volumio volumio[28188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:50 volumio volumio[28188]: info: CoreCommandRouter::volumioGetState Nov 04 23:27:50 volumio volumio[28188]: info: CorePlayQueue::getTrack 0 Nov 04 23:27:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 171. Nov 04 23:27:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 23:27:52 volumio go-librespot[28982]: go-librespot daemon starting... Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=info msg="running go-librespot 0.4.0" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="app state loaded" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=info msg="zeroconf server listening on port 44269" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="obtained new client token: AABSh8qGNbn/qmT1Gsy+ORTyPQbFUSeSK8MqzUOVNqtvnTWdRRrduCblJxC+w73s0pnim22rxnF4Afbu987JuSe+4kOsrXKi7flbs/1iGyF5IcU2RZI3quunaRobpljIML9Adgh8C4+GOFcBRld8VIQass53zBUmbrl/YHt68DXWHaXvJIOmHNOLj1qQJ6hrznP881s6D4BVspB12KmCv1Cs2UMdFU5SBhrWNVaQ2j6eFDMd9ppbpA0=" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" 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 04 23:27:52 volumio volumio[28188]: info: Initializing connection to go-librespot Websocket Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="new websocket client" Nov 04 23:27:52 volumio volumio[28188]: info: Connection to go-librespot Websocket established Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="connected to ap-gew1.spotify.com:80" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="completed keyexchange" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27:52Z" level=debug msg="completed challenge" Nov 04 23:27:52 volumio go-librespot[28983]: time="2025-11-04T23:27: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 04 23:27:52 volumio volumio[28188]: info: Connection to go-librespot Websocket closed Nov 04 23:27:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:27:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 23:27:55 volumio volumio[28188]: info: Getting Spotify volume Nov 04 23:27:55 volumio volumio[28188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:27:55 volumio volumio[28188]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 23:27:55 volumio volumio[28188]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 04 23:27:55 volumio volumio[28188]: errno: -111, Nov 04 23:27:55 volumio volumio[28188]: code: 'ECONNREFUSED', Nov 04 23:27:55 volumio volumio[28188]: syscall: 'connect', Nov 04 23:27:55 volumio volumio[28188]: address: '127.0.0.1', Nov 04 23:27:55 volumio volumio[28188]: port: 9879, Nov 04 23:27:55 volumio volumio[28188]: response: undefined Nov 04 23:27:55 volumio volumio[28188]: } Nov 04 23:27:55 volumio volumio[28188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:27:55 volumio sudo[29019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-04 23:26' Nov 04 23:27:55 volumio sudo[29019]: 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"