Nov 02 05:18:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 253. Nov 02 05:18:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:02 volumio go-librespot[30395]: go-librespot daemon starting... Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="app state loaded" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=info msg="zeroconf server listening on port 46559" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="obtained new client token: AACW4Wb/SVydQzPVUHECVQdHNWSMS/dQKQ9rXIVbNXlvIMZ6a/hmM42MZDYP1Zt3PLktZdQqpWhlWId5nablHYiNoWcYedvFNqV/qLDVGeINzPn/WNbxKOMOolgC7Jo8Tt4NAOyQUfYONFFRUHDS/m7D0Hor28+4/YAYv4/KMtzUrJ48U2cINOMayqhfgDP2jSjKd2nqEc9A1zKkGQDSjA8u2GZ4xnSjyCF60btDY0eETU9jkHSItgQ=" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="completed keyexchange" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=debug msg="completed challenge" Nov 02 05:18:02 volumio go-librespot[30396]: time="2025-11-02T05:18:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:02 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:02 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:05 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:18:05 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:05 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 254. Nov 02 05:18:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:06 volumio go-librespot[30403]: go-librespot daemon starting... Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=debug msg="app state loaded" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18: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 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18: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 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18: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 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=info msg="zeroconf server listening on port 37029" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=debug msg="obtained new client token: AAC5si4DBLGjvHOuhyly6diWwjHQwgthWASffNF3I9LvTXdqzAz4ITP9TvfIbc4eEM5EESOqaC6zn88i58Ct4Cl26c6yD/TDCGuYG9RHNlNHPyDN8yKTin8mUKIidTkG4Qkjf2ufg6lDEjLCcN5czdqnSJNofrmlHjOUyvcU7hHd0IvEGrYXOH7b/3Lk9VA3MCkEzJDLW89/bsc7bAEAFR+l+3liuVRThct7sNdb6DWp/rAa9ahj" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=debug msg="completed keyexchange" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=debug msg="completed challenge" Nov 02 05:18:06 volumio go-librespot[30404]: time="2025-11-02T05:18:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:08 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:08 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 255. Nov 02 05:18:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:09 volumio go-librespot[30412]: go-librespot daemon starting... Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=debug msg="app state loaded" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18: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]" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18: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]" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18: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]" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=info msg="zeroconf server listening on port 40783" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=debug msg="obtained new client token: AABlfhtVvJml/BqmzkBzQyV9pg7Z3ReyjNw6bk2BYcoBDuOAIzNzujHXn7CN4YTDDmvMC1VN9DdiujxpyMsznZcf4cn5WUz5p2zCbxPGxZIFtM9RORtaB8lF8K9clT77X6HGmad6m3l5oMObsGVacpe8M4z2wGJLFNd6NRsUVSpMHJQ8orewoLkpkL6FN22kDub51vUwJ6kJHh1vzGjbHYQFmyGBlBqpIFdfPMKhFpJr1Eiy01xwgik=" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=debug msg="completed keyexchange" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=debug msg="completed challenge" Nov 02 05:18:09 volumio go-librespot[30413]: time="2025-11-02T05:18:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:11 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:11 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 256. Nov 02 05:18:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:13 volumio go-librespot[30434]: go-librespot daemon starting... Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=debug msg="app state loaded" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18: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 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18: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 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18: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 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=info msg="zeroconf server listening on port 43961" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=debug msg="obtained new client token: AADppwysrq+40/Y5w3tSmKOpUNbB4xOsz26HdNIElAZwNY/r2ygxeMPSwhuWnT4DlwNdjWMsMB4W6CBb4a+gPgQdSJ/nTGQwNvjU9CZDG1VSIXh/oIxMn5uj84wLuOD19jZCmMFo084mOuLXcK0d3vKESOj3cwIDs8dpusOLj9L7PTkRr8akmWnVG994sUz3aB5VKwYQL6iv6PhjDzl32eRVTy5GJPKCr4fvxytHchtLx+6jAfEF" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=debug msg="completed keyexchange" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=debug msg="completed challenge" Nov 02 05:18:13 volumio go-librespot[30435]: time="2025-11-02T05:18:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:13 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:18:14 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:14 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 257. Nov 02 05:18:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:16 volumio go-librespot[30442]: go-librespot daemon starting... Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=debug msg="app state loaded" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18: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-gae2.spotify.com:80]" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" 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 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" 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 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=info msg="zeroconf server listening on port 44803" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=debug msg="obtained new client token: AADDm+lf5dz9uajmTEDFT8SHSgwyn63qq4X2k0NXj6DQPu0TRlC3E/d10HKP2VxcAoF9kFHbM82bXTnma7jvbMviSP+TxmLACxb0EMxMrrw+x3oU63DUxDGBdJIlPhAVPtVnBnt/V8T/cqPp/h87/F8MnXQkYUfif4OKt1ylaPV3ecrAFteBH3yLC6f5Wpe0Rtc6fg6J3D3g2uAXjJnYe5DcrAczQFTEruWWE5dATWYXYRcsE6NfCt4=" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=debug msg="completed keyexchange" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=debug msg="completed challenge" Nov 02 05:18:16 volumio go-librespot[30443]: time="2025-11-02T05:18:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:17 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:17 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 258. Nov 02 05:18:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:20 volumio go-librespot[30453]: go-librespot daemon starting... Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=debug msg="app state loaded" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" 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 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" 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 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=info msg="zeroconf server listening on port 41141" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=debug msg="obtained new client token: AACfExis/MFVUtDzbDdplmI5V28Wn2hNv+djON58VkPYx0XEryc5Q3t0YGqqTO1TwCIDojY4tA1c2JtLgIwV55edqpnPkhvEwW57ROx/l1EEdpke5w1U3dbHzzoVvlOf+uCTLAQKlQuinqx0X03HRNag8Kj0anp9YnEyjoNAC3pon33Nmk0BqebvWrNW4QDcv29GZYLwSHZSzWH5Pl6eo3ornMH2FuoVBgqcGtA+GZSubETKr0O4" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18: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 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" 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 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=debug msg="connected to ap-gew1.spotify.com:80" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=debug msg="completed keyexchange" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=debug msg="completed challenge" Nov 02 05:18:20 volumio go-librespot[30454]: time="2025-11-02T05:18:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:20 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:20 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 259. Nov 02 05:18:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:23 volumio go-librespot[30475]: go-librespot daemon starting... Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=debug msg="app state loaded" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" 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 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" 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 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=info msg="zeroconf server listening on port 46019" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=debug msg="obtained new client token: AAD0YA25mWn6ROMDvHSqDgA8EW4eAB3cBEbBbGtyX1YO/Att9PktNELLvdAL4HT9oTGbEvnBNKi0DL3CpkGggOkj8+hOiDGz2rigA8bMDh2Pr6suOf0HitxcwWJ6KLS5HXNZmAgTovEK1ynDJCgPqJ74GeXAOIdr5SZE+5eV9wVDI7tSbxJ9qSVHkJOWhvXiPfeEVD9RTHXMQ9jvVBht1TZ9ulmgRh/RqpR3wnSTVdkMesanOo9KWHY=" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=debug msg="completed keyexchange" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=debug msg="completed challenge" Nov 02 05:18:23 volumio go-librespot[30476]: time="2025-11-02T05:18:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:23 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:23 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:24 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:18:26 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:26 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 260. Nov 02 05:18:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:27 volumio go-librespot[30483]: go-librespot daemon starting... Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=debug msg="app state loaded" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" 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 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" 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 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" 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 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=info msg="zeroconf server listening on port 38321" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=debug msg="obtained new client token: AABmvVuWpZZCabC29wdhTnN+0o74Eu8FyHdh3hilx9nHMCsWRt5sdDA1Ar8/J8iR/EiZk84+F2h9y4wCIKBubDzBWEBb32MfFI5lWo2ZBuv262+T2o2e9U0dcPGN10SXXKFzA7VlcnIIGg20rq6nRPtfAm7B25/HIFt1vs5+D8hL9FEUwydKXrtyiStVTWtZiCqncgjRWdJwA61bpsXeS5BVYjtQP/lVv/PdlQIXOIsV0cxvqSII" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=debug msg="completed keyexchange" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=debug msg="completed challenge" Nov 02 05:18:27 volumio go-librespot[30484]: time="2025-11-02T05:18:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:29 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:29 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 261. Nov 02 05:18:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:30 volumio go-librespot[30492]: go-librespot daemon starting... Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=debug msg="app state loaded" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" 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 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" 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 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=info msg="zeroconf server listening on port 41259" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=debug msg="obtained new client token: AACKh6fVfHJq+aXW1YB5a0/I4Qq+uzJchP6C4joYH1VDHwQeWGo0vFP4CBOA424oMsK+U2FLWB/Q3+/61G+PcEyLz6vIEhPY54oRniPDPsEww8U/XqJDGcyTmfjcNNK0LtNzFd9kqcrAI/CRHICNr9Rv00fULkvZSJwAEk/8njtiMkw8NhMkt7pQGUeu/pp9pX8zfbQ+nMN9WPeJuqERQDW85XbTVj5KjEArXJ5B7ioTnxQ1gDzzHpw=" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=debug msg="completed keyexchange" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=debug msg="completed challenge" Nov 02 05:18:30 volumio go-librespot[30493]: time="2025-11-02T05:18:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:32 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:32 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 262. Nov 02 05:18:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:34 volumio go-librespot[30514]: go-librespot daemon starting... Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=debug msg="app state loaded" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18: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 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18: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 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18: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 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=info msg="zeroconf server listening on port 43145" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=debug msg="obtained new client token: AABkZ6Be+oo9yH7MerY4xtyigvJB23xitcZBrshJAUeZcZZnbgyauMy6jRZlamsETvHfvFaay5hxgpqOrgP/aC0rttO0b5DeGPkliubQjye2f+XYzOUvTTkn5j8LD3YAWc7LdN9pF5oSnlMlmZ8R9WqEiAPzPJpztTkpgBlsODHNfcVUslsDa8EZCGUbF8sGff5vrKJ7TKqH7WklMrajhmCp38t7PgVZN3m3N8u3z5WYMADgeEgc" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=debug msg="completed keyexchange" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=debug msg="completed challenge" Nov 02 05:18:34 volumio go-librespot[30515]: time="2025-11-02T05:18:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:35 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:35 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 263. Nov 02 05:18:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:37 volumio go-librespot[30525]: go-librespot daemon starting... Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=debug msg="app state loaded" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" 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 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" 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 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" 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 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=info msg="zeroconf server listening on port 45893" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=debug msg="obtained new client token: AAC2qudq4TqWs6blqJxNxWPaHWGDDYjReKy0FGsIDpHYqFx1bWApRiuXO0Tc4yWbiH8EA3kBqmOCKw6uWQWLQquT/8gqPAhECeggpEyH9elmzh153ajG5wgazQhWfLusiY3WO98XFOxq5TkXjcbiSUUPSeooIrN14485t720YjlNCz6sApctDTG9ymt9DC4o6/hxFGOzhphWwhxFLM2+Np8qdQzbNLwr4qAzbpRdrpyyX+8DY9EJidY=" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=debug msg="completed keyexchange" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=debug msg="completed challenge" Nov 02 05:18:37 volumio go-librespot[30526]: time="2025-11-02T05:18:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:38 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:18:38 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:38 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 264. Nov 02 05:18:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:41 volumio go-librespot[30533]: go-librespot daemon starting... Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=debug msg="app state loaded" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18: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-gew4.spotify.com:80]" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" 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 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" 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 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=info msg="zeroconf server listening on port 37307" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=debug msg="obtained new client token: AAB9n7N+i6HkRXrYzxJXiGo+B4r7IyhTreJjr/xHTcr8qV4P51RkBLq7rPduYYygb/GwGuY3g+5ihYTG//xmq0Uq9jBMNubtkhQaRiRDqgGJDuceyoC+5+mGH/QozTG7KmvpYBwNV/ukNh6YhekWDPBluJbY5xMxkwCsKnpkWkTa2hVPuoKkj7FjkQJMRQNfEsIWL5vu9pZMhcvVjYbxoAL7fFNkCMD9FT9luySTFzNytC8zFXie" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=debug msg="completed keyexchange" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=debug msg="completed challenge" Nov 02 05:18:41 volumio go-librespot[30534]: time="2025-11-02T05:18:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:41 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:41 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 265. Nov 02 05:18:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:44 volumio go-librespot[30556]: go-librespot daemon starting... Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=debug msg="app state loaded" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18: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]" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18: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]" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18: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]" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=info msg="zeroconf server listening on port 46093" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=debug msg="obtained new client token: AACmYVmWGR/tc/BEufC7FnjUi5L4kqrtrsdMBXd/eY7ZCZQnE0oBuOBhUWMwlCH7tfUs7oOsw/h2kiTKfTK0xBM5/1NHHgy+ISGaiYSrKwZkUtDylFDfO7w7o0dEwnhe0KJaUjwh/DljCpduGX1jTwvyogg6IBD5gJnOCVX0TfHBJfXp0wfhc70avo7FC5WeEMBV4v7RzA8UGXZoapM0KNEHE6HNEqJs37I6idD0XVG42PU7H23mdus=" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=debug msg="completed keyexchange" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=debug msg="completed challenge" Nov 02 05:18:44 volumio go-librespot[30557]: time="2025-11-02T05:18:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:44 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:44 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:46 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:18:47 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:47 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 266. Nov 02 05:18:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:48 volumio go-librespot[30564]: go-librespot daemon starting... Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="app state loaded" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=info msg="zeroconf server listening on port 35575" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="obtained new client token: AAAu9xiEUXImNDuGg2XOlT6NECAjU4C1BVPO+zgU80CQ90ZKONDBjmSJG+uqgOBrIYIX7djSrFNZteLHuglJkUVh7vmvkLg0nnudoA+CMGjiGkYNxWj3rHW90mAM2il4mwSaZNkUrndIDdX8GPeDXVBiWEARkXSDhgqNTnOhfCOI1CwkfI9ieOM+G2uaNJoyMZbMOBQY+gyzai4kSJ6wuWHKVw1u1zBuuNmuqIJufkyRAP0HZnuI" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="completed keyexchange" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=debug msg="completed challenge" Nov 02 05:18:48 volumio go-librespot[30565]: time="2025-11-02T05:18:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:50 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:50 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 267. Nov 02 05:18:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:51 volumio go-librespot[30587]: go-librespot daemon starting... Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=debug msg="app state loaded" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" 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 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" 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 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=info msg="zeroconf server listening on port 39007" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=debug msg="obtained new client token: AADXpv9TmiEud8CV5aLKS3Bbb1vL57Zdd3s1f+5D9fL9YaYu1hLnP0OKCtLLBvf6/mlkdpNPpp/Rx2hzRgMd+kxXzJkODu4zApj0RJSULtQouJ20agtqR0j5fVWOSEDizxqY8FQw2pf9ZnjrM+OcDldUJj28dyd0xypTaB9vX/cBxFeGF5XnjT29mOxYZ7od/gqsdJzMSjBhciBPZPFyvZa1C21HjuFobKSFktYq1ffK/XrV/B9HCWw=" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=debug msg="completed keyexchange" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=debug msg="completed challenge" Nov 02 05:18:51 volumio go-librespot[30588]: time="2025-11-02T05:18:51Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:53 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:53 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:54 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 02 05:18:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 268. Nov 02 05:18:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:55 volumio go-librespot[30595]: go-librespot daemon starting... Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=debug msg="app state loaded" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18: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]" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18: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]" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18: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]" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=info msg="zeroconf server listening on port 40589" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=debug msg="obtained new client token: AABI7qYgsXiEpPJX3bxjQmKkezq2v5y/TIRFeZCyABuXI2iipZ7ri8+Cgbds/MOrIeS+oLFpLUl/j6FI1iFvAhFTU9ldqhCtpDu7ZazkBnMHdJ1WqNoIPd1rkucMLbVm7/9YPuDW9pRgTUoGaBZYTQpZz9qNA/B+Ou5qxuUrUQdukykvKWyiDtDCEKNXzAZhXoc4Jb5fo/0mYcI5d1gYubgGGEEO7GgTClQKXNCRC6PuBtym3smB" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=debug msg="completed keyexchange" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=debug msg="completed challenge" Nov 02 05:18:55 volumio go-librespot[30596]: time="2025-11-02T05:18:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:56 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:56 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:18:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 269. Nov 02 05:18:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:18:58 volumio go-librespot[30606]: go-librespot daemon starting... Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=debug msg="app state loaded" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18: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-gae2.spotify.com:80]" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" 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 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" 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 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=info msg="zeroconf server listening on port 33819" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=debug msg="obtained new client token: AABmVXxr0Ay40ND5NztyqA5ihODYt+uPqa9QmxGy0I4JcKQ5kerNwL0TdRUmdUfr9cypKP6NYpd8W5YCgtXFL6XvusYVBzOJfBbe51MbHekXAxZbiWIKkfdyxy91+ku+54IUtUNobdzH5Mdw/FhMUTldfwsq3K1oLAIMELvzfnHzWFluqBY7JB1O1rN4NyqBw/P9MQ/lZTyMwsC3SYXgjdrehKzqrZBAA8uYhA/nyqRyHReQpwf/Cuo=" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=debug msg="completed keyexchange" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=debug msg="completed challenge" Nov 02 05:18:58 volumio go-librespot[30607]: time="2025-11-02T05:18:58Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:18:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:18:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:18:59 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:18:59 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:19:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 270. Nov 02 05:19:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:02 volumio go-librespot[30629]: go-librespot daemon starting... Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=debug msg="app state loaded" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" 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 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" 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 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=info msg="zeroconf server listening on port 40417" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=debug msg="obtained new client token: AABFDfVh/FCqneecoQY51Rsxwx7l7mZYtuPrhfUcHBbTKhZVbaxzENnOqVXY802tJ8ol0Q4LLekwmguXSwl1LZtqsz7cF7QG7kWaPstSZeybXV0kdd/Lc1FqfMHFRLYPQDzy8dlvbcn+a5wXwZ5i8HXR79nWvKYDeWsHLGjizFKM0qj/CM3RxczpGweKe/DXC2pc770w8xjVkkbGjgdSN3JR7FgjjtJVsY0f1KG/Hhc0V3DriBFZ" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=debug msg="completed keyexchange" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=debug msg="completed challenge" Nov 02 05:19:02 volumio go-librespot[30630]: time="2025-11-02T05:19:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:19:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:19:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:19:02 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:19:02 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:19:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 271. Nov 02 05:19:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:05 volumio go-librespot[30637]: go-librespot daemon starting... Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=debug msg="app state loaded" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" 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 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" 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 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" 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 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=info msg="zeroconf server listening on port 33961" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=debug msg="obtained new client token: AACKoSnoygFWIRHktJBkf+f4SspxgOnLCg3uD45I0Lle6fBPJ1KTB/2xWa7aj3BsFNsUQlgFL97K6MInQOxdxO16IGxEnKNLmymru4y6FSs+ByQS8WoUjiJaLxHWXmqG4NjhxE5sEKdeNsFXRJhK5ynOYYSkqxOxVzNywuwJW4OXxeZ2obf7H5dEoVbNn8Ivlvdr5nATPw6zQW6qmWcqzUMmYjJqjPJZrSenLlrdLGpM//QCkhuj1RY=" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=debug msg="completed keyexchange" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=debug msg="completed challenge" Nov 02 05:19:05 volumio go-librespot[30638]: time="2025-11-02T05:19:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:19:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:19:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:19:05 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:19:05 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:19:08 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:19:08 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:19:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 272. Nov 02 05:19:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:09 volumio go-librespot[30645]: go-librespot daemon starting... Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="app state loaded" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=info msg="zeroconf server listening on port 44601" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="obtained new client token: AACp4x2Qa9gqlnbEOWHVe2G5cRBFm4yh5MgWKbdRTgikYjo7h7KOUB+k0xnMJeUEW7BZURVMLND8yvgoyu1tHwk/EONW29G6mu+8i5oMotZ4YQkUbL+BNWEc6dSbjOE+cZOUOQgx5sYYpDMDuS7GlNqALEX8i8c3hHCpPK45iImdxwKCvQyRIZY4FXbl1UCtYYFkA/6yVEKEplTZiqqpdYbgpigD93JEl99Ykn33Bx56zzMBIoCt" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="completed keyexchange" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=debug msg="completed challenge" Nov 02 05:19:09 volumio go-librespot[30646]: time="2025-11-02T05:19:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:19:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:19:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:19:11 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:19:11 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:19:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 273. Nov 02 05:19:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:12 volumio go-librespot[30667]: go-librespot daemon starting... Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=debug msg="app state loaded" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19: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]" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19: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]" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19: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]" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=info msg="zeroconf server listening on port 38947" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=debug msg="obtained new client token: AABnu/HANsnOlKxKf0vldc698IjtCbOn+YF3QHVlrUuh73694+VfirLhocl6WVffiGzd9AtVHcy1PmXlmZD4n3PzUE+83yCJLQ+14IpKcaNPYemmQKpE42DhIwJkfu5cVetVzi3mX4pC7TVI0+heXkrKlYY6LS8SF7O+hnyMH/6e9KfyZlxlbaB8fQ+rFxWJpXCC/P7dr4bzTdBfKf5MgDdLCsBZLNx8/8fBkAwJwjmpMWz6GWEFgJc=" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=debug msg="completed keyexchange" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=debug msg="completed challenge" Nov 02 05:19:12 volumio go-librespot[30668]: time="2025-11-02T05:19:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:19:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:19:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:19:14 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:19:14 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:19:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 274. Nov 02 05:19:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:16 volumio go-librespot[30677]: go-librespot daemon starting... Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=debug msg="app state loaded" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19: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]" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19: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]" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19: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]" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=info msg="zeroconf server listening on port 34533" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=debug msg="obtained new client token: AADwUibk8Yo8ItVV4CMFIxHSSpXNlmwz+S8dd83lbiWAkhbSd5K/EwfHvm26ZRXfVdcxNtojCJ2otbpbqEjcPe3ZaInZOZlsFCxRX4KbA4jvO8PTErDsS5jmn/6B1sr+rVFgRGUoq/mKVgSRM6bJrJgibw1TimRZeVbiN5LvuRRGXueTN94A+8cj9WJBszFDQrcMkCtSryyPBGreM8FT1rq6e10DlgKtC7YV4T3lnhfe/goYeeJt" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19: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" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=debug msg="completed keyexchange" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=debug msg="completed challenge" Nov 02 05:19:16 volumio go-librespot[30678]: time="2025-11-02T05:19:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 02 05:19:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:19:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:19:17 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:19:17 volumio volumio[29587]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 02 05:19:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 275. Nov 02 05:19:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 02 05:19:19 volumio go-librespot[30689]: go-librespot daemon starting... Nov 02 05:19:19 volumio go-librespot[30690]: time="2025-11-02T05:19:19Z" level=info msg="running go-librespot 0.4.0" Nov 02 05:19:19 volumio go-librespot[30690]: time="2025-11-02T05:19:19Z" level=debug msg="app state loaded" Nov 02 05:19:19 volumio go-librespot[30690]: time="2025-11-02T05:19:19Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 05:19:20 volumio volumio[29587]: info: Initializing connection to go-librespot Websocket Nov 02 05:19:20 volumio go-librespot[30690]: time="2025-11-02T05:19:20Z" level=debug msg="new websocket client" Nov 02 05:19:20 volumio volumio[29587]: info: Connection to go-librespot Websocket established Nov 02 05:19:23 volumio go-librespot[30690]: time="2025-11-02T05:19: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]" Nov 02 05:19:23 volumio go-librespot[30690]: time="2025-11-02T05:19: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]" Nov 02 05:19:23 volumio go-librespot[30690]: time="2025-11-02T05:19: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]" Nov 02 05:19:23 volumio go-librespot[30690]: time="2025-11-02T05:19:23Z" level=info msg="zeroconf server listening on port 39753" Nov 02 05:19:23 volumio go-librespot[30690]: time="2025-11-02T05:19:23Z" level=debug msg="obtained new client token: AAA0+WR3o4vdJ8ebHZTP9wHML97bzZSOWsMqCHeEMuq/8VoGhe1CH4PbQHahd+hJKrhUowPRPBWpJ9CyZ5gaywWUWRZEGbOT0o5xyfhOHXNdCFhofOlpj16nWPTY89jYjXxrXXBpoQ92TVSyTCkHN9MCmO0k1Z16KyDb3esDAdkyvBFr7kSjzzhWoDRFVB+VxwCCcRrzUNUlnElgHo2q7Hv1Xhea9kvg8JLtoAVmAw/myQNrM+6cUfs=" Nov 02 05:19:23 volumio volumio[29587]: info: Getting Spotify volume Nov 02 05:19:23 volumio volumio[29587]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 02 05:19:23 volumio volumio[29587]: info: CoreCommandRouter::volumioGetState Nov 02 05:19:23 volumio volumio[29587]: info: CorePlayQueue::getTrack 0 Nov 02 05:19:23 volumio volumio[29587]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Nov 02 05:19:23 volumio volumio[29587]: SPOTIFY: SPOTIFY VOLUME undefined Nov 02 05:19:23 volumio volumio[29587]: SPOTIFY: VOLUMIO VOLUME 33 Nov 02 05:19:23 volumio volumio[29587]: info: Aligning Spotify Volume to Volumio Volume Nov 02 05:19:23 volumio volumio[29587]: info: CoreCommandRouter::volumioGetState Nov 02 05:19:23 volumio volumio[29587]: info: CorePlayQueue::getTrack 0 Nov 02 05:19:23 volumio volumio[29587]: info: Setting Spotify Volume from Volumio: 33 Nov 02 05:19:23 volumio go-librespot[30690]: time="2025-11-02T05:19:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 02 05:19:24 volumio go-librespot[30690]: time="2025-11-02T05:19:24Z" level=debug msg="completed keyexchange" Nov 02 05:19:24 volumio go-librespot[30690]: time="2025-11-02T05:19:24Z" level=debug msg="completed challenge" Nov 02 05:19:24 volumio go-librespot[30690]: time="2025-11-02T05:19: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 02 05:19:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 05:19:24 volumio volumio[29587]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:19:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 05:19:24 volumio volumio[29587]: Error: socket hang up Nov 02 05:19:24 volumio volumio[29587]: at connResetException (node:internal/errors:720:14) Nov 02 05:19:24 volumio volumio[29587]: at Socket.socketOnEnd (node:_http_client:519:23) Nov 02 05:19:24 volumio volumio[29587]: at Socket.emit (node:events:526:35) Nov 02 05:19:24 volumio volumio[29587]: at endReadableNT (node:internal/streams/readable:1376:12) Nov 02 05:19:24 volumio volumio[29587]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Nov 02 05:19:24 volumio volumio[29587]: code: 'ECONNRESET', Nov 02 05:19:24 volumio volumio[29587]: response: undefined Nov 02 05:19:24 volumio volumio[29587]: } Nov 02 05:19:24 volumio volumio[29587]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:19:24 volumio sudo[30725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-02 05:18' Nov 02 05:19:24 volumio sudo[30725]: 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"