Oct 29 20:08:01 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:01 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 142. Oct 29 20:08:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:02 volumio go-librespot[13139]: go-librespot daemon starting... Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="app state loaded" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=info msg="zeroconf server listening on port 39253" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="obtained new client token: AAACz0jE9vWfe6MgwGGUkY7bfYBTIZhCn69x/MHc0nzMTpvN5HHscpjSkxDGsYiv+GqX8uFiMBV8S5nAM02ShhY2oA7oDzjXEcNtckf4YaS/ZhzRu0UIC9zPQTkLvueiinkrNdmIUJyDyN1PYEzCAP94sKrJXgpQpvXrHkCB85g3AXBh/oQrYmVgAgZBpo+t4pj0QcmadVNu74lWwEObiO5dA9JVfsAVvL4Mfv3UC61KItxzTYHTCjg=" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="completed keyexchange" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=debug msg="completed challenge" Oct 29 20:08:02 volumio go-librespot[13140]: time="2025-10-29T20:08:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:04 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:04 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 143. Oct 29 20:08:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:06 volumio go-librespot[13162]: go-librespot daemon starting... Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=debug msg="app state loaded" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08: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]" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08: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]" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08: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]" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=info msg="zeroconf server listening on port 45203" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=debug msg="obtained new client token: AADdH88syg2w0U08SM97xr2IY3p9sUX/7i9cPvUnHV78fIlb4dMZevJBKl/Vlh4zUNKAcVQj7uTN7co08XZ9cP0Xz2Pc14kdDftjLz00r7N5vZuXphKPgGGpCWhX5gpHWi+Q37nYHso1E/nBMhPr83Ins3mlYDpFKWt+VdyZ4ZK1N2NtrK6yOkwZSufBgLEnafU7ndC+jV/GzsfDT7P3qDK1quAFDMc+pGrZXfjQh09N+tnKAh5Q" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=debug msg="completed keyexchange" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=debug msg="completed challenge" Oct 29 20:08:06 volumio go-librespot[13163]: time="2025-10-29T20:08:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:07 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:07 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 144. Oct 29 20:08:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:09 volumio go-librespot[13173]: go-librespot daemon starting... Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="app state loaded" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=info msg="zeroconf server listening on port 33997" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="obtained new client token: AADresBgtl/8onj9rUaDVS6Jx0IQQePbplYcRr8nw+jGDI3a0nZ4pN9JN2Do3Uj/6kv/fTcgUOqyBCW2VrStOMHW4Cl6oO8IjK+9OtD+IJLvaOIXK5fiYjgGDzrM58yF2B4L2PHFe/VwSqJauVz9cStwyi3TGTG5IBF9cdOOFrV+cFOOJKbuLbuhZNaknLCoxRmrEoQgRGRsoZdz7ybSjFSJ8+LHPu21jI9mLQHN8RXYh9+Y/unRU+U=" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="completed keyexchange" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=debug msg="completed challenge" Oct 29 20:08:09 volumio go-librespot[13174]: time="2025-10-29T20:08:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:10 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:10 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 145. Oct 29 20:08:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:13 volumio go-librespot[13195]: go-librespot daemon starting... Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="app state loaded" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=info msg="zeroconf server listening on port 45179" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="obtained new client token: AABPh2gnIHggrAqffDKDJKB7AH6zGkBjsNGYVXlJPSyKfZs3j6ULQw3mRHzhH9mIEvXziO3VANpPE732RDF8ZBMAuk07zx/OVC+1fvuL+oVfO9r2PxAA35xmYwXkvRMcB3QJ0J4Kz1VfIAhpK5nJL4uOImVc8Nr2MG1JrdWKaC/Jcc1b4n3CeY6jcPzdipIdE8gYuyq39Uab6yn6drRCkyZlQsi3T/28mVoisYN22qEyP9ad/i3B" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="completed keyexchange" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=debug msg="completed challenge" Oct 29 20:08:13 volumio go-librespot[13196]: time="2025-10-29T20:08:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:13 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:13 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:16 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:16 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 146. Oct 29 20:08:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:16 volumio go-librespot[13203]: go-librespot daemon starting... Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="app state loaded" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=info msg="zeroconf server listening on port 45315" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="obtained new client token: AACbkqFq63C/J55glyVKytVGLh6znqNqXdf3GvQF5Tcpgy/+sVTtyMniWllt5XSFDxC7he9QbByG8KdplX2IHy/7MjW+2LHjiA1Y4u8aJPBBhVEj4SiA8GAUovujh0uB2kTiqz6g70qZxNExW0XnNeAipsDihDMv1cNxH8yS5CgMZaSTA6xA0gFz+OYYzmSAcUGoESS86GCjHXGczkZWOfqVfZf+y+mq35+8Z92CuflcthsK8cnRPQs=" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="completed keyexchange" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=debug msg="completed challenge" Oct 29 20:08:16 volumio go-librespot[13204]: time="2025-10-29T20:08:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:19 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:19 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 147. Oct 29 20:08:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:20 volumio go-librespot[13211]: go-librespot daemon starting... Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="app state loaded" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=info msg="zeroconf server listening on port 35363" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="obtained new client token: AADw+d3kTnPmePvaVWTLhKJWYj4d7Zszj6H+buo88HUwuyslImsmHNVBiZupsJht9E2VreYQliohbJiuAl9Ysa+a0NnOuRVio/PsImiLjCgK5UNRtrUQf6CgdSsE2RwOU4Sw8cyln4U6sAjOsXfAPKwV7dM7b5qatE8fgT0RlKTL/D5GWlEFljDeY8XW3c3yaYyhN7oQKkWf/fsK/b+Il8ywwMmcmjYPQvoIw075HhejR+cPqrZa" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="completed keyexchange" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=debug msg="completed challenge" Oct 29 20:08:20 volumio go-librespot[13212]: time="2025-10-29T20:08:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:22 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:22 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 148. Oct 29 20:08:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:23 volumio go-librespot[13233]: go-librespot daemon starting... Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="app state loaded" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=info msg="zeroconf server listening on port 35359" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="obtained new client token: AABwP2t+5JqaRzz/7YkBNVhVKQ6XnGqWtDgvZ7fx1SI4DdVnFvjUrro8t8hDtCYcsPJraXkV90BoShwKgQLkHFoKrcvxh4pAoHv1VoPjeHLal9D5D83YaJ9ofyJSvKV8gV4K2Jh6S6PsMbGPTbB/k5rAmncbU38XUy5aUClojQAyZ1Y9bKMnzw6ACgnCo3pJHQHZCNsXtRHU/0WwbesdjVtspt3Qg1fuHtqdgjVHKCG9NAFwpNFkGW0=" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="completed keyexchange" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=debug msg="completed challenge" Oct 29 20:08:23 volumio go-librespot[13234]: time="2025-10-29T20:08:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:25 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:25 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 149. Oct 29 20:08:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:27 volumio go-librespot[13241]: go-librespot daemon starting... Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="app state loaded" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=info msg="zeroconf server listening on port 46791" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="obtained new client token: AAAneZgRXEFLiGsvLucpQ1+5ADrqcGxan7jHSC15NoBUIiz0RdtnXUkhoAbNvW7zh947qUxarfApvM/YsACK6t00S0M4BtQTviKdWQHk4T+8aPjrMjYJeLJl/67pcwwUOfKPM2OFdWf+Ij8cV5cDqRXPrfQ8sT9JkaAKSLoLvCN53ho4+WEIF8DwpeLKZXpgmMnMmqYKphEEaPvrQziCNMtqnOGTQoyFCe3qiXijb0nZhDzi9r6W" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="completed keyexchange" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=debug msg="completed challenge" Oct 29 20:08:27 volumio go-librespot[13242]: time="2025-10-29T20:08:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:28 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:28 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 150. Oct 29 20:08:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:30 volumio go-librespot[13252]: go-librespot daemon starting... Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="app state loaded" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=info msg="zeroconf server listening on port 46763" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="obtained new client token: AACMBwQT65t3F7cinGe4YfbybsvwhT7oLikppGkqVssakGRyhlT8k/4F/nU0gIe/kXzIJlsU5aiiEEQEC83FHIqiYnSJ0SlJw+Dhe3g7XtNvt/i5EePpyDrx+zae0K/FX/Dn24wTIjHkFy4wXJidaUXGtz12WhzU4Ir3LuEsq+YE9FCIHMY4Qm1Pb2GqsGB9gbHHYgsDxMk8d1N9/nY8qK/EA/gzxnRFT8bEHsBUFtIKVJlMmye4tOE=" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="completed keyexchange" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=debug msg="completed challenge" Oct 29 20:08:30 volumio go-librespot[13253]: time="2025-10-29T20:08:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:31 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:31 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 151. Oct 29 20:08:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:34 volumio go-librespot[13274]: go-librespot daemon starting... Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="app state loaded" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=info msg="zeroconf server listening on port 39353" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="obtained new client token: AADQKyq/T3X6RVEyECWRNiJUzMV/H3H2Er7nJLFXWg+bdS0c9emvF1aSaR+VMtikXuBA9NL3rummQNBu5mKkHPyKY5Ut5YR6ELsw/DGUL0hfwmAQDOjHPOWhFct1sli162UYDaNoRxslITiZg/CYPxxKtdhAJUHqzba50tIrmkagvfreCbBOWJWctZXebrCsoC9XOI5ugaaofMdyvFZ+NDiL7N272G9WbIdN6HarC87CDNcJ7b2L" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="completed keyexchange" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=debug msg="completed challenge" Oct 29 20:08:34 volumio go-librespot[13275]: time="2025-10-29T20:08:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:34 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:34 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:37 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:37 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 152. Oct 29 20:08:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:37 volumio go-librespot[13283]: go-librespot daemon starting... Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="app state loaded" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=info msg="zeroconf server listening on port 41497" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="obtained new client token: AADzHLOLMzDRcFUy8AicEpLcmAZ3vA6RrIf1SzmKvbHsJYrEcq7V2EZK98UYFqgMzK/sN/MkZ5YJZA9obsmGwiu80vt6wwlgZp6Tn4O4/aLaklomaY4gb7HBR12Ap+50OvXJUfPUlMfcly21yL2FwEN37+RYqh5FOwwZogDMPzXK5GFohXJ6wskh82Z53KgV2A69w87v+uwp8t7ali3DSwo2AK2mZgxDt3ZfX0d/1lVlPcooDlPp/dU=" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="completed keyexchange" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=debug msg="completed challenge" Oct 29 20:08:37 volumio go-librespot[13284]: time="2025-10-29T20:08:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:40 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:40 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 153. Oct 29 20:08:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:41 volumio go-librespot[13291]: go-librespot daemon starting... Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="app state loaded" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=info msg="zeroconf server listening on port 45445" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="obtained new client token: AADXCNRjntoKIlHThYRpn75MDWd9kxhzCjQJfgBsvwW7DuQZlBpl3w1WbuPHFB/BdLV4jbtGvAHd5J7whZym53T4plxmz2DJa4iKtsOSOhDM5rL+lZXUNkrCfViqPPVV+W4QFLog7pNDdsVS6Bs9azrjbIdjgIeFkms2ml5fQVASvw/h3KWnEvs3DfNx/psRGpTiMQKljy0Zr7rDATkwFLZBYS43+uCUO5SopoombUYeW6bI7DkX" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="completed keyexchange" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=debug msg="completed challenge" Oct 29 20:08:41 volumio go-librespot[13292]: time="2025-10-29T20:08:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:43 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:43 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154. Oct 29 20:08:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:44 volumio go-librespot[13313]: go-librespot daemon starting... Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="app state loaded" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=info msg="zeroconf server listening on port 42393" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="obtained new client token: AADUjq5iKQKa3uj2uhXcRqZ5jKzGJqjit6LTX7G40gpPOMI3L7oWRub79Q24DkwUkcMmcWRl+zBSLnEJE2LJHrsMErkVlwKLg/etG2J7M5ffDWeNCU3kXZYkuen1Z4rH3ND7nsbm/wJnt+TCVfH1+5cnABWEOKg8/I93ssTzQmxEZUu3XboP11VSnBxdM+nG/rbx9i8APHfG9rqLzB/6hnjwPU9OypcHxazSAUyQBPDrm1izUXD8UcU=" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="completed keyexchange" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=debug msg="completed challenge" Oct 29 20:08:44 volumio go-librespot[13314]: time="2025-10-29T20:08:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:46 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:46 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 155. Oct 29 20:08:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:48 volumio go-librespot[13322]: go-librespot daemon starting... Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="app state loaded" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=info msg="zeroconf server listening on port 40301" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="obtained new client token: AABlSTPANt9CkgXVgm0AIrNkpgxwYupCzK1iOuyGjtuzr5sRXTDUw4PsY5Mq2BwJNYTRJHeEaih3uK0UUYsWodliL4qPnhdCGCgcduor+8SDiASrO2oxzsM9mP1gnmmfeVV6B2uwPwGGyv/60MyFSXct6Wb+lnn0LM6Y9A0jAzC1rog9oDxnTSGZPYdEOc3QqMyyB/Mg5ouqa7lHNqsGQUh5Eo5edsp7pqYa3kLGlSssraLPycCI" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="completed keyexchange" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=debug msg="completed challenge" Oct 29 20:08:48 volumio go-librespot[13323]: time="2025-10-29T20:08:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:49 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:49 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 156. Oct 29 20:08:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:51 volumio go-librespot[13333]: go-librespot daemon starting... Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="app state loaded" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=info msg="zeroconf server listening on port 44179" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="obtained new client token: AACsLArNjsBsxA4KQoSlbhlebFP+zHOUGNIJFGMsBM6pHxaZzzEw4Ui0M7UU86iWt2Y0ddeFhQh1bkAxCNe+cCTuBJaaf9bMExPtl+zXFTOXMonY+3e9+MVW9N3tzkU2cRmrr5U4tgEdxbSl2bQwxKZyCsPi/usosVAKfSAV862TyrUv9aCxWg5Q+vKBCpX2hOGST3Th8wgwljrMbtgelehI3dmXlLft1PE4YykxGHVUPTxSnCWDGJU=" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="completed keyexchange" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=debug msg="completed challenge" Oct 29 20:08:51 volumio go-librespot[13334]: time="2025-10-29T20:08:51Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:52 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:52 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 157. Oct 29 20:08:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:55 volumio go-librespot[13355]: go-librespot daemon starting... Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="app state loaded" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=info msg="zeroconf server listening on port 35279" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="obtained new client token: AAC4BbAPYiAFpO/51j5DVXPvZLYI278JLVlT9RZFuA5FmMLuR0zGdi6pUg9zN6oNcsbZK/SYYAA4Vez9cFSeSVW8VG6ardDLgYf1pBMV4vomQtRMPpIn6TIMI809zIacIFx9wSuip7HDcDNONvTRoODD9fsAy01CGjud0yh4/CRR51bijzMVGDItpMNIcjC84Uudj8lB3TakMej24aZbm73Ty67dGY/EqCH/lGUS9OD9uTN1xv2E" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="completed keyexchange" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=debug msg="completed challenge" Oct 29 20:08:55 volumio go-librespot[13356]: time="2025-10-29T20:08:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:08:55 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:55 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:58 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:08:58 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:08:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 158. Oct 29 20:08:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:08:58 volumio go-librespot[13364]: go-librespot daemon starting... Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="app state loaded" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=info msg="zeroconf server listening on port 42403" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="obtained new client token: AAA5ZjBHQ8I3teAvgymuASDSflbuoJIOMJ7xfFc0WUhbTlHLHvMd/bVvlYqNv554GKSyObnE45319fhVKjWKwCpbga16kksIVP2FMqtYwiRtZi2j+j3Uy7L4jLZ4GxYHeGCbjLUhG+h0Q4Lm4GY1RE0mWo/IBW3s1mV3IOB9SJMcUdYCSzF5KOTrN3NOomyCBNrxTUi8S8LqJ2QU52pV1PrNRLTXW1icaUyIEMDaIwnMbFnY76GA//I=" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="completed keyexchange" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=debug msg="completed challenge" Oct 29 20:08:58 volumio go-librespot[13365]: time="2025-10-29T20:08:58Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:08:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:08:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:01 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:01 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 159. Oct 29 20:09:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:02 volumio go-librespot[13373]: go-librespot daemon starting... Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="app state loaded" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=info msg="zeroconf server listening on port 44077" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="obtained new client token: AABPvpcSZzCBy2YrPB1wZoUwo/mAyJU/k2WiCg3OPgHxfekA3R+tRWltOCKEiRzIPEe48Z8FsSCFQaWEWKOI+DfSom1E0H3XIruKlVAwIbrf+Zp2EmWQ6e1iogSc3LL79V8QXVCjSSlPInM23zoIn5tz/SEVD7mkOACj+0vS9wg964DobunPaEqSRBuK0zGtKMJzj1I7HEFtx+ExIEUXmFBhDiJjnLHcRWdQdS7RCZycEHp5FPPM" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="completed keyexchange" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=debug msg="completed challenge" Oct 29 20:09:02 volumio go-librespot[13374]: time="2025-10-29T20:09:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:04 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:04 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 160. Oct 29 20:09:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:05 volumio go-librespot[13395]: go-librespot daemon starting... Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="app state loaded" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=info msg="zeroconf server listening on port 38679" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="obtained new client token: AAA43sYKgn/BSX4Zc2Hf+3456M4/HjAY9H0CS3QnoaIgQQMSxbQKhs8aLFNsmcIDEc9qDORRgVX8EaJfEX5NINJYf6s8DJRer23rqKnNns9MR1waASpguNrMkSbPYq9qrEEb43OuBbRe+CoEez7GZo/nzfeToCaZpiFPENmcndXAZnMOz03RtZVMVjbp3bS7pMPmfpAsfn6VM+ndsckIVJ/caftoxTR/IRCRvOLXdfzHmFh1jbtETnI=" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="completed keyexchange" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=debug msg="completed challenge" Oct 29 20:09:05 volumio go-librespot[13396]: time="2025-10-29T20:09:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:07 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:07 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 161. Oct 29 20:09:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:09 volumio go-librespot[13406]: go-librespot daemon starting... Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="app state loaded" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=info msg="zeroconf server listening on port 37929" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="obtained new client token: AABMJopTtsNZN3JGLx0BKVmbnHpYdAH1qAAC8hvg+xUX03coPck5grsmhALFLd263Kiq4A+80Fo4nM9Uu+4PNg7gjsBpXdRV0B0/AbliA9oZULWVUCRMpz5qY+xWgQONEdbnhn0wpClv7czgNuAHiOoRMGttg9AGXzRoddPjr6Qz7a9sF1B17yaIeUzzLb03fxFhCbsO80IAbseFDQzjNnGaBK81RjuUqeMxp+1uQldRNwnpzYaZ" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="completed keyexchange" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=debug msg="completed challenge" Oct 29 20:09:09 volumio go-librespot[13407]: time="2025-10-29T20:09:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:10 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:10 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 162. Oct 29 20:09:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:12 volumio go-librespot[13414]: go-librespot daemon starting... Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="app state loaded" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=info msg="zeroconf server listening on port 34479" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="obtained new client token: AADEG9g7vLhEtOPLiJTn71PpsJyOhoh3Y6/Xc3V6xxBB2lRmpmBwlb6ux//5x+bINfbNtBH9sbkoZEBJrf6Lf88prRxsDR35uITnul9aXU8PmEpJhVKFIJqWTyb55lNZYtZa9jMyi6HWvGm8Wo1EmdRdpQWbqATlpyP8ehJ+BcDssA0ZsNjAkfGR/7XalflpDwPJm7SkcVw3ESRTKqGF3LYXtjH3OuMrGo7c542ZbqKE5PgFf6m7YYc=" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="completed keyexchange" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=debug msg="completed challenge" Oct 29 20:09:12 volumio go-librespot[13415]: time="2025-10-29T20:09:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:13 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:13 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 163. Oct 29 20:09:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:16 volumio go-librespot[13436]: go-librespot daemon starting... Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="app state loaded" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=info msg="zeroconf server listening on port 43579" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="obtained new client token: AAAU1GzSbUSLv2myYwkxR3cxPYQIrxV4txVaIIETyBgnX32bXkl6Ws2OXLNNf+D6kcvuno2bPPdK4pvaw4SC0TQFmrKay4z4CozaelGOrk/M21kMxfBfcF+twAtCDdwqRqo6IcC4mVxkrnqFaG0zqJ9x2XqbDopWzmgd7vw3H172wsFHSPnSfp5GKJyeoRTopecu0pdll3uyU+HoPx6K/yod+kbvEnbvSQ7mZLZPnJtKzMf1KdZS" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" 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" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="connected to ap-gew1.spotify.com:80" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="completed keyexchange" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=debug msg="completed challenge" Oct 29 20:09:16 volumio go-librespot[13437]: time="2025-10-29T20:09:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:16 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:16 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:19 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 164. Oct 29 20:09:19 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:19 volumio go-librespot[13444]: go-librespot daemon starting... Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="app state loaded" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=info msg="zeroconf server listening on port 35903" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="obtained new client token: AABgA+T/sVeCoRogdHHi7hRSZVcFP7UfJdzbOeL46j0d7VTlBYI2pGNEPX720nc/23JYMRZjSJwqFhmOx/0xZIPjJ/GZkTBNtq7zx1botXVyc3mV3nhia48P8Xm4IREPYA4mpl2Z0SfHbjWVjOz80fw18vmIknhSLpihD4iLKmBnDqf+p7pG1a/Ut8udu/ma1M8SQWyzfJQ+Aqagc4xVyKXKJGz8dc8l1NsWn940iuJUNBADuidwRXQ=" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="completed keyexchange" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=debug msg="completed challenge" Oct 29 20:09:19 volumio go-librespot[13445]: time="2025-10-29T20:09:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:22 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:22 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 165. Oct 29 20:09:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:22 volumio go-librespot[13453]: go-librespot daemon starting... Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=debug msg="app state loaded" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=info msg="zeroconf server listening on port 43583" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=debug msg="obtained new client token: AABnpMUDygW3e41JkVL/cFbameTxU8CwZpOlGHEa+8RqLyawCVO/bVMMJeQS+FsU8GrP+PqYKQklQs/7g1DJ3g+PGWFbJl0WkPfpZt+oPTpe+dDz4nFH3mV+jYmyQrWteqHsQxMZVFhQtrWtnSm+oQBFwaNlHL6gPTkHAZcAYTgTR8PgLDDWgK0aHahddqKCjVRixLOr1aG0HaTPKzBlAd7hEyb7q5mOAf2cJsXo7SyhJ3lEHwt828o=" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:22 volumio go-librespot[13454]: time="2025-10-29T20:09:22Z" level=debug msg="completed keyexchange" Oct 29 20:09:23 volumio go-librespot[13454]: time="2025-10-29T20:09:23Z" level=debug msg="completed challenge" Oct 29 20:09:23 volumio go-librespot[13454]: time="2025-10-29T20:09:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:25 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:25 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 166. Oct 29 20:09:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:26 volumio go-librespot[13475]: go-librespot daemon starting... Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="app state loaded" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=info msg="zeroconf server listening on port 41835" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="obtained new client token: AAANk/ZGhM1UrFGwfwXbOEgCRLFovH0iKFhNg/OJJO+Woe6uA33dGLlQDVPiOMlSJrbnwYDlRtT3s/CYDjCzdQZ+UeN4a87PW3xcv7DMkg9KUcBNAMVe4KS/z17+PCFSa2kfo+tPIeEQx4fsjB58ZwUAk9rg0G2sV+RDldAl0kb4NCj3r2RD9im26Py1TnOR9KXc4/KeRWKuW1cJ2NBEwLQ5aa0DRauQjVCVlYYx1LE5PBekIBhiV3A=" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="completed keyexchange" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=debug msg="completed challenge" Oct 29 20:09:26 volumio go-librespot[13476]: time="2025-10-29T20:09:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:28 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:28 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 167. Oct 29 20:09:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:29 volumio go-librespot[13486]: go-librespot daemon starting... Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="app state loaded" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=info msg="zeroconf server listening on port 40421" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="obtained new client token: AABHfjAVoKl0F5QQvScf4javlT9bBYCwmhiymbGwXCeyYHjTIcODZvEfoPs08X7ON5Kpos3T1IOjSgdv1hZFoei0yRlHHTRrEVC+A8M+/jEgSL+vAwKEINMs4PiNVYbwyacRnE4Y0jdTWuDA1IoQaoIQ++pqksZgGMANNgPAdWOK6np2/uWllXWAvvOONrRnwQSz/0TZCx05ZTOpa8lIZt52e8NXWw+nKn0GvRIaUm5F9YbMxV3GO2w=" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="completed keyexchange" Oct 29 20:09:29 volumio go-librespot[13487]: time="2025-10-29T20:09:29Z" level=debug msg="completed challenge" Oct 29 20:09:30 volumio go-librespot[13487]: time="2025-10-29T20:09:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:31 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:31 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 168. Oct 29 20:09:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:33 volumio go-librespot[13508]: go-librespot daemon starting... Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="app state loaded" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=info msg="zeroconf server listening on port 36493" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="obtained new client token: AAALH/Ck4tymmf8KQDFmm5I8b7tSfvmV5rPEebYriIrzZTS0SPZ1uwrVkKS0nTjSVjkGNGf1CKwj3GdkFR+OLyCce/LgcpBP6/LGFcYplfN9G6xlG0zo64mIlu1A6pLtshmxGznzRg7zdGqSIHZ8QRiTTLO0F7PpNF4r1OdiSwGpbGxjvlJeewv6lqGXwhf9PQqMP+znXMXArUEE0NkZumgCxbyyTKrVMBMPvrnHyVqwqWCi0Sm0G1s=" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="completed keyexchange" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=debug msg="completed challenge" Oct 29 20:09:33 volumio go-librespot[13509]: time="2025-10-29T20:09:33Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:34 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:34 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 169. Oct 29 20:09:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:36 volumio go-librespot[13516]: go-librespot daemon starting... Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="app state loaded" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=info msg="zeroconf server listening on port 43149" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="obtained new client token: AABIwOwqIDF9eC5wTxUxuiZsfLUovraarafB1dnBKy+1hvd42Blr1wyQSBL00t7Xyf9t0BXenkopYprnN+TTPVURWsO/3pOEanXqaEq05J3Jf6HlJUNLhGi5DZ6DXyeppa/Nwc2ID+S8fBVDFmKaPQdKbOySKH85z4wnORb+YEc7Q8d+MlVgFDJ3Rdr1Cj+4LXYA4/wAJuCVEP/nYqkH0kSRGPGR5b9k7w2SplDCUW/Eyv7G7YWmRis=" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="completed keyexchange" Oct 29 20:09:36 volumio go-librespot[13517]: time="2025-10-29T20:09:36Z" level=debug msg="completed challenge" Oct 29 20:09:37 volumio go-librespot[13517]: time="2025-10-29T20:09:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:37 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:37 volumio volumio[12187]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 170. Oct 29 20:09:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 20:09:40 volumio go-librespot[13525]: go-librespot daemon starting... Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=info msg="running go-librespot 0.4.0" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="app state loaded" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=info msg="zeroconf server listening on port 39357" Oct 29 20:09:40 volumio volumio[12187]: info: Initializing connection to go-librespot Websocket Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="new websocket client" Oct 29 20:09:40 volumio volumio[12187]: info: Connection to go-librespot Websocket established Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="obtained new client token: AABmlXXTYrRHZnRTXihNOYdDtSfTQJ9crlRJJ1EMScJWw/Z1yQq/+ovpBd8QbuNBnWDF5y/sSPXFYx15A7dyBNZzALOfPdlkhK7IB7fuH8IC+j6FzhDlPCEx//DJSWCyGRPfZ5PS049rHXYf20ES6+MA6xdS2wmH3i048EO67orPb8UNTVsy1esFCvJec33G9mKPIJb7wA+S6Z//dQpj3Dmp/2dJWQ58I6Db9U5ZeqWp+SBeD2mhLm0=" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="completed keyexchange" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=debug msg="completed challenge" Oct 29 20:09:40 volumio go-librespot[13526]: time="2025-10-29T20:09:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 20:09:40 volumio volumio[12187]: info: Connection to go-librespot Websocket closed Oct 29 20:09:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:09:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 20:09:43 volumio volumio[12187]: info: Getting Spotify volume Oct 29 20:09:43 volumio volumio[12187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 20:09:43 volumio volumio[12187]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 20:09:43 volumio volumio[12187]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 29 20:09:43 volumio volumio[12187]: errno: -111, Oct 29 20:09:43 volumio volumio[12187]: code: 'ECONNREFUSED', Oct 29 20:09:43 volumio volumio[12187]: syscall: 'connect', Oct 29 20:09:43 volumio volumio[12187]: address: '127.0.0.1', Oct 29 20:09:43 volumio volumio[12187]: port: 9879, Oct 29 20:09:43 volumio volumio[12187]: response: undefined Oct 29 20:09:43 volumio volumio[12187]: } Oct 29 20:09:43 volumio volumio[12187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 20:09:43 volumio sudo[13561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-29 20:08' Oct 29 20:09:43 volumio sudo[13561]: 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"