Oct 29 04:56:00 volumio volumio[26398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 29 04:56:00 volumio volumio[26398]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 04:56:00 volumio volumio[26398]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 29 04:56:00 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:00 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 201. Oct 29 04:56:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:01 volumio go-librespot[26823]: go-librespot daemon starting... Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=debug msg="app state loaded" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56: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 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56: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 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56: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 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=info msg="zeroconf server listening on port 43503" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=debug msg="obtained new client token: AAA06SV0p+c5mP33PaV/IM0xMxuZB01y7SnwZQdggtGRQ6vRnBXfFlEuQa1xt+bJnOBTDox91x3cmXpyhcjxmT7rNq1I4HVsKlCu0QghxAGkTh/Ds9tXU650I/XrFPFuIewvhCOj38KX0yZYy39zThPmzESUC2hMPmBmGOuZ4tmXck8SjCFXuiq92nf1UwTWFv5Xfbk3HOQx+viNAUth/kYbsOHvqGGykWG4XR0adU0VXVJvUu7P" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=debug msg="completed keyexchange" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56:01Z" level=debug msg="completed challenge" Oct 29 04:56:01 volumio go-librespot[26824]: time="2025-10-29T04:56: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 29 04:56:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:03 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:03 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 202. Oct 29 04:56:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:04 volumio go-librespot[26832]: go-librespot daemon starting... Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=debug msg="app state loaded" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=info msg="zeroconf server listening on port 35953" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=debug msg="obtained new client token: AAAQGZu7JU2F2Gg7YgQGGsqRspQdFsY+3tKlFdb0ub4GtyIaeSSs/QxnQV7wR3rodf5u9k3GxdvMYW8TSceVsDY+IMav8bF9q/OXlcJWWvFFPeajP0h3xzDB4DGorVoLDgSFEKEDseu5kvM4CWRNFc/ktpCsj6/5w1KmaMMHak/s+k62aoJtgWZn5/j8epcNbc64bTJcDH/HPwskkvpDXrunhGqrBOqn/gWcTqYlEzq5goyJNLjlOQU=" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=debug msg="completed keyexchange" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56:04Z" level=debug msg="completed challenge" Oct 29 04:56:04 volumio go-librespot[26833]: time="2025-10-29T04:56: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 29 04:56:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:06 volumio volumio[26398]: info: BOOT COMPLETED Oct 29 04:56:06 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:06 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:06 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 04:56:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 203. Oct 29 04:56:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:08 volumio go-librespot[26855]: go-librespot daemon starting... Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="app state loaded" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=info msg="zeroconf server listening on port 34075" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="obtained new client token: AABZX0zBQdLWaNWbs1fsgPZeQcd0LNyyPJdBncivlKLsrIpFp18lHMaYWYbQUmSTvWDR1yCSGtF/OVImj6MQpZEfjKccjDPQAyca60QGRmxOT/qPvv97Dds9zdpUMNInbNXlFYhSgb9rEacDDkOEhYu0attTpl8/lWv+YtwtOxSu7VPlqFzIaQq7T+BEiC6b9CZDfj0zZSaIOE9zUdPtjIbMi8YS9FGlcdf4Qw2kMUVz9p5j2Egk" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="completed keyexchange" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56:08Z" level=debug msg="completed challenge" Oct 29 04:56:08 volumio go-librespot[26856]: time="2025-10-29T04:56: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 29 04:56:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:09 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:09 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 204. Oct 29 04:56:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:11 volumio go-librespot[26864]: go-librespot daemon starting... Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=debug msg="app state loaded" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56: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-gae2.spotify.com:80]" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=info msg="zeroconf server listening on port 42699" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=debug msg="obtained new client token: AACwvbmyXil5PYLaZyN3iX8hLiXe+Jh7FxbTceHEawqJa4Z40s81Dz1h1Q/nKWlnf9r1xYZRA/Po9cNYAgRn1uDh2AR3jPxSDL4UT9OZmlvGMmlcW5kM8Z+dpzWmWRHXPHpFfRsoDg4gA43g9+hGLSoM1ujO6TdcmVgxppOcLmgrV3icRmfkYlZDzDjudfCk+4Cv7qYeU1MD30CZu97Uq5wZTUwBItW0i2odry/SymziGEEmAA+WRb0=" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=debug msg="completed keyexchange" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56:11Z" level=debug msg="completed challenge" Oct 29 04:56:11 volumio go-librespot[26865]: time="2025-10-29T04:56: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 29 04:56:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:12 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:12 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 205. Oct 29 04:56:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:15 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:15 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:15 volumio go-librespot[26875]: go-librespot daemon starting... Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="app state loaded" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=info msg="zeroconf server listening on port 44371" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="obtained new client token: AAB/BW/isunoC5OKsZS4mG2FMcueqz6FrI0SWFzMNuwVtGjX5ogS+dBLf6fFbNdFcUZ1OTUk2OEJERxz8VZd86q27LMCIrJO+XnP+2CCwnmyahCIgY5qrRlTpeao5n0nnCjQlZub5KpEvebcQoEq2/YEoUvGu65P5SGg7Usve2frCAbHUy4E1xxZsorbSmnhXR8wSqKNpIAHKs7cXl6J3L8Rj8rw+KLhFL8uscEBkS7qLxfXKstn" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="completed keyexchange" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56:15Z" level=debug msg="completed challenge" Oct 29 04:56:15 volumio go-librespot[26876]: time="2025-10-29T04:56: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 29 04:56:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:16 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 04:56:18 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:18 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 206. Oct 29 04:56:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:18 volumio go-librespot[26897]: go-librespot daemon starting... Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=debug msg="app state loaded" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=info msg="zeroconf server listening on port 37533" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=debug msg="obtained new client token: AAAABw6IIXwROuTrgt/CBBfVYJig7Jr6Q5a6TI/OGCGuSgk1mg6FmgQv6wXlF0eHGRPppKd0SKbKaQYp41I/E2uqNICeXjPAoVAtjCNJSvZK1V5kpoRVReRYZ7pyUABSy5Ilq9z8c6OurcJYB+X9MEczopqQ5Ms4MJAZZhuiGjMnb+a3dT/twu5Mfr3W5wpPmetRL0xdmKg1s7gpxM9gTJ95FWjmJxGAj+9IiwSbvhLdfzyVwOcKp4Y=" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=debug msg="completed keyexchange" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56:18Z" level=debug msg="completed challenge" Oct 29 04:56:18 volumio go-librespot[26898]: time="2025-10-29T04:56: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 29 04:56:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:21 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:21 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 207. Oct 29 04:56:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:22 volumio go-librespot[26906]: go-librespot daemon starting... Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="app state loaded" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=info msg="zeroconf server listening on port 37179" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="obtained new client token: AAD2elcXCi9ER+KP//SPsKWREkwgwmdA2SdzG/Xm2w7P9p7IcoBWT9klJFvDBkasElZ/7p8DRAIeoT9Olg9hkv5d6K9V+3TtaqV3+77sWAIDt0DEsI6Aplw1btqsI69txzA7LGHi4ZyUSRNzi9wNIgJeKtGe+SG0uQla+bJWEpKNnorGIqyxsd6LYnlQYYeRxaJz9JmvlaSL32f4vJ1AFxfHu68CWQTqHSh7kMK5LdfoJtc7js9+" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="completed keyexchange" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56:22Z" level=debug msg="completed challenge" Oct 29 04:56:22 volumio go-librespot[26907]: time="2025-10-29T04:56: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 29 04:56:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:24 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:24 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 208. Oct 29 04:56:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:25 volumio go-librespot[26914]: go-librespot daemon starting... Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=debug msg="app state loaded" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=info msg="zeroconf server listening on port 38011" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=debug msg="obtained new client token: AACnY540IqGMo2CO/8AnhGF0QfO/8gZcrAY3iktJNGC07jxrtCTW6c26n+4JxyvGHLVU+Zm0Mepws7MRL5vSIGGh9HrcbtOBglmq1lFBXgo7pXy8jhaPfmq39+7gpzwxZ5zVbllFJFpyR3QntXjwsSgx33txKnpijIzWAk24rGJlZ0MQ2vY8yLSqb3XPvpzLsr5psWCKKQGVHvZvRQnPV30/yJJAjkK+9qYP6nrrZL9elTPyeDPWU4s=" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=debug msg="completed keyexchange" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56:25Z" level=debug msg="completed challenge" Oct 29 04:56:25 volumio go-librespot[26915]: time="2025-10-29T04:56: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 29 04:56:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:27 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:27 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 209. Oct 29 04:56:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:29 volumio go-librespot[26936]: go-librespot daemon starting... Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="app state loaded" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=info msg="zeroconf server listening on port 33999" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="obtained new client token: AABikB1WXSQW5Xgu9oOF9BEQx5zr+5P1VzsBtnjyRfNSK+SWdFIHe1FQXHVgZ2wM0GU/RCYCKy/3IL3qrYnYWcSkgAPzDswdBxt3pnyKGGLC6svwc5M9p9otgYF/Ro77TsQmsdVgKW1NtxMTlXqTkRd5MwOY+Ztm+UfM3nBQTeMneuYSMqGfT7ZqRbauCfw2RO+YTmNxeCeS/YPG58zgBsfViRN89+0RlhJSzupn4uLmhKCvVo3T" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="completed keyexchange" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56:29Z" level=debug msg="completed challenge" Oct 29 04:56:29 volumio go-librespot[26937]: time="2025-10-29T04:56: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 29 04:56:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:30 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:30 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:32 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 04:56:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 210. Oct 29 04:56:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:32 volumio go-librespot[26945]: go-librespot daemon starting... Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=debug msg="app state loaded" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56: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-gae2.spotify.com:80]" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=info msg="zeroconf server listening on port 34147" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=debug msg="obtained new client token: AADVCFMAwQ3gyr0tCrm0x26ewzd3E9miVcC3SlkfJJg2lYfUeEPFjO3VxoCRKNEfpslwRxHroKqkrX9jgn6EU4hc6W6pmjsgbWGHISfAlOeoUWK4l5LCQCayAHuzds/J1rlC7cGBYiS/1979ugQpKz2nmDXGFGTI67ztD1xwpUn8qVpHOK7Vz7ZQNbN5d1CcY7gF9dMYIUErJ4fhbNT9PxoKJcrOzfhofBCuN5PqXnQy60pahCPBgcw=" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=debug msg="completed keyexchange" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56:32Z" level=debug msg="completed challenge" Oct 29 04:56:32 volumio go-librespot[26946]: time="2025-10-29T04:56: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 29 04:56:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:33 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:33 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 211. Oct 29 04:56:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:36 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:36 volumio go-librespot[26957]: go-librespot daemon starting... Oct 29 04:56:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:36 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="app state loaded" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=info msg="zeroconf server listening on port 44099" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="obtained new client token: AAAcgCXsVWhtg0HXsrBjpD2rVtk88OMJp9Bavv9lij79wzo99Xw22OgKn0Nwfgh4eDNUFy2M2TzQOROZoYeiD5QV64RwYJBbDixrLFFIGGiwWz3qndS7WiC1NOBo7o8SG4LC9kcVHgqIY4HUlIFDQFI86MOTBVx8VLUtkC0oU9aYE0gnywshRi3jv6PmdClmxNfX9Wrezky62vHAtCBR0dE2QeWgECro6LjyBPFKoYKhU4fNREBf" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="completed keyexchange" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56:36Z" level=debug msg="completed challenge" Oct 29 04:56:36 volumio go-librespot[26958]: time="2025-10-29T04:56: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 29 04:56:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:39 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:39 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 212. Oct 29 04:56:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:39 volumio go-librespot[26980]: go-librespot daemon starting... Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="app state loaded" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=info msg="zeroconf server listening on port 40737" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="obtained new client token: AADFODU9EakJbZ4JPjSmsJfHLG39IlOM78Jd0xptIrAZdnp0gHLcQdFomTYrUYbctKOc9UIdpElIopP9VxVKdgBLi4FoE192j521V5U3UPk8YCaXvZaVo59dfd5lte17Md/nx+tP51SJllvxpPAJMAd69/E4ymTMsrorZxSMdfWKlyqVkr+9xI9A5dfVVnP22cOyvWSDaZFnzRc4ZJifWnw/M7Rb08QzQQbuF8TVSnWKRf3W5Zfext0=" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="completed keyexchange" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56:39Z" level=debug msg="completed challenge" Oct 29 04:56:39 volumio go-librespot[26981]: time="2025-10-29T04:56: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 29 04:56:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:42 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:42 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 213. Oct 29 04:56:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:43 volumio go-librespot[26989]: go-librespot daemon starting... Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="app state loaded" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=info msg="zeroconf server listening on port 46739" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="obtained new client token: AADfL6CaSNkmZ325/KIvvkEkYTKNuXgIttWv1DgVrNLApYJF2kD2ZB0/hos4uyWfKHEwFCB+Xu51MBpekPM9dX7naD+hdr2Zf9bDHVtbwNfZb55iD8Np8SXb9O454K9UfHlhGASjh+9nPGiu4zNoQ8dhPUtj21tisn24GxbaRJmY5GORj7GTFsbWuNA4Xpb/bfmQz/cwY7ju5dUNu8rPBrIlQ8neIvXgIYHV3q7PIh+KlYBAaXwL" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="completed keyexchange" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56:43Z" level=debug msg="completed challenge" Oct 29 04:56:43 volumio go-librespot[26990]: time="2025-10-29T04:56: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 29 04:56:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:45 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:45 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:45 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 04:56:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 214. Oct 29 04:56:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:46 volumio go-librespot[26997]: go-librespot daemon starting... Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=debug msg="app state loaded" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56: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 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56: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 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56: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 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=info msg="zeroconf server listening on port 38547" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=debug msg="obtained new client token: AACdk/UMYkH7tG1Ah3pksCT5p4DeRS5qV71BE5t4n8R7UiVcntxL84LSpjJjauGHOnSXkoqUlUbERE/JL3mCrHLxy8Hr5dyObf8zowrUi7j13T9a/TFUQQ+lzaieX/xnZRnNSVySit34XZ2N89319wGkZme5Gdq7b4FJWh6eST12GCAY/yLtxgPOtMqEvFymGO6Nz0a4k8wTNMAgdC99V+9TTByuUcJIe55qBt+325JoTTarWOqV34E=" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=debug msg="completed keyexchange" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56:46Z" level=debug msg="completed challenge" Oct 29 04:56:46 volumio go-librespot[26998]: time="2025-10-29T04:56: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 29 04:56:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:48 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:48 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 215. Oct 29 04:56:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:50 volumio go-librespot[27019]: go-librespot daemon starting... Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=debug msg="app state loaded" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56: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 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56: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 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56: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 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=info msg="zeroconf server listening on port 34479" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=debug msg="obtained new client token: AADFf07w+GAqOArwXyt9bvSwk3W7mQR2kC26yxTYXEtXVrQe+ZnyR1Pgmc3tGWiVG+piH1BzWSoHuC2PfwrY+8O8SO0sL7yM8P9QFecYNE0KlS7AFp1nX8SzXdIqodMNYZsZ9cTxD+MFl9bkQuNilgJtiSYMM1k8njj1kXfDTVlbnnH1y1M5fuiYH2jExIapwj35/4ZDZAP7NKpOD6X2/3qrK+q8/EuExIFzyAYBbotOJa36d/1Z" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=debug msg="completed keyexchange" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56:50Z" level=debug msg="completed challenge" Oct 29 04:56:50 volumio go-librespot[27020]: time="2025-10-29T04:56: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 29 04:56:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:51 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:51 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 216. Oct 29 04:56:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:53 volumio go-librespot[27031]: go-librespot daemon starting... Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="app state loaded" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=info msg="zeroconf server listening on port 38169" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="obtained new client token: AAB1ltd/57/CJuTqU4gL13JU1VjVupRcS2xptAlqt7dvePp5IUn5YKXkSO8KXRlWxsi/+U7qIBIW1jMx9AacTtqfqystk4hpEnFB40gWeUNXyivRQcwk/U2LwYu58BR5d/hB01Bud3Zp7+c7oehXC5wPC4hxuGmw690fx1tIocGGGrff+4mSl22S8f3QvWYU9Fu92BzY6tDnAw2htR7SPx+nOJCkXMjzVqFCRhhEn2fu2fjpnn255yw=" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="completed keyexchange" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56:53Z" level=debug msg="completed challenge" Oct 29 04:56:53 volumio go-librespot[27032]: time="2025-10-29T04:56: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 29 04:56:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:56:54 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:54 volumio volumio[26398]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:56:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 217. Oct 29 04:56:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 04:56:57 volumio go-librespot[27039]: go-librespot daemon starting... Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=info msg="running go-librespot 0.4.0" Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=debug msg="app state loaded" Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 04:56:57 volumio volumio[26398]: info: Initializing connection to go-librespot Websocket Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=debug msg="new websocket client" Oct 29 04:56:57 volumio volumio[26398]: info: Connection to go-librespot Websocket established Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56: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 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56: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 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56: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 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=info msg="zeroconf server listening on port 34799" Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=debug msg="obtained new client token: AABnSLv7Ga7QwRg2eDlwx90oUKvfwBj3qyjSLhzkmQkxc409yOJzI/V3U+PnFji3uIhQ6X31ke2UZRo2DTVnqDPEd8vSpyVFihHHpd3NqtJp3lPTyBPt7ygx9kD0wPUfEqFa3Z9orx7G++7gZXsNMKveB+cyRsL5srOWgN62wg2/OQPG0g5/3HwgLlqsuq/se8n6JXiZYyDjXtPG7ExuzdxrKMZnUZInhUcgcY8qSNflbN27Ftpm" Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=debug msg="completed keyexchange" Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56:57Z" level=debug msg="completed challenge" Oct 29 04:56:57 volumio go-librespot[27040]: time="2025-10-29T04:56: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 29 04:56:57 volumio volumio[26398]: info: Connection to go-librespot Websocket closed Oct 29 04:56:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:56:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 04:57:00 volumio volumio[26398]: info: Getting Spotify volume Oct 29 04:57:00 volumio volumio[26398]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 04:57:00 volumio volumio[26398]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 04:57:00 volumio volumio[26398]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 29 04:57:00 volumio volumio[26398]: errno: -111, Oct 29 04:57:00 volumio volumio[26398]: code: 'ECONNREFUSED', Oct 29 04:57:00 volumio volumio[26398]: syscall: 'connect', Oct 29 04:57:00 volumio volumio[26398]: address: '127.0.0.1', Oct 29 04:57:00 volumio volumio[26398]: port: 9879, Oct 29 04:57:00 volumio volumio[26398]: response: undefined Oct 29 04:57:00 volumio volumio[26398]: } Oct 29 04:57:00 volumio volumio[26398]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 04:57:00 volumio sudo[27075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-29 04:56' Oct 29 04:57:00 volumio sudo[27075]: 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"