Oct 25 22:11:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 702. Oct 25 22:11:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:01 volumio go-librespot[7346]: go-librespot daemon starting... Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=debug msg="app state loaded" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" 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 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" 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 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" 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 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=info msg="zeroconf server listening on port 35697" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=debug msg="obtained new client token: AAABmjqox7fpGeFdn2bak06Kt2w6HnPlO190s4gBf4lTC/JqusPlthDraS1kz2WRqeyfmx2O0wfXcj1KOECtKdq7Zd3Ja34BCRPt/zLbJ9qfeCmPJwZBVBl/CSM9J0CVYrUpaLfWNDVCKuvyXMWRwhuZV95sMCpiSoO92lfnTtXmyWvToH3BPXTINrynM5HjtHgsGEwnF9WwL9fzET/gwYLpHNvxHKbYn6YdEh3MNdHGorJ4xRSBQwU=" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=debug msg="completed challenge" Oct 25 22:11:01 volumio go-librespot[7347]: time="2025-10-25T22:11:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:02 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:02 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 703. Oct 25 22:11:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:04 volumio go-librespot[7368]: go-librespot daemon starting... Oct 25 22:11:04 volumio go-librespot[7369]: time="2025-10-25T22:11:04+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:04 volumio go-librespot[7369]: time="2025-10-25T22:11:04+01:00" level=debug msg="app state loaded" Oct 25 22:11:04 volumio go-librespot[7369]: time="2025-10-25T22:11:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" 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 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" 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 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" 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 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" level=info msg="zeroconf server listening on port 40207" Oct 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" level=debug msg="obtained new client token: AADQxYjFxWxC0CC4hkCNSp0xuaaFPx9I/2ELVZz9RlA07YD0itwmu6n6gs4crEB7vPw6ERcKeHyHNbzhnyTRGL6kpqDVoPGy+UYoRld3jsb0A0XC3JFZQF39WLiQ/MDSDZ1WFQA3LwsCtigCWfJr/IWfxnTJ1+YusYFz2cSm8k0IBmNt9e8yX2iTCwGXg8VR3mj8/puPHpoGMzdJsgFT3VFzBlt1hltHNzaoOIjhHGkn4jcUvjaM" Oct 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" level=debug msg="completed challenge" Oct 25 22:11:05 volumio go-librespot[7369]: time="2025-10-25T22:11:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:05 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:05 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:08 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:08 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 704. Oct 25 22:11:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:08 volumio go-librespot[7376]: go-librespot daemon starting... Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=debug msg="app state loaded" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" 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 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" 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 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" 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 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=info msg="zeroconf server listening on port 35485" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=debug msg="obtained new client token: AAA0acvVj1uBBNmDCUl2Oal0aGZgUsqB0Op0LkhdofF7KJznbuAx10VZEt92ShO2cCVoBI0pVp/0jzoMOvkFhydVCfYtwcGYTWDnfzc1+OtyQtkrDRwdnrIXRznev0ootv5waZpmgmlJoyXWkYoYhcNkDCUXI54JE3Lx0vg/vBebUAA84TeNdVeXB9MA4d4+m+0NlWp72lhp1onnqNA3q1nVdazeHfDZhy3MvwebBocGSnR4wymMOVw=" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=debug msg="completed challenge" Oct 25 22:11:08 volumio go-librespot[7377]: time="2025-10-25T22:11:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:11 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:11 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 705. Oct 25 22:11:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:11 volumio go-librespot[7399]: go-librespot daemon starting... Oct 25 22:11:11 volumio go-librespot[7400]: time="2025-10-25T22:11:11+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:11 volumio go-librespot[7400]: time="2025-10-25T22:11:11+01:00" level=debug msg="app state loaded" Oct 25 22:11:11 volumio go-librespot[7400]: time="2025-10-25T22:11:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" 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 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" 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 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" 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 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" level=info msg="zeroconf server listening on port 35135" Oct 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" level=debug msg="obtained new client token: AAA+wMYn2eugxSMcGTFPGxLwKW/7lwkI6ymg1m/GoJpWwS5fqT6dVUBDLY0kbbd7vwGtYKZZK3kyS5CIBXRWNa6EEsrqlvNhR5pA2EZkE8NdRUNSLCKr9fXlb+YG8cd8Nma/Th8tGIDgsZnJMoJcZIsqy53V1LjAjtDMAF5589/OrLvHnWuzOvkXoaqQsEtE2qeue4AqdRyTD4nDfL1Lra7nPisY4hJaHztBkhbJs4vASOemeF3K" Oct 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" level=debug msg="completed challenge" Oct 25 22:11:12 volumio go-librespot[7400]: time="2025-10-25T22:11:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:14 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:14 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 706. Oct 25 22:11:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:15 volumio go-librespot[7407]: go-librespot daemon starting... Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=debug msg="app state loaded" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" 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 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" 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 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" 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 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=info msg="zeroconf server listening on port 45571" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=debug msg="obtained new client token: AABijRoxO/Pn4yV5kIIdQ65SJEk+bEUPnIIahjbNEUx0sMDr+MAoiSr+4DyOAi/Bb/tHc8p2sWxTifYRx3bv6TmT5xeryq49+Dj7hWRuyVp7bEOpmH8AexYJuLc55rKH6L+tSW/YXIRCfAGSvyRdKNPvw9GXr6y7Qv1In1TV57nFpYx0h0AIsbxZVFfvdyavlMnPGrf6F0ufG4YLd+ydlLo/SPGgDHkHQdRhaSYkjCUeq0pQaRbjDD8=" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=debug msg="completed challenge" Oct 25 22:11:15 volumio go-librespot[7408]: time="2025-10-25T22:11:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:17 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:17 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 707. Oct 25 22:11:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:18 volumio go-librespot[7418]: go-librespot daemon starting... Oct 25 22:11:18 volumio go-librespot[7419]: time="2025-10-25T22:11:18+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:18 volumio go-librespot[7419]: time="2025-10-25T22:11:18+01:00" level=debug msg="app state loaded" Oct 25 22:11:18 volumio go-librespot[7419]: time="2025-10-25T22:11:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" 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 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" 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 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" 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 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" level=info msg="zeroconf server listening on port 45083" Oct 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" level=debug msg="obtained new client token: AAB33XWgq0UR5mTv+p6Ywn4shvDEeXcpZYi32sqvOOtNrNQXL0Dv/pEftBVmBfgwKYcE2WSWKnizB1eY0jXY2RbOqWUeAEli/Ulls8L8Ncq8ZFMaopjtuGYp3OwXW6V8yhjiZcSGusAN2hJzI0OoZI6ss/WTMDdAprUVNrjSfTMM81VpW1a7aWoEYHORm1kW0rZJlBmzafxUp26BNXJZfbMgTso2MQaXDy9/pY8DPrT3ieCtFOSb" Oct 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" level=debug msg="completed challenge" Oct 25 22:11:19 volumio go-librespot[7419]: time="2025-10-25T22:11:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:20 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:20 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 708. Oct 25 22:11:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:22 volumio go-librespot[7440]: go-librespot daemon starting... Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=debug msg="app state loaded" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" 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 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" 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 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" 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 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=info msg="zeroconf server listening on port 33725" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=debug msg="obtained new client token: AACjn/zuBCLqKWXOCYqEOjLRI9yNuItAGQy6j0g/TafEV8O9FVxAat0T5bUKlohthsNRI432FRx9R3fnqyuuR/50JV38TTc7DqqjFiXPUhSO18fMQArArr14uhfb3CdOwv8yf99M82214hiJIxThNUYgr1+KVAidfwf2Td7H801jdTYc89BtEWd1UnBjbZrACPyIei5Uwd3v0MfcMtXSkkPernS3wHZ23ao86HKrHdW8Dz1I4iDjfQY=" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=debug msg="completed challenge" Oct 25 22:11:22 volumio go-librespot[7441]: time="2025-10-25T22:11:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:23 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:23 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 709. Oct 25 22:11:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:25 volumio go-librespot[7448]: go-librespot daemon starting... Oct 25 22:11:25 volumio go-librespot[7449]: time="2025-10-25T22:11:25+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:25 volumio go-librespot[7449]: time="2025-10-25T22:11:25+01:00" level=debug msg="app state loaded" Oct 25 22:11:25 volumio go-librespot[7449]: time="2025-10-25T22:11:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" 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 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" 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 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" 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 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" level=info msg="zeroconf server listening on port 44313" Oct 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" level=debug msg="obtained new client token: AAAd1My6w5mfyzUnqiOYdc113s5TYRJhiCZApvfEZsUETKFPRubx7QVNB19smbJuTHLkv3vGO4OKKQct9Vr3bEQEaSdaV3hmFCZnyRwO7iMaUFE5j+wc0Y2yPa+8MtAJgV3i4R5ya+f9w4EYx5f6UC/1LlgnMf0uN1svkEPDZmQA6+XACp7UGFF0YmufU8SAAIh7JbOBNR+3hCiZM3IKro/SOKo/HHefh5VywmqBDzvcfKtnioUs" Oct 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" level=debug msg="completed challenge" Oct 25 22:11:26 volumio go-librespot[7449]: time="2025-10-25T22:11:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:26 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:26 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:29 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:29 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 710. Oct 25 22:11:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:29 volumio go-librespot[7457]: go-librespot daemon starting... Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=debug msg="app state loaded" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" 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 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" 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 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" 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 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=info msg="zeroconf server listening on port 34733" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=debug msg="obtained new client token: AADpreuBSebmj1p5bVeTHClutaJx3Pq6mXntflxmBOh7G6syDf7ZhWj9DrYEg9aj2ohEN82W/VW2v0EiwHvM9Lf/0qkpRmeifcCRE+3hZtXusECx7Z5PcE2WBRTNQjcDwugpzNgJttaXDnLAuymU8Vzg74T9ZxleN6FErGDRGhmPOHKLMUOzdAklcwflTX6/pch8mT+xa2SalY2v2oy/Vh6sAZ8SqTLhyo9Xm4STSivt+dcO755Bv3I=" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=debug msg="completed challenge" Oct 25 22:11:29 volumio go-librespot[7458]: time="2025-10-25T22:11:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:32 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:32 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 711. Oct 25 22:11:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:32 volumio go-librespot[7479]: go-librespot daemon starting... Oct 25 22:11:32 volumio go-librespot[7480]: time="2025-10-25T22:11:32+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:32 volumio go-librespot[7480]: time="2025-10-25T22:11:32+01:00" level=debug msg="app state loaded" Oct 25 22:11:32 volumio go-librespot[7480]: time="2025-10-25T22:11:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" 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 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" 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 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" 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 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" level=info msg="zeroconf server listening on port 40793" Oct 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" level=debug msg="obtained new client token: AAB5uIt+1DfTh99Az4n5ALrL8SqCxzFmZktwrDojhinIYpknbEyDyqO8OR7B3BYMPhZUDiW4KbUAGr7p2K0Cs6aUJxzx3zovnDhN16Fo0cjCpIj/T/S9KulqOVcAHHCn0d5RZfvoLViU5A8a9hrweyaRM6FPNvjifvjVRJsXjsKPONY8bjh409JPTU87p6GXDkdBzv5f2I+qhfrrwKxBPmeEVcBhXGnGX3a1ze/VQRRfJttevtjn" Oct 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" level=debug msg="completed challenge" Oct 25 22:11:33 volumio go-librespot[7480]: time="2025-10-25T22:11:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:35 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:35 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 712. Oct 25 22:11:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:36 volumio go-librespot[7487]: go-librespot daemon starting... Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=debug msg="app state loaded" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" 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 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" 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 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" 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 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=info msg="zeroconf server listening on port 43599" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=debug msg="obtained new client token: AABxPnj4ItU8m5EO8gEOnpjNTykiE2Ke6GdI9FiLxqxDokMEHeSkaFHePaYvBiG/KZI/BSIsbJK/ghUTmC/caZgd4rIKF2mdHYJjZCc+w3BB3ysAZ17zXmLTL8wYezU4EI8rWrYAfkcbWI6D95bR6Oc6gSrbkMAJEJ5LBSIQ2lDAHeZ8cDCCQn0ma2it8HXTJaMFVxAaAJ1vfm75KZcxcCUqd9b29rSnZ4msuie8UYZ9CHW1RHdqPaw=" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=debug msg="completed challenge" Oct 25 22:11:36 volumio go-librespot[7488]: time="2025-10-25T22:11:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:38 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:38 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 713. Oct 25 22:11:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:39 volumio go-librespot[7498]: go-librespot daemon starting... Oct 25 22:11:39 volumio go-librespot[7499]: time="2025-10-25T22:11:39+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:39 volumio go-librespot[7499]: time="2025-10-25T22:11:39+01:00" level=debug msg="app state loaded" Oct 25 22:11:39 volumio go-librespot[7499]: time="2025-10-25T22:11:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" 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 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" 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 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" 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 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" level=info msg="zeroconf server listening on port 38783" Oct 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" level=debug msg="obtained new client token: AABkbch9l4ZkXnCrU4CllG1h+bpSNNMedpG/eD7I1uq1pKf6GyD11N5S7Igbr62oy/re6EDsUMDw/R2SaUA1td4v3nFIS2QxQ1Ixmec+o++NGuGMzTWHuytS78JLYoOCw/ZyESB9jkwMrg9i+LnVhto+IWJOJmy2tGCdQzRslL0IFz5YJ0YSeSXep8d+xaU9cQ8p2PrE+6wV69K5KrMNDD29Yv9BkxegOkI4cv+U9wpu266ihdPF" Oct 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" level=debug msg="completed challenge" Oct 25 22:11:40 volumio go-librespot[7499]: time="2025-10-25T22:11:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:41 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:41 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 714. Oct 25 22:11:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:43 volumio go-librespot[7520]: go-librespot daemon starting... Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=debug msg="app state loaded" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" 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 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" 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 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" 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 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=info msg="zeroconf server listening on port 41393" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=debug msg="obtained new client token: AAD/FUWnjdxcXKH9znLf/1CJN0BmM0xstMowhlDF4EMuzvadGdEOjgUjFh+Zm+ScuH04NzUNvQtf4eAT8l38UPfd5rwbmrkVkDs2r/0tEnEkUyLWHabd7rVOcQaUYVw4mbMMw3+5+Vt03lY4igCTR7WSVK1y4LwYvtAMGrDX4Ztn5qiy7V50C62ySHQAKP2YbBEjBVMdz43Mo6v2LHii01ebN3Wtssx3EK2qxWEFNyYGaRxJdtxgrSQ=" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=debug msg="completed challenge" Oct 25 22:11:43 volumio go-librespot[7521]: time="2025-10-25T22:11:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:44 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:44 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 715. Oct 25 22:11:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:46 volumio go-librespot[7529]: go-librespot daemon starting... Oct 25 22:11:46 volumio go-librespot[7530]: time="2025-10-25T22:11:46+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:46 volumio go-librespot[7530]: time="2025-10-25T22:11:46+01:00" level=debug msg="app state loaded" Oct 25 22:11:46 volumio go-librespot[7530]: time="2025-10-25T22:11:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" 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 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" 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 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" 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 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" level=info msg="zeroconf server listening on port 43775" Oct 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" level=debug msg="obtained new client token: AAAWsV9ZX4FKIOg4e+JtQSeaJ+2Dkkb8HxqN+rmfn8fH6rNFX5RwmEUo56pzQw6z4kAWNZ263jsmJopt4gOtEP+Q/62R8YdZXtoTmd7RyVpRWp2xl+Mdpk76Qy+56F/vGccHUGPV0ftm35h2XwP7TC6c1ViqSZgS7x7hFonRzS1COTK6RaTOMowvv33OOwRiivsrKzVgRXoNbVHCtMcL3FdZr4z8tMTI+TwNTaZ1De2OWpxMyJ9k" Oct 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" level=debug msg="completed challenge" Oct 25 22:11:47 volumio go-librespot[7530]: time="2025-10-25T22:11:47+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:47 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:47 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:50 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:50 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 716. Oct 25 22:11:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:50 volumio go-librespot[7539]: go-librespot daemon starting... Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=debug msg="app state loaded" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" 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 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" 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 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" 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 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=info msg="zeroconf server listening on port 34967" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=debug msg="obtained new client token: AABM+Glgh2jdpZ0WgCiKtxty5cTTq34sNQolsXdnwxe6Cyiko4mo9oDkLoOwnUjR4JK3Lr7FkwD2EMtpuDxiR9BTjad6efKVR5g45GL7tOTKCxndlqSEXxdHIaov+kV5jR2AW8kzn+bPUmdL/L3bRxGMRX9ALt/ZfuG5YfHKpfZzm6tRPgEBleduMuoO4fi0a9U/GJN/b2RaD0k35ykAGNkOSHxRpim+jjrpXRg33WNXiQ5LK/GqOec=" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=debug msg="completed challenge" Oct 25 22:11:50 volumio go-librespot[7540]: time="2025-10-25T22:11:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:53 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:53 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 717. Oct 25 22:11:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:53 volumio go-librespot[7561]: go-librespot daemon starting... Oct 25 22:11:53 volumio go-librespot[7562]: time="2025-10-25T22:11:53+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:53 volumio go-librespot[7562]: time="2025-10-25T22:11:53+01:00" level=debug msg="app state loaded" Oct 25 22:11:53 volumio go-librespot[7562]: time="2025-10-25T22:11:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" 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 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" 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 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" 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 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" level=info msg="zeroconf server listening on port 43855" Oct 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" level=debug msg="obtained new client token: AACr4uXzRXRNod9e3SENDmw3DIPYFmrLfvaU5IcVh7MbDF/6gPLCmIhsuZRydCkusery1OQfO9wSJ3Ex/9Z70MiAkIdNMorgU0a/3CzsY9Y9RZC+bPI5DxSpegzF220VJmlMqd2sQtKUtb7PQtcKvasPapHoPp94eUohUcuBDBwGzHVKBARYF861j8m//LD3MPCac5uLCjnYONfOvpA+sDPAoN4Rr9o+y8ejS4+eheWI4j6LpjbR" Oct 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" level=debug msg="completed challenge" Oct 25 22:11:54 volumio go-librespot[7562]: time="2025-10-25T22:11:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:56 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:56 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:11:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 718. Oct 25 22:11:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:11:57 volumio go-librespot[7569]: go-librespot daemon starting... Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=debug msg="app state loaded" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" 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 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" 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 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" 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 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=info msg="zeroconf server listening on port 42039" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=debug msg="obtained new client token: AACSTg60+TCgeGoOVl6Bc586MB1ssS1GIRLYIRM72eNyTEIFQNBVBoTP+Ax27LZpAQ991iCPwzKPzn+/vmWV/XEBqm8/8Pe+up62sHIAhwPlBdoTJI630h9FZb+uCxgAThz1EbYwldZuch395M0BAG5niuesFvJEbjLrsWxXM5Uj6W06WSdzBhCLVzu6d8Jvhuy3lWVtilKl9Wy/SqV+qessC9F6fDvTkPc+XboHpwPTo9JhthRwKig=" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=debug msg="completed keyexchange" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=debug msg="completed challenge" Oct 25 22:11:57 volumio go-librespot[7570]: time="2025-10-25T22:11:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:11:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:11:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:11:59 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:11:59 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:12:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 719. Oct 25 22:12:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:12:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:12:00 volumio go-librespot[7580]: go-librespot daemon starting... Oct 25 22:12:00 volumio go-librespot[7581]: time="2025-10-25T22:12:00+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:12:00 volumio go-librespot[7581]: time="2025-10-25T22:12:00+01:00" level=debug msg="app state loaded" Oct 25 22:12:00 volumio go-librespot[7581]: time="2025-10-25T22:12:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" 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 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" 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 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" 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 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" level=info msg="zeroconf server listening on port 41399" Oct 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" level=debug msg="obtained new client token: AAB4HAn9gZSN5BMaiB0R9vwbKW0VQUvlKetV6G0DRSixUID4KXaTsTkFYv05ldAfhZ/bgInFTYOy7TD5WErro0FdftzR3CwyE5srnSZzXQyZCZ9Yeg7jRz1RmsPjEg/lxXzWRp9kTizDOyOVjZzeOchRE87BO85aQirotkfWbxGMBVxGR7OqWfx1PcjpBt5g5vrSwL5PcpHeKeJP6yuHI05UaMx0V6udACavcPYoG3MC2JCeROpm" Oct 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" level=debug msg="completed keyexchange" Oct 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" level=debug msg="completed challenge" Oct 25 22:12:01 volumio go-librespot[7581]: time="2025-10-25T22:12:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:12:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:12:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:12:02 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:12:02 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:12:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 720. Oct 25 22:12:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:12:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:12:04 volumio go-librespot[7602]: go-librespot daemon starting... Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=debug msg="app state loaded" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" 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 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" 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 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" 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 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=info msg="zeroconf server listening on port 46563" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=debug msg="obtained new client token: AADOP9Asw7iGzdHX47TTreLy4BBp00S47uCDQYAJeZMMaRzfGwwOt8mfHqRuSLzMkyQXHMKf8+1KFwwgsPvnBDzZBXsgHIBZ64VyNglHYJUKL0eHTnP0sFJKH3TDXkzjgMXPg0OhB+nGcHQuvwWHVptCgN63qRGzBhEtn1hjhYnLC1mwgBOl5syByBjS7NcWQy9wFOtVjqfqKcY4hUsFnRojBEA2F6MABFT5vgwCMnCI0eErLI4XWOw=" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=debug msg="completed keyexchange" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=debug msg="completed challenge" Oct 25 22:12:04 volumio go-librespot[7603]: time="2025-10-25T22:12:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:12:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:12:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:12:05 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:12:05 volumio volumio[6462]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 22:12:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 721. Oct 25 22:12:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:12:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 22:12:07 volumio go-librespot[7610]: go-librespot daemon starting... Oct 25 22:12:07 volumio go-librespot[7611]: time="2025-10-25T22:12:07+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 22:12:07 volumio go-librespot[7611]: time="2025-10-25T22:12:07+01:00" level=debug msg="app state loaded" Oct 25 22:12:07 volumio go-librespot[7611]: time="2025-10-25T22:12:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 22:12:08 volumio go-librespot[7611]: time="2025-10-25T22:12:08+01:00" 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 25 22:12:08 volumio go-librespot[7611]: time="2025-10-25T22:12:08+01:00" 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 25 22:12:08 volumio go-librespot[7611]: time="2025-10-25T22:12:08+01:00" 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 25 22:12:08 volumio go-librespot[7611]: time="2025-10-25T22:12:08+01:00" level=info msg="zeroconf server listening on port 38741" Oct 25 22:12:08 volumio volumio[6462]: info: Initializing connection to go-librespot Websocket Oct 25 22:12:08 volumio go-librespot[7611]: time="2025-10-25T22:12:08+01:00" level=debug msg="new websocket client" Oct 25 22:12:08 volumio volumio[6462]: info: Connection to go-librespot Websocket established Oct 25 22:12:11 volumio volumio[6462]: info: Getting Spotify volume Oct 25 22:12:11 volumio volumio[6462]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 25 22:12:11 volumio volumio[6462]: info: CoreCommandRouter::volumioGetState Oct 25 22:12:11 volumio volumio[6462]: info: CorePlayQueue::getTrack 0 Oct 25 22:12:11 volumio volumio[6462]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Oct 25 22:12:11 volumio volumio[6462]: SPOTIFY: SPOTIFY VOLUME undefined Oct 25 22:12:11 volumio volumio[6462]: SPOTIFY: VOLUMIO VOLUME 33 Oct 25 22:12:11 volumio volumio[6462]: info: Aligning Spotify Volume to Volumio Volume Oct 25 22:12:11 volumio volumio[6462]: info: CoreCommandRouter::volumioGetState Oct 25 22:12:11 volumio volumio[6462]: info: CorePlayQueue::getTrack 0 Oct 25 22:12:11 volumio volumio[6462]: info: Setting Spotify Volume from Volumio: 33 Oct 25 22:12:12 volumio volumio[6462]: SPOTIFY: SETTING SPOTIFY VOLUME 33 Oct 25 22:12:12 volumio volumio[6462]: info: Sending Spotify command with payload to local API: /player/volume Oct 25 22:12:14 volumio go-librespot[7611]: time="2025-10-25T22:12:14+01:00" level=debug msg="obtained new client token: AADo0koFC7VHmDB7ADWnFF+Zh0wrpZmMS2sAbyWvH6geD3VTtCN0rScHIqBy3lA/csVEK9CXaHjhFaHFALy60IKupPolQo1hxXfqhxQb+gpbQSNLJolaQYxk7BiJpJTJEuVF9s+ONyp3muTkzPUO0VKgpuQe3QQFsONHUWD9UElTX3O44/CdUuOJeuOAxKXcmH87scLjDJT5MuNgEByk2tOiOdY68NuWJYeh0UpoEkfo3tcIvMt6R18=" Oct 25 22:12:14 volumio go-librespot[7611]: time="2025-10-25T22:12:14+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 22:12:14 volumio go-librespot[7611]: time="2025-10-25T22:12:14+01:00" level=debug msg="completed keyexchange" Oct 25 22:12:14 volumio go-librespot[7611]: time="2025-10-25T22:12:14+01:00" level=debug msg="completed challenge" Oct 25 22:12:14 volumio go-librespot[7611]: time="2025-10-25T22:12:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 22:12:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 22:12:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 22:12:14 volumio volumio[6462]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Oct 25 22:12:14 volumio volumio[6462]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 22:12:14 volumio volumio[6462]: Error: socket hang up Oct 25 22:12:14 volumio volumio[6462]: at connResetException (node:internal/errors:720:14) Oct 25 22:12:14 volumio volumio[6462]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 25 22:12:14 volumio volumio[6462]: at Socket.emit (node:events:526:35) Oct 25 22:12:14 volumio volumio[6462]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 25 22:12:14 volumio volumio[6462]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 25 22:12:14 volumio volumio[6462]: code: 'ECONNRESET', Oct 25 22:12:14 volumio volumio[6462]: response: undefined Oct 25 22:12:14 volumio volumio[6462]: } Oct 25 22:12:14 volumio volumio[6462]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 22:12:14 volumio sudo[7647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-25 22:11' Oct 25 22:12:14 volumio sudo[7647]: 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"