Oct 28 19:06:02 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:02 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Oct 28 19:06:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:02 volumio go-librespot[27740]: go-librespot daemon starting... Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=debug msg="app state loaded" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06: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]" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06: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]" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06: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]" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=info msg="zeroconf server listening on port 42835" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=debug msg="obtained new client token: AAB3nlzRT0me3UZ+qiKF5aG2wW9jq76LkXttxK4LJ5gYJKHjAoHCz632WGjRbrYK6y0IbQ0RvinKrMgmlV/43Yi9WhCuowB/EukEzXMQxU/08/4M4Aj5C00MXYUp1a4Zo6lPZxfioVKbeDiP5Dcd6x8DubsVSsGN75NNobNoJ3/UOlQcc3CEGD6DCMtwqiOKN7vYbLqnWeauvqgV1c76dg9pCELP72Hyb4J1/L6dJh7YIBmtvbpgw6Y=" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=debug msg="completed keyexchange" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06:02Z" level=debug msg="completed challenge" Oct 28 19:06:02 volumio go-librespot[27741]: time="2025-10-28T19:06: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 28 19:06:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:05 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:05 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Oct 28 19:06:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:06 volumio go-librespot[27762]: go-librespot daemon starting... Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="app state loaded" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=info msg="zeroconf server listening on port 43395" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="obtained new client token: AAAm+v17kITYXyCYiwaQ8BSRCu7bhxDIi8cdtROZBDrc4Gl8tn+bSy0RVwkVsu3lVnlBYUqynYHp7xq4V1bWcTHs3rYdEvswpWMxDT+p1/mxjNcJHg60jv80LsywF16LNnTCPbYhxHOVe9tp0576ECWEMQoC/Q9ihlkhLXxN1kcwpG5gjLXrsvNymwkYY1BbfX+++1UW1298EfE1MNaMQZ+M5sqDWFmj5t2pWy0uDYyMf1l58SvS" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="completed keyexchange" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=debug msg="completed challenge" Oct 28 19:06:06 volumio go-librespot[27763]: time="2025-10-28T19:06:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 19:06:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:08 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:08 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Oct 28 19:06:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:09 volumio go-librespot[27770]: go-librespot daemon starting... Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="app state loaded" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=info msg="zeroconf server listening on port 36299" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="obtained new client token: AADqBNrOPlCo6FQuPjrHlCgQpdyKUM6NwrjMl45lIr7GhdXtmuRXRgwXH83aGEtpmwv5kPfUFFMfMyp+3kOmArUR5Bwcm48sMNnwOfgaSiKoaUDCnuOAG3vW5E9ON+h26XJNRjPbvpR4zea8Gyp6Sg2MdCL5zGeYN4JKCs2j47DHvJ3a36qkulVHSavkRUWmXUoDgsg8J/seGPJX6OgZUHDxhdoSVSv9X+BiUH+Rylz3p5aOMAz6feY=" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="completed keyexchange" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=debug msg="completed challenge" Oct 28 19:06:09 volumio go-librespot[27771]: time="2025-10-28T19:06:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 19:06:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:11 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:11 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Oct 28 19:06:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:13 volumio go-librespot[27792]: go-librespot daemon starting... Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=debug msg="app state loaded" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=info msg="zeroconf server listening on port 39615" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=debug msg="obtained new client token: AAC0XH+srYfFgLMXewCne6mfp3F4BVlD//JDVoTZei7ORxQZHRXBo4mLjkzivwp4iscivDCR+GXr0QOGlr9tIQ5aNa3g7IBJtUOpli/s1HT2xkPpbBkzaGVpRGki3V1CYmtQY9PfPfkV5xhYCggTV/iWxVFWZvfzmDr9FSZt1mKuDWQdiakRx3OTROi536sTm1LlOFd4ju8qZZ288G5J8tGBp0cTtuiXX5P6dnCpg1/cWkOuNHep" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=debug msg="completed keyexchange" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06:13Z" level=debug msg="completed challenge" Oct 28 19:06:13 volumio go-librespot[27793]: time="2025-10-28T19:06: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 28 19:06:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:14 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:14 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Oct 28 19:06:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:16 volumio go-librespot[27800]: go-librespot daemon starting... Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="app state loaded" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=info msg="zeroconf server listening on port 39341" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="obtained new client token: AAC6FJ9ktihvv6Dw4XQRvW0+JYmrwJAI1HPiHl0WraB3IMKvIZQrMoPfrn1Hv5KQQu/MFwGlKO2xcOx949oMgRMejnlo4Kgwd+oL0m4luPV4aAgr2OhkIhZjpvfgmUlNKrvolWhFG0XDW8on/chBiOB715NmzJ2qPOn3Tu2Cbu/qZjcE28YrHn36dI2/1uw4bO+AlUhsITaiGKm/GP+QxbTB4/UoDlqpQg3+q+u2howlaSZTO++rJ7E=" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="completed keyexchange" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06:16Z" level=debug msg="completed challenge" Oct 28 19:06:16 volumio go-librespot[27801]: time="2025-10-28T19:06: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 28 19:06:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:17 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:17 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Oct 28 19:06:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:20 volumio go-librespot[27811]: go-librespot daemon starting... Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=debug msg="app state loaded" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06: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]" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06: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]" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06: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]" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=info msg="zeroconf server listening on port 41379" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=debug msg="obtained new client token: AADa7hwdfvgKqM4Rqfx+cxyQvkdAfwhFvrPp0pJ3vc4JasIc5a1Hmmq3VgAcp0RuRW4ioAE56cUBK7sgGzRaZirDdRIAYFb1iatcKaKRhzKAJBCBFU1lZhtm5OngNfX0KxSkdMBfGPHGmS9+7zTfwBgJdH1+hEoQCEmBhf+UizSHv09q4T5jNdckGNBoDYktl7SRi8Gr5p1tCdSqCBjlGrN0uPlnrsYk0rUO2JYW9TFjgp5KFLq7" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=debug msg="completed keyexchange" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06:20Z" level=debug msg="completed challenge" Oct 28 19:06:20 volumio go-librespot[27812]: time="2025-10-28T19:06: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 28 19:06:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:20 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:20 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:23 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:23 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Oct 28 19:06:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:23 volumio go-librespot[27835]: go-librespot daemon starting... Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="app state loaded" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=info msg="zeroconf server listening on port 44795" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="obtained new client token: AABtXnzNk3D59sIi40k5sr4F3fPoaZWGApEdRFvywTkoI8zDBZpnVC72a5Nu7YL4e/MuheQNkgpsSA9cvQjT+B0QqAEKKCFQo3mqVzB4pmZqz7JTbHK3TUQqBv80SCRea+r5aOdfJbaV3h6b0JfI+KobC3Akv/fW09tyVjZJVo+9ri890jM4TLJXZTLJDw0hh/ealoy9LfmahJ13ezRrEqydl3H83omvQ0Psb5FyuB5GLg2VFu4uHkc=" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="completed keyexchange" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06:23Z" level=debug msg="completed challenge" Oct 28 19:06:23 volumio go-librespot[27836]: time="2025-10-28T19:06: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 28 19:06:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:26 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:26 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Oct 28 19:06:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:27 volumio go-librespot[27843]: go-librespot daemon starting... Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=debug msg="app state loaded" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=info msg="zeroconf server listening on port 34163" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=debug msg="obtained new client token: AAD5lDAJcp0XScQoccCCbTwLS1tL5CWLMOXIahs6KBxlyA+SGcdDkOMNeOrV7g7qpD+oafU240ZY+FVbvnbVYnsOeDuiYtlgROXRF3CYyeXb6jezVmhFV3OvRq6cOWkEDNvf6tbcuZKcKhDFftxKrSFL6ufqqCi7LsE32X+Q561vn4JCaurzI82DVfjXZVP2nPup82Bk89Gy1Fm3lhVocfapmYd5qWqbn1VZg650SG+oaaU3MvDC" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=debug msg="completed keyexchange" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06:27Z" level=debug msg="completed challenge" Oct 28 19:06:27 volumio go-librespot[27844]: time="2025-10-28T19:06: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 28 19:06:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:29 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:29 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Oct 28 19:06:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:30 volumio go-librespot[27851]: go-librespot daemon starting... Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="app state loaded" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=info msg="zeroconf server listening on port 46299" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="obtained new client token: AAALdlYkr67kVXWNxgYyHjDfEQ2frZxw0VQ/zfM3JWU3b33nESYb9I+YYD3Ow/ds3DFnldn6nrPsHZMQs9fU+UC/kD9ZhtMX5RZlApDqCGMF54n8cIQQOiiOq8AewlbcEt/RspWv2r40vSzQmvkxK7i8OxwqoXLcH9LVN7yT+ih6PN7Ls1bv98IOxN1z/MjccWGdAIA0E3dL1uDQhYNkZu87LrOdx9OnRdCt127WonIwwtVppfpaYiw=" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="completed keyexchange" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06:30Z" level=debug msg="completed challenge" Oct 28 19:06:30 volumio go-librespot[27852]: time="2025-10-28T19:06: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 28 19:06:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:32 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:32 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Oct 28 19:06:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:34 volumio go-librespot[27873]: go-librespot daemon starting... Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=debug msg="app state loaded" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06: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 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06: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 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06: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 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=info msg="zeroconf server listening on port 45195" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=debug msg="obtained new client token: AADCXEmWYB7Mq17P/4IrpfSujvia/R37fx3rzzULvP6rELx7Hdel3XE74Ssp68sIAe04KWPrZt9ckst3y+TIY6OX3gN16aod0tqD6tOnMTX4J9kGFon1lULo9FDspCF54elJBJ1gdsKPCyY6KLgLf6Qu/qMSTyu0m7rNmwm+ZH0AOXgcqvxPRTv8aNpF6xm7qWaKZB0DC91yX1jrTFM9W/HhmFhkaHOOYMxIH2rtxjE0TqmvJTgp" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=debug msg="completed keyexchange" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06:34Z" level=debug msg="completed challenge" Oct 28 19:06:34 volumio go-librespot[27874]: time="2025-10-28T19:06: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 28 19:06:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:35 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:35 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Oct 28 19:06:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:37 volumio go-librespot[27881]: go-librespot daemon starting... Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=debug msg="app state loaded" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06: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 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06: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 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06: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 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=info msg="zeroconf server listening on port 46869" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=debug msg="obtained new client token: AABB3bYcEFuy1gLWaZi5mFCi+LIKKU86TtbGyWtXz8vuXPxS7YVFYYloOF7EyDZO5ZwzKTFuSEcKtkDMZsee0mdYJKT0ijKk9/N0z+74jyy23foXYxq2DhXkrqambEKqZ/7ZKIZvAEwzokWHcKML0RDYguVJFo+9rkYItEhuKkcwpgoQHcy0G8niQ7JQNVlVReuq/iMK7AwasJvIGUSZ4dWhHTZ+AtmBQpbmH/QldNPf9+XYzbjC0ig=" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=debug msg="completed keyexchange" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06:37Z" level=debug msg="completed challenge" Oct 28 19:06:37 volumio go-librespot[27882]: time="2025-10-28T19:06: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 28 19:06:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:38 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:38 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Oct 28 19:06:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:41 volumio go-librespot[27894]: go-librespot daemon starting... Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=debug msg="app state loaded" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06: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 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06: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 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06: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 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=info msg="zeroconf server listening on port 42483" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=debug msg="obtained new client token: AAAuhsNTbhH2hufgnN9OcR3ZHyYDkgE+juSXHhhxkMEdJC4gxu0Ak7Fs4/vPKsezNZWdKySZZLB+rg4jCoCDRRijjTo6u6FCh3p6GmY7C5y43o3eGZ5ikNFD9FhdcuR1Z/AZZT70CL+QbNsxCIACzmGJi571gpuLJh/z38X1KxdOKDOaTrmKuORvR2E2QmDu//HPuavBdBRTggOrj/82IrBvIkAoxG2HM0WJF1mj27we4QKWb5/W" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=debug msg="completed keyexchange" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06:41Z" level=debug msg="completed challenge" Oct 28 19:06:41 volumio go-librespot[27895]: time="2025-10-28T19:06: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 28 19:06:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:41 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:41 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:44 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:44 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Oct 28 19:06:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:44 volumio go-librespot[27916]: go-librespot daemon starting... Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=debug msg="app state loaded" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=info msg="zeroconf server listening on port 37873" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=debug msg="obtained new client token: AAB9xUYve/l6uSC8sQMSV19y3jNkyIThKW8xkh2rtsG879+vuPgl097Eyk9wTIf8LDVPUQqeHhvG/fY0L9GIk73doqN99JYSLZ8PMKnq/oIhYpPLLgUqEoqv47Uyh94RwIYH6KKzA7oQIAceMlVDxEC5yKMpShHjrJpdFQoLbqSWliPycU8+yHVKTZs9cy/tuV2zd4knb1mi/vfFtpE69xcm41Jk3UJmddNGSmyZbWryJhsCCE03bOw=" Oct 28 19:06:44 volumio go-librespot[27917]: time="2025-10-28T19:06:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:45 volumio go-librespot[27917]: time="2025-10-28T19:06:45Z" level=debug msg="completed keyexchange" Oct 28 19:06:45 volumio go-librespot[27917]: time="2025-10-28T19:06:45Z" level=debug msg="completed challenge" Oct 28 19:06:45 volumio go-librespot[27917]: time="2025-10-28T19:06:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 19:06:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:47 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:47 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Oct 28 19:06:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:48 volumio go-librespot[27925]: go-librespot daemon starting... Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06:48Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06:48Z" level=debug msg="app state loaded" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06:48Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06: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]" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06: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]" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06: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]" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06:48Z" level=info msg="zeroconf server listening on port 33567" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06:48Z" level=debug msg="obtained new client token: AAAUYlk9t+AlI+KdIGiAxSp1eudRyVPGw49KnUZkinlFVgkcD60nu7qKK1IahwXwLgsDYdZ3YcqDAPKeLfY59NHhf7A4ioD+6V3S8ZdthZlBsXICLcntt+KvSjMuPKtCCD+yAAkjDJ6m1vErORW07c58YwWyYePWbDSYHwaOK/wygSu6SpY5PwXJYqv/0HkbsKTH1Wg/mzAj9E9XUP7XQCOQptwC1FEoCrUblg50SoGmZrdVvk1AM+I=" Oct 28 19:06:48 volumio go-librespot[27926]: time="2025-10-28T19:06:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:49 volumio go-librespot[27926]: time="2025-10-28T19:06:49Z" level=debug msg="completed keyexchange" Oct 28 19:06:49 volumio go-librespot[27926]: time="2025-10-28T19:06:49Z" level=debug msg="completed challenge" Oct 28 19:06:49 volumio go-librespot[27926]: time="2025-10-28T19:06:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 19:06:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:50 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:50 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Oct 28 19:06:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:52 volumio go-librespot[27933]: go-librespot daemon starting... Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="app state loaded" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=info msg="zeroconf server listening on port 37701" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="obtained new client token: AAC7P9mlXyjgP5nXqbU4xXINuNFxBdTVsXhQ9MuMeyZZHfzbvI4bqZ+EHU6husemv8jlqPJNPFeSEnMgiN8/Wm7pPk22r1kDohJbtWBe9ZVmwqpEAVyRPxk9wuROQlDAn4o0HZ1zu/PatQsTFQ/KVgEJxRJ6WG6e3I+m16J+wKroR1cp0YDcGi9qnkaCLaYoax2CVY80fdyLmGwucsM+5OPTW/EqiZVZNSGH6C5UGdtd30t9SHWDKB4=" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="completed keyexchange" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=debug msg="completed challenge" Oct 28 19:06:52 volumio go-librespot[27934]: time="2025-10-28T19:06:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 19:06:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:53 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:53 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Oct 28 19:06:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:55 volumio go-librespot[27956]: go-librespot daemon starting... Oct 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06:55Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06:55Z" level=debug msg="app state loaded" Oct 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06:55Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06: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 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06: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 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06: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 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06:55Z" level=info msg="zeroconf server listening on port 45935" Oct 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06:55Z" level=debug msg="obtained new client token: AADXh5da6p35dRtTKNdN3n9NvPfjcfLZ047QMhrEW4q0hRqZ7nOSKMBAAi91e6x/hrLu4ExqeQSnsBib50JC6FQUKeSqz+ifKkaaUtVJLbeyPHmTdZVuxot4UY6bPQ+M3pGPXeez/EwGilqq5WOGD4gdBbzhHWGBu7lc69NVBVz4lNmTHDPmc8GybE1iGL9aOWOX68sQBa8dYx2MAEtzzVUJGNT4o81SK7KpkzyyBtDgOFCvwmAxbzE=" Oct 28 19:06:55 volumio go-librespot[27957]: time="2025-10-28T19:06:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:56 volumio go-librespot[27957]: time="2025-10-28T19:06:56Z" level=debug msg="completed keyexchange" Oct 28 19:06:56 volumio go-librespot[27957]: time="2025-10-28T19:06:56Z" level=debug msg="completed challenge" Oct 28 19:06:56 volumio go-librespot[27957]: time="2025-10-28T19:06:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 19:06:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:06:56 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:56 volumio volumio[27049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:06:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Oct 28 19:06:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 19:06:59 volumio go-librespot[27968]: go-librespot daemon starting... Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=info msg="running go-librespot 0.4.0" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="app state loaded" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=info msg="zeroconf server listening on port 35241" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="obtained new client token: AAB5EP4kWkY8GmikbVBLu6/HmuI0UL4wFgtJLjm3BaPtth7AWCN9Q6KEUnbbnTPKhP/KrThynBF/Q78CTnpyzsOz8bSh0/lBkgZkxrHLpFuefsVfGsZxTxytPe1K5AjugNY0l44ywfBtvdqdA39LcnxmbQ0rIKh4N7pkB8JrRuw+ylA2yAd54smG41JuzTF/MN8ruSFp39gJlGVjDynEFsSgKRl71DvyGSrXV3S1CaOmH7nioaLRNoU=" Oct 28 19:06:59 volumio volumio[27049]: info: Initializing connection to go-librespot Websocket Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="new websocket client" Oct 28 19:06:59 volumio volumio[27049]: info: Connection to go-librespot Websocket established Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="completed keyexchange" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=debug msg="completed challenge" Oct 28 19:06:59 volumio go-librespot[27969]: time="2025-10-28T19:06:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 19:06:59 volumio volumio[27049]: info: Connection to go-librespot Websocket closed Oct 28 19:06:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:06:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 19:07:02 volumio volumio[27049]: info: Getting Spotify volume Oct 28 19:07:02 volumio volumio[27049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 19:07:02 volumio volumio[27049]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 19:07:02 volumio volumio[27049]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 28 19:07:02 volumio volumio[27049]: errno: -111, Oct 28 19:07:02 volumio volumio[27049]: code: 'ECONNREFUSED', Oct 28 19:07:02 volumio volumio[27049]: syscall: 'connect', Oct 28 19:07:02 volumio volumio[27049]: address: '127.0.0.1', Oct 28 19:07:02 volumio volumio[27049]: port: 9879, Oct 28 19:07:02 volumio volumio[27049]: response: undefined Oct 28 19:07:02 volumio volumio[27049]: } Oct 28 19:07:02 volumio volumio[27049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 19:07:02 volumio sudo[27992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 19:06' Oct 28 19:07:02 volumio sudo[27992]: 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"