Oct 27 08:46:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 459. Oct 27 08:46:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:01 volumio go-librespot[12439]: go-librespot daemon starting... Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=debug msg="app state loaded" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" 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 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" 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 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" 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 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=info msg="zeroconf server listening on port 46179" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=debug msg="obtained new client token: AADLmWzs8JXUocbxYD5HibzPIqSnGm1FHATJHUjxN63H+ef7QM79kXGgsxqG2QeVbxE+Za25or0xaOOKJfHyYaPh6CNeUJw9inj1EOin+1lk434RY0RT5+YS7IsCFNgR+gbWKHo9xtspRfCXXfBRwcbpnV6PmpwnI34oaNo55EJBwwoc5FtVgoiaFs3zeMlJ/hh8ULDd3Yv1Oeemh6APTwvE0AM6sm91hf80YpwH7K6hflCIuONW" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=debug msg="completed keyexchange" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=debug msg="completed challenge" Oct 27 08:46:01 volumio go-librespot[12440]: time="2025-10-27T08:46:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:01 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:01 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:01 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 27 08:46:04 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:04 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 460. Oct 27 08:46:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:04 volumio go-librespot[12450]: go-librespot daemon starting... Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=debug msg="app state loaded" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" 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 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" 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 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" 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 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=info msg="zeroconf server listening on port 46605" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=debug msg="obtained new client token: AAB1hUCc3LYFkV9eQVLVyAC0kMpHepbn+6aldTS0QEGydTUJXhyYUws52Drmh+f/uxQcXw4j7Bb7MLnYIl8Y0W+nJsVz1BxF/WhKM+fXGAwPZWCj2dWr0M4ADjqTAes4wkIyF8t/7dPeS9x0pb0YNm6SsTSLm6w1Wf3CjeJrrj9NjyjJ3bu+1tZwi/SEqsnoOsLxEIWmnM5cxtCCFCdEEz5XTBI1lgcHXXoKJ9Y7MALnnRICICd/0Q4=" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=debug msg="completed keyexchange" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=debug msg="completed challenge" Oct 27 08:46:04 volumio go-librespot[12451]: time="2025-10-27T08:46:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:07 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:07 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 461. Oct 27 08:46:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:08 volumio go-librespot[12472]: go-librespot daemon starting... Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=debug msg="app state loaded" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" 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 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" 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 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" 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 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=info msg="zeroconf server listening on port 35343" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=debug msg="obtained new client token: AAAaStZyyu7dMU+eMtgLfVeaceYeUW76ORl5ev3jIda/X7eRdYzmE7tBst8syM7wx9o8lcULs71n0j+I1BIrf7wWFXq3ARBob096dfVsdnZKDone5VciypFYA9UChJl2OCCqRpKJOdjAXQPos/dT5HBay6/IegB4cmYYECeMga2TSiJhjQZkUMaXtLfTV7ZRVwTctpYk/Yxzd4L4qquCjLpJG4a2RMCCIyZDrT30iHcZAmozImBE" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=debug msg="completed keyexchange" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=debug msg="completed challenge" Oct 27 08:46:08 volumio go-librespot[12473]: time="2025-10-27T08:46:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:10 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:10 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 462. Oct 27 08:46:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:11 volumio go-librespot[12480]: go-librespot daemon starting... Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=debug msg="app state loaded" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" 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 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" 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 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" 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 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=info msg="zeroconf server listening on port 36583" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=debug msg="obtained new client token: AACHTr1mLNXmXCm1sTjDuPI7vfK0paPFxKs3KYMABem9oBTprgBkAdL004PBFHUTxTBGAEcfFVUklfcsre/lenMcTdEmyrjzoFQUAVHiWfhmKecO63WK62+yJXH+k8eZVKOLGfNGVCRiDmI5e4BNdpdBzxBHkXPRr0A8QGCf6BnLl13rZsz3LCt4QO5VmcfRAELZDo57Revku3AaeLcvL/QR7o59cMH/7VcaO/XHq7QTZpEj/ma5qgM=" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=debug msg="completed keyexchange" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=debug msg="completed challenge" Oct 27 08:46:11 volumio go-librespot[12481]: time="2025-10-27T08:46:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:13 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:13 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 463. Oct 27 08:46:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:15 volumio go-librespot[12488]: go-librespot daemon starting... Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=debug msg="app state loaded" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" 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 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" 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 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" 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 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=info msg="zeroconf server listening on port 44431" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=debug msg="obtained new client token: AAAG2tXqIarVQ2RSiA3nnlSfR8gdkc26eo9Qw2HytNpxmK0uy9TX+5YX5+dz2LIDjxrMKWvcPR2NiCeSTmSXFhtP7kwZ8CG0oomrqN6CB3AQp2ZLsZX+tfGedKMTBcHLXquAKY02de1MAo38OCm0CRL6sj96VVdXJ6jKse3Ai1gN7s05b3MMhEgMprtGZmGTpc/K32VYMcsbZiy7FQirEPKs5FAmzglBI7gK0AgMuP1ZqP/9dDg2" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=debug msg="completed keyexchange" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=debug msg="completed challenge" Oct 27 08:46:15 volumio go-librespot[12489]: time="2025-10-27T08:46:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:15 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 27 08:46:16 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:16 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 464. Oct 27 08:46:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:18 volumio go-librespot[12511]: go-librespot daemon starting... Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=debug msg="app state loaded" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" 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 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" 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 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" 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 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=info msg="zeroconf server listening on port 45331" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=debug msg="obtained new client token: AAD4eLdw38NL7pcZdPohxTOa79VFuhtYzpc8XSdl+q2qq0YHxZmbN2vYW4nWrIGBcuadBmk1PjKMwi5DDnwzHYF+gcN2j1LZkdcJ71DvboWMjiUaXOav5Gj2iPnyIBd77nIRYyrxDLSvwi8yVHKb0dFfriUxeMKZrk3ImOqdg0l6ZvelwFlnVmdfJu0zBneZM8zSxJty9o/NPhLuxOg1M/WxQ9sAS9YP9Lk1ZXMHu4PTkgqgeZz+4R8=" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=debug msg="completed keyexchange" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=debug msg="completed challenge" Oct 27 08:46:18 volumio go-librespot[12512]: time="2025-10-27T08:46:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:19 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:19 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 465. Oct 27 08:46:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:22 volumio go-librespot[12520]: go-librespot daemon starting... Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="app state loaded" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=info msg="zeroconf server listening on port 36051" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="obtained new client token: AADLjnihF9IvGG2HcLpO6GyjLJZeajA5u53pd61/uC6Um6V7y5jlKL9zB9ZwgyS6k8hESqG5EplK+jVBR9GWPDEZ0IEzSBruohyak9yIi+x+Dj2r1inzxahdGdW9rVvhawC3Z2tJyuyxrM5/2yaH4RHve0ZCEFPUEwP4uiOePe/b6ZtCaRiGYAioAH7nvehHIpINlzubLw+kWVuMFruHwiPa58o1yX9kRlohI6n2hb3o7nHPcWyX" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="completed keyexchange" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=debug msg="completed challenge" Oct 27 08:46:22 volumio go-librespot[12521]: time="2025-10-27T08:46:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:22 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:22 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 466. Oct 27 08:46:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:25 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:25 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:25 volumio go-librespot[12531]: go-librespot daemon starting... Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=debug msg="app state loaded" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" 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 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" 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 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" 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 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=info msg="zeroconf server listening on port 39787" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=debug msg="obtained new client token: AAB8CDBxiE6Xt3A4g2nc0hbxYfYyKkNWgIU/48lE8DsL+29fuqTFQZuCGut/8iiCTn07coqTsld2ZKqGrjOich7/QwbCSuSvjm8HV/RYGwgV80vsaABnRftu6ra2WtBRj40o4u1XBjtvJnoGa0AAkCXhWV94R43nKbjzS/0xTEx0EnQ985DbMXRlajCW/ezBreTHZsTVKgTWHLCCydrCgyd6+Tkt9aWvqdDHFYbPOc/BaLi2cLbNkxM=" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=debug msg="completed keyexchange" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=debug msg="completed challenge" Oct 27 08:46:25 volumio go-librespot[12532]: time="2025-10-27T08:46:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:28 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:28 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:28 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 27 08:46:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 467. Oct 27 08:46:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:29 volumio go-librespot[12553]: go-librespot daemon starting... Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="app state loaded" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=info msg="zeroconf server listening on port 46345" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="obtained new client token: AACoiClw9poIBfJOLyIRNefXXMtTsI89129VSJL6nhrSkoNTzDNSeniC7ZnDOKpKOH6O1MpzqcgkOuOk2wLOH+OoE9ioLzEPWl5FIx+MNTi3LCN7A8u6F7jNsrBAAJ81e2IfIceEBAGLGlw4kQ8reiINCtTEaLgYkA0Vmk1HymMH9MuV+1HyLUhlJnbFPdeO2G0fA5pWH0NtT4WTsIS5E0YkghvlwwL0uK3pvdplgXAx0nnbvKbu" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="completed keyexchange" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=debug msg="completed challenge" Oct 27 08:46:29 volumio go-librespot[12554]: time="2025-10-27T08:46:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:31 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:31 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 468. Oct 27 08:46:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:32 volumio go-librespot[12561]: go-librespot daemon starting... Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=debug msg="app state loaded" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" 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 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" 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 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" 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 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=info msg="zeroconf server listening on port 40527" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=debug msg="obtained new client token: AAAUbJhMadGCX1rExk/iOlJHBimjaRkxRmX1DsMsXZUIF+8uBYEDQCxC3IbV9OpoLAF4O/NR3w7xPYSslyuq318JFwcJt3pAp+TXPJ/N8NQgPs2yELaX4V8aYlgrc1pyRJsWL0YqBo6wnwlWsbVTgOrDpPPptKTwr1rlc8RR4kw7a9GI4s8JqpC1nNwIE/bz7n4vGoiIp1yVlSvLtLrjjxRaaywH65W1Q1/SkgH87/bWRQSvLrLCvmE=" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=debug msg="completed keyexchange" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=debug msg="completed challenge" Oct 27 08:46:32 volumio go-librespot[12562]: time="2025-10-27T08:46:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:34 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:34 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 469. Oct 27 08:46:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:36 volumio go-librespot[12583]: go-librespot daemon starting... Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=debug msg="app state loaded" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" 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 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" 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 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=info msg="zeroconf server listening on port 36961" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=debug msg="obtained new client token: AADXvqnOgXzTuQixK4hJ36I0XXbi1xvZH7peaCWAhkO6OutUsdI41Hl/Ngfo5h4wxn9V+i64+ATTXXeO7evFt00FT17lnAifhfyUqnVAhICfK0wEZEspsaa9DWCGf6likRiIWWH/DOZWtLz0MB4a1EWtLGB6ZIJ7N8TjN+GNC7DKsZyA1VDWwMVbEXeCS1FMInckHctYAnIuo/RaJBjvochJIFgH6uL9e90UNcfTDk19x9662qRs" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=debug msg="completed keyexchange" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=debug msg="completed challenge" Oct 27 08:46:36 volumio go-librespot[12584]: time="2025-10-27T08:46:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:37 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:37 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:39 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 27 08:46:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 470. Oct 27 08:46:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:39 volumio go-librespot[12593]: go-librespot daemon starting... Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=debug msg="app state loaded" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" 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 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" 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 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" 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 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=info msg="zeroconf server listening on port 37687" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=debug msg="obtained new client token: AABy2XSi+gX0v3AZvtmgT4LL/dyh5FfH1c7tqAiro9PMugCifFjP5mc1yBTECHg0PRrswzmRxcNepUynDPTqz5nMZVG+zd0aXQAzLBoGLQ/jpf/AhVdQeepdxBqp/QD7Mjmetwmf9nWa28TqzSM0jIoOdChz6ETei7C5e+IH/vuHU8s1rNsN3t51RmOdeC/jcPtYXc/hpTtQ+NA+H3MDOBUvxnDUh8vWvCXDhmub4TSDecHKWZrsd/Y=" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=debug msg="completed keyexchange" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=debug msg="completed challenge" Oct 27 08:46:39 volumio go-librespot[12594]: time="2025-10-27T08:46:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:40 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:40 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 471. Oct 27 08:46:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:43 volumio go-librespot[12604]: go-librespot daemon starting... Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=debug msg="app state loaded" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" 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 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" 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 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" 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 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=info msg="zeroconf server listening on port 46557" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=debug msg="obtained new client token: AACas9HTPB3RzyPqVsndv1qNqrFC/ieEZXYiu2UthZQJZ4T8JcBMWxOQfU8hGmyqkeQgVJym8wmyURfQ1B+7WBU8Dz+CMnp6BgpdTY3j+x+k8S3VOiznP44l9FNmoOgmunBOeONf2Yq/FKtGF1bFEu1BXNAmjcie+uwNV01p8yrTCva3CMkALGuqhFJMEHnT0Jh9wt+sjoCc/6vML7LRIf+8y44ekbTudDyMX8vaRy4j3cJOitst" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=debug msg="completed keyexchange" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=debug msg="completed challenge" Oct 27 08:46:43 volumio go-librespot[12605]: time="2025-10-27T08:46:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:43 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:43 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 472. Oct 27 08:46:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:46 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:46 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:46 volumio go-librespot[12626]: go-librespot daemon starting... Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=debug msg="app state loaded" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" 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 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" 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 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" 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 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=info msg="zeroconf server listening on port 37555" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=debug msg="obtained new client token: AADQRsRC8dztYpkxIlY4h7UmfEpfCkeUbfagMWn+RI6KqZPL7ZheaitPR9tcfQ3tkYOjHPSGxKmgA8YRZogfUDEflL258HpWXgYrubBl1vDNRjSRxMpOWCdAkCJpCPsp2JnnK1I+Iju+kdcMCiJdy6eNZcAmRVrjTzl2WpAivuAeSwOLZYj003B9Y49NZpJP0VeAKoLvJWLkDML5dJbU2P6VOOk6dXnXnXavnvmx37h3mLOyTSA2l5E=" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=debug msg="completed keyexchange" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=debug msg="completed challenge" Oct 27 08:46:46 volumio go-librespot[12627]: time="2025-10-27T08:46:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:49 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:49 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 473. Oct 27 08:46:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:50 volumio go-librespot[12634]: go-librespot daemon starting... Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=debug msg="app state loaded" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" 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 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" 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 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" 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 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=info msg="zeroconf server listening on port 43001" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=debug msg="obtained new client token: AAA7sza2ICxz5pMoMfydRBl7lwuU6d/naQiBjri7nQwQPiirREz64fmnIKKyTel5YVpeqcIhd8RhzkidXsgySeHi6gQga4jjeugKYv1cJA7bBC/j4xjxhyStDfOnw5UhgcWVEHkHaCRNQSd/tEPF9BKydPttMNVgdvx0VWkCqcXgTStywEGgQ2kHOg6aH8bZBL42hohroeuiidDxH8iEiTnDXiLLNHVCAUKfHyE2W7V4nCv9G6W1" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=debug msg="completed keyexchange" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=debug msg="completed challenge" Oct 27 08:46:50 volumio go-librespot[12635]: time="2025-10-27T08:46:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:52 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:52 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 474. Oct 27 08:46:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:53 volumio go-librespot[12644]: go-librespot daemon starting... Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=debug msg="app state loaded" Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" 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 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" 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 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" 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 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=info msg="zeroconf server listening on port 34509" Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=debug msg="obtained new client token: AACNuktI82WPW6Q7flGJRKo7EAqspcmNDjSEnhD7maj9Hg22nSZUlFC6VgP3AAyVG1I/wklvS5dKyTFB5U0oJsvCsBHSxYiL3UKNrLp3jKUqZXKLFhbTqhY5hfurHxteqU6/ddR/KBqcR38loJ7RMimNbZtq0JmknREH0FFUo+df7kTnrUxG5zjoE54UeYSHQSKl5irR9QdBaXtnrTA2A0dL/wOAEbp93KbHSey05k6kE6lbFBV9ucA=" Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=debug msg="completed keyexchange" Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=debug msg="completed challenge" Oct 27 08:46:53 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 27 08:46:53 volumio go-librespot[12645]: time="2025-10-27T08:46:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:55 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:55 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:46:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 475. Oct 27 08:46:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:46:57 volumio go-librespot[12667]: go-librespot daemon starting... Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=debug msg="app state loaded" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" 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 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" 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 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" 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 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=info msg="zeroconf server listening on port 39047" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=debug msg="obtained new client token: AABNFyoGZHpeLDzJ0hxZcsWjp6wmzZnmNFSlNSx48l6Nr0vwxq7DNl/dPanjRE6fiHxOFd5FAgu547ymWIICrQU/gkp/btNeYZFWVKpPsgpNP4wfMIhbgnuHMaREfcG35/w59Yc6C6t9rRbCDpIG88YdfPpNVrop2yIMypvwvM3gY6Mzqs6DXd8kLlB5RVAFmjZQOwCh1QNDjjPPCfx7kVvaRarZssrl3LU178FdOsgRM81sUywW" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=debug msg="completed keyexchange" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=debug msg="completed challenge" Oct 27 08:46:57 volumio go-librespot[12668]: time="2025-10-27T08:46:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:46:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:46:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:46:58 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:46:58 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 476. Oct 27 08:47:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:00 volumio go-librespot[12675]: go-librespot daemon starting... Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=debug msg="app state loaded" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" 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 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" 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 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=info msg="zeroconf server listening on port 34325" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=debug msg="obtained new client token: AABgAyV8O5kJ/EqmsWb4zooKeQ+yI3m5iKHpLk8EH5oxvkLiCISe49r/WtdAV+oI/QHprcL0S/A62vJKAMg/m++UIuIqIHLWHXRSvzIMICbdEBF3O2Chv2wAZ53xup6Dh2ku6UHnW3LCTp1+QGjXv2L1SLxmcazxwypQL4Qliop89r4sHjPGb3tpqmivLSPASgaX2Ye29sqQSVmG54Hj29FkDKWXrTJs1HtIpjbpLjKrXVfYxQ2B9E8=" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=debug msg="completed keyexchange" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=debug msg="completed challenge" Oct 27 08:47:00 volumio go-librespot[12676]: time="2025-10-27T08:47:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:01 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:01 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 477. Oct 27 08:47:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:04 volumio go-librespot[12687]: go-librespot daemon starting... Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=debug msg="app state loaded" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" 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 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" 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 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" 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 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=info msg="zeroconf server listening on port 33355" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=debug msg="obtained new client token: AACjShu8rudWXQh30XZkah5uCr3Fvx+Hn/Rl9PweX6f3PsmLgB/9CNS8BMUgDKP6YLkqqTU246fRY0KWB5tx/ewa8OHot8kSM/DJOKQU3eYSBsVHjcTNaenU79nAcOCinwprDkpIIWmM0Z+lbCf8/J1IMV6B0yZOLs9Qw8DCLRc5w9259/cOksP9cEGq79arCBgEMThukhF8/ljduVua8qkckE0yrVQf/K0qobfnCn0uD2rD/FcL" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=debug msg="completed keyexchange" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=debug msg="completed challenge" Oct 27 08:47:04 volumio go-librespot[12688]: time="2025-10-27T08:47:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:04 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 27 08:47:04 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:04 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 478. Oct 27 08:47:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:07 volumio go-librespot[12712]: go-librespot daemon starting... Oct 27 08:47:07 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:07 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=debug msg="app state loaded" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" 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 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" 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 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=info msg="zeroconf server listening on port 45251" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=debug msg="obtained new client token: AACYRN5gG40157CZP8vE6LSap5TgByliqcHqP21C3v0mTtsIAIPnVhCLhYQsXPARLMrfKc+Cj/uW8N0foqc7mURsQeFbv/fNfP9bEqM5vtjmdJtQofsOKmrEp0kQNc7wFdnBPPtIJwK0L9+gg6C2/1vxxsurAQhV2G330heig3C+E53N+96Rv12FlzFsCmk44TB3HRtRd10pahHCpmCIO6ASX2F/709rFxNDqFKNSvZ04zYGswQaQWc=" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=debug msg="completed keyexchange" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=debug msg="completed challenge" Oct 27 08:47:07 volumio go-librespot[12713]: time="2025-10-27T08:47:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:10 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:10 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 479. Oct 27 08:47:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:11 volumio go-librespot[12721]: go-librespot daemon starting... Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=debug msg="app state loaded" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" 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 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" 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 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" 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 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=info msg="zeroconf server listening on port 35427" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=debug msg="obtained new client token: AAAte4AoftMm3HmqZKtZse9CuJESi3/ktfu6RreL+2Mh9lTOdkNxwg6Wxj0bbXDCg71L7O6sutQYzgls0ScBi8o1+J28Ofx+0XvytilHjuuBEHfheKN9Yr809giMoKtGMHVnwe6YKURYz1sx0qKyvL2uXNuAV1RZHyHzWV3aKZHCCkQx0irVnUhdA+i4Bnk+GECaI/z0yzWorg8SWixOKM/vbOg34JjLyXc+2f9SLuH7DbVK6StR" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=debug msg="completed keyexchange" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=debug msg="completed challenge" Oct 27 08:47:11 volumio go-librespot[12722]: time="2025-10-27T08:47:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:13 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:13 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 480. Oct 27 08:47:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:14 volumio go-librespot[12729]: go-librespot daemon starting... Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="app state loaded" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=info msg="zeroconf server listening on port 38683" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="obtained new client token: AACI+9kY9S5qJNrKk91HCZS+hAkpRTlm5YQkdUUJx9YqLBvT1Ksmq48btjVWhnJFvLSpf00ge5rvLdDn0NZqd2eYIfUqSuUiV0GaobX/dNYQYQ45u/nyitRMaWAfJqpY/fiAfP3JFA1kO4m4ZZRNqXPqtEzfBZt2oETQthnh69EIBoYt3rNkctJCYid4dJfZUw8MyG1FKy/Wp5s4PL5UfyW1FZTkwp8BfCUFR8sC36mEN3LnZSDbjv0=" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="completed keyexchange" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=debug msg="completed challenge" Oct 27 08:47:14 volumio go-librespot[12730]: time="2025-10-27T08:47:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:16 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:16 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 481. Oct 27 08:47:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:18 volumio go-librespot[12753]: go-librespot daemon starting... Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=debug msg="app state loaded" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" 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 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" 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 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" 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 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=info msg="zeroconf server listening on port 40675" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=debug msg="obtained new client token: AAC6pmnIAcMXc9wKfcCabNW4huwonZT8R778w3P1gRRWOA5py+RCoKm2a90Cotcxvdtc0JdLxt/smj3UF6OYh6GPcLfGBlw5TQK+Rm5koaBc9nDuxZSG5dNK0nDlChnxMBC3NHgIr2x03mdZWdf6Mnxo8FS59R2pw/RCgl7q5c7Gxo7jYsNqzlSW5wWVLpDLzo0fS/y44gyN/bT//5SC9rpCHdd3TDVul+fMiKBRhfwJ/WHjxqG7" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=debug msg="completed keyexchange" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=debug msg="completed challenge" Oct 27 08:47:18 volumio go-librespot[12754]: time="2025-10-27T08:47:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:19 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:19 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 482. Oct 27 08:47:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:21 volumio go-librespot[12761]: go-librespot daemon starting... Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="app state loaded" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=info msg="zeroconf server listening on port 38805" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="obtained new client token: AAAiuTCFvTJ2oWg2n7v52sAw0mUAuFOR2fEeDjbaoktpZhMARglk5yWIP+0qfUY0Ix6kabGFn6ySS3vjulIo4lIhdvOkuv9UMeobWDgJ+DDnhub7CffWQjh0CNqcaT+m0VNowAciSfhzfWJxYbst0A0CWGpAe39z/+sFMP7iZ3CvIbqFMSYOr1N/fQiBl2emLwJ8GsRd4i1DPrWwP8to4GF9zLF3xUJt4Fsifdsd/VveHMzMJPDfeZQ=" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="completed keyexchange" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=debug msg="completed challenge" Oct 27 08:47:21 volumio go-librespot[12762]: time="2025-10-27T08:47:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:22 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:22 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 483. Oct 27 08:47:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:25 volumio go-librespot[12773]: go-librespot daemon starting... Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=debug msg="app state loaded" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" 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 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" 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 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" 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 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=info msg="zeroconf server listening on port 39951" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=debug msg="obtained new client token: AAA+e04EOueup5y/Zl7FXS/aqoUVGpK/9vUHGLcaHSmd5E+4e9ujFAaD1ynmJxvuuV8iP7LkTeXaDYdR2ode7lP2wsN52ei0KU+4nJ5dhxnDOlGGUrCGZuDPYDE4HTfXjL3k+nZqcrsvknBmdHjpkLX6AfYd5+QqnwWy4fjsCcjmBHjXzTi3o/5VFCJ/J6X0ZxxAMMNXRC61ghRzpSebf+x/LrfU3PHHK4LSM3Ob0GcRRKM9ptUQ" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=debug msg="completed keyexchange" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=debug msg="completed challenge" Oct 27 08:47:25 volumio go-librespot[12774]: time="2025-10-27T08:47:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:25 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:25 volumio volumio[11951]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 484. Oct 27 08:47:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 08:47:28 volumio go-librespot[12795]: go-librespot daemon starting... Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=info msg="running go-librespot 0.4.0" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=debug msg="app state loaded" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 08:47:28 volumio volumio[11951]: info: Initializing connection to go-librespot Websocket Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=debug msg="new websocket client" Oct 27 08:47:28 volumio volumio[11951]: info: Connection to go-librespot Websocket established Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" 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 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" 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 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=info msg="zeroconf server listening on port 39007" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=debug msg="obtained new client token: AAAIq/qQ/XrC8SM2T8E38Kbv+1GERroBD8DDfFygNRQtRL/V9QE6kRPCBdjcuviA9V0kRIHTQAR1PVhib5Z4q4b0Wji10NwFnXu11CM9e/tdIGEOLIwlfkN21tK2h8iAzEON8qLSc4W5ilnSKw/FeJcmeWnq/GIy3gkiBZcsa5u83d45+Jfzu0+WAmtogfqDqbXUlFUHKu/CTiF8s2LgeDjYn68NmQUcsUsIlSHd+hO60hySKRyoyts=" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=debug msg="completed keyexchange" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=debug msg="completed challenge" Oct 27 08:47:28 volumio go-librespot[12796]: time="2025-10-27T08:47:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 08:47:28 volumio volumio[11951]: info: Connection to go-librespot Websocket closed Oct 27 08:47:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 08:47:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 08:47:31 volumio volumio[11951]: info: Getting Spotify volume Oct 27 08:47:31 volumio volumio[11951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 08:47:31 volumio volumio[11951]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 08:47:31 volumio volumio[11951]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 27 08:47:31 volumio volumio[11951]: errno: -111, Oct 27 08:47:31 volumio volumio[11951]: code: 'ECONNREFUSED', Oct 27 08:47:31 volumio volumio[11951]: syscall: 'connect', Oct 27 08:47:31 volumio volumio[11951]: address: '127.0.0.1', Oct 27 08:47:31 volumio volumio[11951]: port: 9879, Oct 27 08:47:31 volumio volumio[11951]: response: undefined Oct 27 08:47:31 volumio volumio[11951]: } Oct 27 08:47:31 volumio volumio[11951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 08:47:31 volumio sudo[12817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-27 08:46' Oct 27 08:47:31 volumio sudo[12817]: 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"