Nov 01 04:47:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 501. Nov 01 04:47:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:01 volumio go-librespot[11719]: go-librespot daemon starting... Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="app state loaded" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=info msg="zeroconf server listening on port 35063" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="obtained new client token: AADJezA31bvIu0puYa+hwr14RyfLfaFU10yp1GWbP6m6MlN1jgUhGwTqnLtW1kqUZgUaVcNk5ZYrQisEPniH/8usQmeTJ8RxHLkJtVZQeSEYqghpLeko9qC8b+T23VRLJLywJj+Yzwa45xD9//Mwx/L9xP9gbBVCCNnLUSg4V5r4JCA503rBx0SBrmJc0B0B2iJdytM4/hvIU79362phpzFJz8wB70QglljkkBBfIlVZAullAhWzDv8=" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="completed keyexchange" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=debug msg="completed challenge" Nov 01 04:47:01 volumio go-librespot[11720]: time="2025-11-01T04:47:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:01 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:47:02 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:02 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 502. Nov 01 04:47:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:04 volumio go-librespot[11741]: go-librespot daemon starting... Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=debug msg="app state loaded" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47: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]" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47: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]" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=info msg="zeroconf server listening on port 42071" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=debug msg="obtained new client token: AACJYv1DDlYXD7rPAJpZYYVOYqWcvUHM8koU90K2/ujVUEmW/490bGAq7Z0GIIriY84ZAgsDDvTZwRz8Z+2L2zVGu4U8XBk78J4d/7Wp/vI1sJk5a9PNakjkE0RVCrBMYK9i4bT8WmAr3bL+xc/HlSwF10SGVdBqzgbs7vh4WIxxXjxrS5tL9uvae9eQrO8JP4Idc1GyhBjxQS5DlBmbmEAiR+xLp6H9m3YEWY1hD94cAki5SmKaYQ4=" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=debug msg="completed keyexchange" Nov 01 04:47:04 volumio go-librespot[11742]: time="2025-11-01T04:47:04Z" level=debug msg="completed challenge" Nov 01 04:47:05 volumio go-librespot[11742]: time="2025-11-01T04:47:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:05 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:05 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 503. Nov 01 04:47:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:08 volumio go-librespot[11752]: go-librespot daemon starting... Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=debug msg="app state loaded" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47: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]" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47: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]" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47: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]" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=info msg="zeroconf server listening on port 43317" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=debug msg="obtained new client token: AACtBozu0Ff4Zyle+zLTbBmEX8Eq1+BcDB0deSdBmI1N9pS+eSx5LdE5Z0qzZAzYZKoxX9tJc8qK893y8wDy+816rr1jfM0cUBwwC2Hwh1br57uTBRu9osObxpbawFdH1DgJFBkjl+Ij2go8D9rvEQqUCq1giqvNC5+6wNbDkUvpXG+lV5S/SZ0UJNXoOMHIflaTBNwtvS59pUPtTcNUkoQUQvE940vwCj51bWvXQgfwgvdzS2pM+yo=" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=debug msg="completed keyexchange" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=debug msg="completed challenge" Nov 01 04:47:08 volumio go-librespot[11753]: time="2025-11-01T04:47:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:08 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:08 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:11 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:11 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 504. Nov 01 04:47:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:11 volumio go-librespot[11760]: go-librespot daemon starting... Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=debug msg="app state loaded" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47: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]" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47: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]" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47: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]" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=info msg="zeroconf server listening on port 33885" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=debug msg="obtained new client token: AABbjnAfoC+M3ok7QvrMwLfrwihW6UnM30fhfM88KUOaVmcuesF1sBl7+H4lzMVwZn0FuQLMhOl3jsSd6uCOoA2NYkXwf9xc/Gwi2rGTSPQ5yPyzbk8coDtXnUIX+Pksk2GLHvPcJ2sjdS4anw+HgI92Mqm8WVLmUpgVuqVUubkSTg33ggXqwnYkW4AyowDSEenSrqMyKia7V9jOLbJplo1HskVexsEbWTRcLZAQL+n0+GPW/7/DyoI=" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=debug msg="completed keyexchange" Nov 01 04:47:11 volumio go-librespot[11761]: time="2025-11-01T04:47:11Z" level=debug msg="completed challenge" Nov 01 04:47:12 volumio go-librespot[11761]: time="2025-11-01T04:47:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:12 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:47:14 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:14 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 505. Nov 01 04:47:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:15 volumio go-librespot[11782]: go-librespot daemon starting... Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=debug msg="app state loaded" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47: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]" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47: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]" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47: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]" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=info msg="zeroconf server listening on port 40261" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=debug msg="obtained new client token: AAAT/mBGhlJno5vpqi9nwvrgzsOy6E3NGxeyQ/DYjuyncmAwAHBPub7Ic+TUAt20USnFkRF44HaXL7tAjqalp6jFZBWhi0CXwi7LnCLt71WUdFTxjROMIM5ZO6SIBE1kJpiF2XxTM5EPzpBC7kgKCYLX7UHcL/YOTTntFHSMpY4bO+OFnBOibOQwE+oO9ldvQUsndeNSRlywrb16Pjh3F01q1TR3mfGF5LIIKVzR7K1CMDVRGuUjqCA=" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=debug msg="completed keyexchange" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=debug msg="completed challenge" Nov 01 04:47:15 volumio go-librespot[11783]: time="2025-11-01T04:47:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:17 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:17 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 506. Nov 01 04:47:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:18 volumio go-librespot[11791]: go-librespot daemon starting... Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47:18Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47:18Z" level=debug msg="app state loaded" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47: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]" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47: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]" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47: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]" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47:18Z" level=info msg="zeroconf server listening on port 37057" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47:18Z" level=debug msg="obtained new client token: AAA0tfFMairTq2I6G9deQfdD4xubNjRfCgVrTXr1/B/47Mrs+No7PKxuqcZEQvWh0D2nyiZUkGJXrDzMxFtMOxfZk6CdogI2gNFidzz+hzVv9OrRByvXNMqwPD4TnRNcqiNaT+ySDLUbX1Qz0JewxWviCJrsZH18m2bRvF38Y57ENFsc9747m2aSXm6HetFj9ZKVVoi5z14cXQS9QA/QhUkF7ctXsnQhy/frbNlm6T7WRxVgP1OphHs=" Nov 01 04:47:18 volumio go-librespot[11792]: time="2025-11-01T04:47:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:19 volumio go-librespot[11792]: time="2025-11-01T04:47:19Z" level=debug msg="completed keyexchange" Nov 01 04:47:19 volumio go-librespot[11792]: time="2025-11-01T04:47:19Z" level=debug msg="completed challenge" Nov 01 04:47:19 volumio go-librespot[11792]: time="2025-11-01T04:47:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:20 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:20 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 507. Nov 01 04:47:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:22 volumio go-librespot[11813]: go-librespot daemon starting... Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=debug msg="app state loaded" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47: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-gew4.spotify.com:80]" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=info msg="zeroconf server listening on port 36735" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=debug msg="obtained new client token: AADmjdN3Q4M0ccyjg/RhYInXt28Km70iolvz8o4e1X5vGwi/C60JRD1esVgXCYGOiL3gYa6qXnq4ZzMzlyyk461xY246I2wbawN2A3Z9LpAlkEIz/BlO+5bM8zx7A7HjAhC4OZ+lgiZVJ+lrt3ACpy2Gs0A5QQNlEPA3T6UFdsWuC+3e4tf668WCluIOE/1OImVn+NHtNxgPvn3l4C17M7AZDUdo6ffLf1rHxY4RWS4yUZyUL3MtJ2g=" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=debug msg="completed keyexchange" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=debug msg="completed challenge" Nov 01 04:47:22 volumio go-librespot[11814]: time="2025-11-01T04:47:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:23 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:23 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 508. Nov 01 04:47:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:25 volumio go-librespot[11821]: go-librespot daemon starting... Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=debug msg="app state loaded" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04: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]" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04: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]" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04: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]" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=info msg="zeroconf server listening on port 46371" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=debug msg="obtained new client token: AADk2EYrqzWVTcnUm9VpzlJMRbKOrt3NFZMewl6UGUpJ0inQa1nOjaNVU1Zg5T1aTF6TxjJmYsh6QRBQFOTuXYOnJY/7eWkv59IGcVvxIVl7KXWTWqrMQB3LFyUDWcG/eN4j1zpfwEWnRVP3uuVUL/bnnF3Q0wCaPNXE/ystgR1nIcvqqI4qHdWwjVMWPQlegjoTt7pXweAip2KZnE7BnFNoA5qJI4R4IFhjP2enhcjYO51Tz0r44Ow=" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=debug msg="completed keyexchange" Nov 01 04:47:25 volumio go-librespot[11822]: time="2025-11-01T04:47:25Z" level=debug msg="completed challenge" Nov 01 04:47:26 volumio go-librespot[11822]: time="2025-11-01T04:47:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:26 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:26 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:27 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:47:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 509. Nov 01 04:47:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:29 volumio go-librespot[11832]: go-librespot daemon starting... Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=debug msg="app state loaded" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47: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]" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47: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]" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47: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]" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=info msg="zeroconf server listening on port 41775" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=debug msg="obtained new client token: AACauUUQZQkJukNeIcIkL2kMnrYuR3553Q87L4fvLNcuai9WOsZzhRbTerIEsDNjVtjLs1KBsfmnevZ0fv/4eIPZ+X9bF3fT1JTz7yrw88FreG5smtWxPD/L28+9n19KTsi/sdDLfjUQ7cJ9btOJBs4JDMixFtz2+HNPhDJznRPWMnHzn1p9f2qhsyUdTfWI6p9laaB+NYVrSavnVBg5IassPu0qrlId6metvrEfoFU+SyvqjO5zLXU=" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=debug msg="completed keyexchange" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=debug msg="completed challenge" Nov 01 04:47:29 volumio go-librespot[11833]: time="2025-11-01T04:47:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:29 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:29 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:32 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:32 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 510. Nov 01 04:47:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:32 volumio go-librespot[11854]: go-librespot daemon starting... Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=debug msg="app state loaded" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=info msg="zeroconf server listening on port 36293" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=debug msg="obtained new client token: AACXpLiHjaCHmbgHdEQXJyBYS0MQFHxwiohWY0UX/8+OHJ8RW41J0BWwXvUhia4tKvVFP1X/dovu6aBGcJwEEPGORvzxluWTlUCT3kLVip6v2dHw5uPqgbSbBzbMEyK75v/qEFIleQXuKJC18I9ZfKlxBw7treaC1jollPR6B1bkiElB4m/wE2hDhl0Dk6MIVYqb5VMQqOxpQjT1d4pelai7dMpUqJpxxCepqyl1NiVYByNoU8he5JQ=" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=debug msg="completed keyexchange" Nov 01 04:47:32 volumio go-librespot[11855]: time="2025-11-01T04:47:32Z" level=debug msg="completed challenge" Nov 01 04:47:33 volumio go-librespot[11855]: time="2025-11-01T04:47:33Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:35 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:35 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 511. Nov 01 04:47:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:36 volumio go-librespot[11862]: go-librespot daemon starting... Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=debug msg="app state loaded" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47: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]" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47: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]" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47: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]" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=info msg="zeroconf server listening on port 40811" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=debug msg="obtained new client token: AACGaeO2f/pEPpmr1D4Z0HwAI7ltftJFPZUpl/Gf9XRf3+ZW4bMPSQgjnd1gkc8kTYAbBdYu2Wga282Icw6TPdg/xZaC1LRzAOIB7aYXbPh1Z4LJsuVMKGm3QoKJft+gThTWLSmgZx+v72+uVqNnTJ7lFBfx+YN7nA3Cyld7FkpWaaGDZyvy8iKUPzsMX0Ym0kD58kSSl29tHG4znWUStG2iKGPc+DM3AE4DSnDWCtva+WSBt80QNqc=" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=debug msg="completed keyexchange" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=debug msg="completed challenge" Nov 01 04:47:36 volumio go-librespot[11863]: time="2025-11-01T04:47:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:36 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:47:38 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:38 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 512. Nov 01 04:47:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:39 volumio go-librespot[11871]: go-librespot daemon starting... Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=debug msg="app state loaded" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47: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-gew4.spotify.com:80]" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=info msg="zeroconf server listening on port 36703" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=debug msg="obtained new client token: AAAJUg7zFHMhlkkEoj2AJfhG5gMMolqxx6AKQrZiuXOGKaYd1m51ojOZP4ME/RULzAymvtIwEjXXvefXEfeaOBWMEn7KqIhS5FwHYV9IcHiDGM3MclaCkD8x6xezJe8Sb8KkMUXbY9gYifVc0CyMgSRjhaZLzhY/3MgUOdw9hFjLkUC0/KMtISMDrMWq0CYGKbRF9ZOYMM3bRMBSZd1fOYyV82yEXsV95DtTSbZU8RY0BL18lsBY+k4=" Nov 01 04:47:39 volumio go-librespot[11872]: time="2025-11-01T04:47:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:40 volumio go-librespot[11872]: time="2025-11-01T04:47:40Z" level=debug msg="completed keyexchange" Nov 01 04:47:40 volumio go-librespot[11872]: time="2025-11-01T04:47:40Z" level=debug msg="completed challenge" Nov 01 04:47:40 volumio go-librespot[11872]: time="2025-11-01T04:47:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:41 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:41 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 513. Nov 01 04:47:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:43 volumio go-librespot[11893]: go-librespot daemon starting... Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=debug msg="app state loaded" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=info msg="zeroconf server listening on port 43627" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=debug msg="obtained new client token: AAA+9Mb5FCeZ1/35cXvGdusRckLv2BOJAQMM7Nmn0cCOCCB7SKUvXglMTffFq0B9tHxQYXUBlBBCuhxmeLit8hJfj22+Ri+pOrytTlybh3xO8eLrFaBzdmS3uXnq1ZXo99VN+KL3OAab8dLiAvVJeH26v7zBZnuJep3GU/nH9LxGbNEUQ5zT6I1JugluG/1Kn3QDIwMOjBYPWfiJzsDWCmpN3+vqLfsp/PoeSZP7W7zrO+KGNQxm1GQ=" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=debug msg="completed keyexchange" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=debug msg="completed challenge" Nov 01 04:47:43 volumio go-librespot[11894]: time="2025-11-01T04:47:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:44 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:44 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 514. Nov 01 04:47:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:46 volumio go-librespot[11901]: go-librespot daemon starting... Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=debug msg="app state loaded" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=info msg="zeroconf server listening on port 38881" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=debug msg="obtained new client token: AABIrDQQbHRdPypkPKjZQ11eAVHsFEhAj5OaWZNg0GKTGwQLnJePQ+iGnbCs98qJo65gMxPi3aKUOAD7Oz2K45ViZdu5rNzI5TYrToh6LMwU92M5DkIPl5nyP6r03UrPYvo1vHhAZP1i9WDyiUbKtn4x+JRV5z/BKxmXYcFFst2cfgOmEniMn9Vy4eAHp5E+zGGiOe4GF/9c+ejkLw57NSgzkTFBjwpgQaGYrBlDwQ14Nx+WWygEhvs=" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=debug msg="completed keyexchange" Nov 01 04:47:46 volumio go-librespot[11902]: time="2025-11-01T04:47:46Z" level=debug msg="completed challenge" Nov 01 04:47:47 volumio go-librespot[11902]: time="2025-11-01T04:47:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:47 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:47 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 515. Nov 01 04:47:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:50 volumio go-librespot[11912]: go-librespot daemon starting... Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="app state loaded" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=info msg="zeroconf server listening on port 44597" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="obtained new client token: AADQ8slJoCem3/3oLynjbgYTRR9wQEdd5UFCyAvVvO9zTbt1ZWXo3gYS3YHm1kVDtramun5MoUE/E5BwfT8ljmi+gM4eiFTS5jdWIxvYBRsi4XbXphUVskI6qjF5MO2vy46hptYhbghb4zHpjW/IKjZHMYmLoGEZzvsQ0EObnXK4cf5SOX7vV4LqKn2qZs1KN7GdLzG/EbxFAPpxz/VpbJeMrgt53EcEg7uZ8OWerVNW4vWjXnkbqHQ=" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="completed keyexchange" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=debug msg="completed challenge" Nov 01 04:47:50 volumio go-librespot[11913]: time="2025-11-01T04:47:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:50 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:50 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:51 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:47:53 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:53 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 516. Nov 01 04:47:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:53 volumio go-librespot[11934]: go-librespot daemon starting... Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=debug msg="app state loaded" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47: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]" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47: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]" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47: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]" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=info msg="zeroconf server listening on port 35581" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=debug msg="obtained new client token: AADV0CUK0UodhrvbJnCR7UioKKaREeiiDFMGKwJPepFmFDfLDr5pYBdZPyJvEHLDjrIr9y2IsZTC1F7wZoaTqwDtw+5cEuVB/LTsN0ghyxZrFihQRAKWX2iQecLh/UbdqFVzDif1v4enpEJUKQnpj60/Q6/wNh9ulKegdStfX/uAnRdNEkQuNx++Fl35erAJbQwgv61AqAFFKVQ8V5UqCprqwalGn7gElixu7rNOaSvT8nIxpFMm0H8=" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=debug msg="completed keyexchange" Nov 01 04:47:53 volumio go-librespot[11935]: time="2025-11-01T04:47:53Z" level=debug msg="completed challenge" Nov 01 04:47:54 volumio go-librespot[11935]: time="2025-11-01T04:47:54Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:56 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:56 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:47:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 517. Nov 01 04:47:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:47:57 volumio go-librespot[11942]: go-librespot daemon starting... Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="app state loaded" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=info msg="zeroconf server listening on port 42723" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="obtained new client token: AAD/g2VyPw0Ywj4ElM70/2qdhziFaYmnJ6yNp5OIFqdD5rcw+u9ZvKlacDK5c5y39yEuCBi33QwPdA6VtDZlsZVjvBP9+V1jfqAzwb8hMgbAE2XVu58c07fxv8Mv08d1DprfeWNHctJrkHDlc5xRj42o5v4s+b5id+KedsLUsJG+utL4xl/QpB8nnoNOIvtYZv/A+Q0c6LY3A7zuQzYo4NCzUdcCDI1KwmvITsZteEPnC4WvB1Poy84=" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="completed keyexchange" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=debug msg="completed challenge" Nov 01 04:47:57 volumio go-librespot[11943]: time="2025-11-01T04:47:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:47:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:47:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:47:59 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:47:59 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 518. Nov 01 04:48:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:00 volumio go-librespot[11951]: go-librespot daemon starting... Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48:00Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48:00Z" level=debug msg="app state loaded" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48: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]" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48: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]" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48: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]" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48:00Z" level=info msg="zeroconf server listening on port 34055" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48:00Z" level=debug msg="obtained new client token: AAAEgcwQ/bYhNK2f+cd5wRoKviG7llbrmfttf7z+kDTApsSXoY/SDh/pEoXGmIa1GVwy9Zj68hBUaU8RE+rBMc0N8sID2R6dxzRWy1eqj+NjT5LiwubtrNFzlpS+noj0ZtB6l7ngHrcGfqgT+hlViEAa2NoqHs91LG2CTi7FgND/NJJksM4WkdImo8Yx/szZLbWlpgo4q6Cd23XIxtExs4zG581XIlYb35O8G4mAuyETxAXdsfC1y/A=" Nov 01 04:48:00 volumio go-librespot[11952]: time="2025-11-01T04:48:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:01 volumio go-librespot[11952]: time="2025-11-01T04:48:01Z" level=debug msg="completed keyexchange" Nov 01 04:48:01 volumio go-librespot[11952]: time="2025-11-01T04:48:01Z" level=debug msg="completed challenge" Nov 01 04:48:01 volumio go-librespot[11952]: time="2025-11-01T04:48:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:01 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:48:02 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:02 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 519. Nov 01 04:48:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:04 volumio go-librespot[11973]: go-librespot daemon starting... Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=debug msg="app state loaded" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48: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]" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48: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]" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48: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]" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=info msg="zeroconf server listening on port 34429" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=debug msg="obtained new client token: AAB7RACFo8KfTDPqI7Rx2Ji+Ly/Zmep9i7v2BsdfOF5QYd6aJZiq1Gz1yftbtw45QJuXZOHIPJ6NWVK8QTsMPu8qXIPNmEZvQAsY84q737BiYHeZikrFZ7aW78IzZowMCJ/0BMoTGYD+oXfxeYaqW1lpUKoQdh671i0WdPyn7IpW4yNR7KoXnYaIGIN9GdwmSW2kW1S0qhb6QlZ80ugDuEyS6RxybWwhdiThZPPdJ9zylZ39eJ2yrjM=" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=debug msg="completed keyexchange" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=debug msg="completed challenge" Nov 01 04:48:04 volumio go-librespot[11974]: time="2025-11-01T04:48:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:05 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:05 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 520. Nov 01 04:48:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:07 volumio go-librespot[11985]: go-librespot daemon starting... Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="app state loaded" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=info msg="zeroconf server listening on port 34501" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="obtained new client token: AAAFNIsdQiS2auZk7WykyRgu5WCEYuHdUS3/oIXRE0/O3xsZ9FRhAu0S13uTICSsUwzdOU71lfGv6a32A41zv/VJjJo5Dw+d2KTcIIOIgdqwoF5N83sLvMf2VrEUN++JM43WiQeCAOcfEPZ32sPSg86pZpbnG/fA/z59AFMvUwSPSB/8IAl1GdzRL+u/G9o3QOGlJqaosUOJVQ/CwOLbmFuj1vDQf9Kp51+9eLrwADA+wQce3cqF43k=" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="completed keyexchange" Nov 01 04:48:07 volumio go-librespot[11986]: time="2025-11-01T04:48:07Z" level=debug msg="completed challenge" Nov 01 04:48:08 volumio go-librespot[11986]: time="2025-11-01T04:48:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:08 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:08 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:10 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:48:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 521. Nov 01 04:48:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:11 volumio go-librespot[11993]: go-librespot daemon starting... Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=debug msg="app state loaded" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48: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]" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48: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]" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48: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]" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=info msg="zeroconf server listening on port 35373" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=debug msg="obtained new client token: AACwxVyw3eBDqZ2WABkVFhWjEx0IxhAS5vChXUpe5CUtDkiLvx6ZBWHD8iHEXVWh6ql/qJo4I61Z1k6RMp+33CqHlkrW2bY7hQScw5ZijF71nPMTPjxJ9fQiXjf1pGgI0BXSV2MQHJZPe6+8F3je6VgGmy9TypYdbAl4jo+i3OMEAhoycVjsNzvx+Aq1c17IW2Xqmk6Ho+t+qUpvEWbg7hGV2cOQIJLRKAH6tCRNINGWCV1ZIUkopC4=" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=debug msg="completed keyexchange" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=debug msg="completed challenge" Nov 01 04:48:11 volumio go-librespot[11994]: time="2025-11-01T04:48:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:11 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:11 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:14 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:14 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 522. Nov 01 04:48:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:14 volumio go-librespot[12015]: go-librespot daemon starting... Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=debug msg="app state loaded" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48: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-gew4.spotify.com:80]" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=info msg="zeroconf server listening on port 40653" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=debug msg="obtained new client token: AACJDwKpQ/w6/HH79xlpfYX5t4f+nh5VKRspzePx9nZpHkH2Az3FLSQWTHmm9ibBFKj3nRPnv82a2Z7aBp119QjQSSGfwMH51dXJLpiNF65dPfSmmEAVqBpftp548lHxJ3zHLjx8getPWvWf64l3HlTi2bdH3EyrmhWO6fjqxh5aNzH5DSpH54dHxXX+bDAwIdrwjHe0NMso7bFGiM/wm4SZGhE7O89ES/bIvGz/pn7zBarUWKN2Enw=" Nov 01 04:48:14 volumio go-librespot[12016]: time="2025-11-01T04:48:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:15 volumio go-librespot[12016]: time="2025-11-01T04:48:15Z" level=debug msg="completed keyexchange" Nov 01 04:48:15 volumio go-librespot[12016]: time="2025-11-01T04:48:15Z" level=debug msg="completed challenge" Nov 01 04:48:15 volumio go-librespot[12016]: time="2025-11-01T04:48:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:16 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:48:17 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:17 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 523. Nov 01 04:48:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:18 volumio go-librespot[12023]: go-librespot daemon starting... Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=debug msg="app state loaded" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48: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]" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48: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]" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48: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]" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=info msg="zeroconf server listening on port 43739" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=debug msg="obtained new client token: AABLkClmIRtMzOmDrVj77iUKzHFti/cQEo5lbVrVlbTe0T80NKM7fR3PbtAqvrR9sncYCbv1qLmKRPxOTrjo+QeOVD/oVdCIfqJOwTflM9t5pyHZMZGqMbHhR7dInU95YsGZ2ElztS3ePcR9z+DDA3vVgoHBfiH62WPj2kpMcSlQpMHJGu+HqfZkh7YltdtRQyIxB9yaYbneRCXHqOyC9YY4HKy+bXZQ0qgtEJGqTZYPL1JKfw2tIXI=" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=debug msg="completed keyexchange" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=debug msg="completed challenge" Nov 01 04:48:18 volumio go-librespot[12024]: time="2025-11-01T04:48:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:20 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:20 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 524. Nov 01 04:48:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:21 volumio go-librespot[12032]: go-librespot daemon starting... Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=debug msg="app state loaded" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48: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]" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48: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]" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48: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]" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=info msg="zeroconf server listening on port 42773" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=debug msg="obtained new client token: AAAFxTEpEefeZvvT874e6K9AOxK5ZcOyOIgQYIRSF7qATThRU8GajspS4Lga3c15n2xzruF7z9cHIhZMwovhfQCM82XPrCvaDg+VPPfkRTNecoa/c8/1tslAWRaGNMFtdijcPyfkPml5hncdl9SL6Z5fT/99qxszzASP8Q1RZ+2YzjdBp0rMG7pyInhcCGOztQO0wAvODA4hA/M+1TyC3YshRsNu3z+juAwFbCQJ48Cb7duyMDKA3Yw=" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=debug msg="completed keyexchange" Nov 01 04:48:21 volumio go-librespot[12033]: time="2025-11-01T04:48:21Z" level=debug msg="completed challenge" Nov 01 04:48:22 volumio go-librespot[12033]: time="2025-11-01T04:48:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:23 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:23 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 525. Nov 01 04:48:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:25 volumio go-librespot[12054]: go-librespot daemon starting... Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="app state loaded" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=info msg="zeroconf server listening on port 41733" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="obtained new client token: AAADUXCpkd0o/sM/h+EaEYgByMhmz0+vE3GGdXxgYxXpSMeMonYDJbI6FTFpy7W6iZs40JP7mODtySRSmIEQEUcTKdl27Isj+HNGwofcN+J2dspSThdc89lUMLjtv03KkvoLF2MeyLLpoGcQ0tkKMwrjqy261M+SWUoU2ZVNrvpvCyB2+bdNZxeikKVsUSlJoJI9fpIQz1hNGDxEZwiWDKdPwYXNlmxMB/e4AnOo3QDCg87zpDM/fso=" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="completed keyexchange" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=debug msg="completed challenge" Nov 01 04:48:25 volumio go-librespot[12055]: time="2025-11-01T04:48:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:26 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:26 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 526. Nov 01 04:48:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:28 volumio go-librespot[12066]: go-librespot daemon starting... Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=debug msg="app state loaded" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48: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-gae2.spotify.com:80]" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=info msg="zeroconf server listening on port 42127" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=debug msg="obtained new client token: AAD43jAy1TYOv74dc6/Sspa8gBre2J0B1Sbpu417eeiRYLAhTZ7WtICkkd5cpkJhX7XpOXXCC2u+CA5Kyeagcs4Hx4kumbEXtTfiPaCV+Rr8mTKiyTv4WSRToT0ocUrDyrhzSCocaHC8p+4mWqyB1BYBA9K6a8dBwvU3Q1aUgGMNDv9Ys6nniCrsJidt22WtOtEfvlkPoLjBnIK/+9pAE57GM6g5/owJ9YUqGSF+6a0vrtaoHq5EuHM=" Nov 01 04:48:28 volumio go-librespot[12067]: time="2025-11-01T04:48:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:29 volumio go-librespot[12067]: time="2025-11-01T04:48:29Z" level=debug msg="completed keyexchange" Nov 01 04:48:29 volumio go-librespot[12067]: time="2025-11-01T04:48:29Z" level=debug msg="completed challenge" Nov 01 04:48:29 volumio go-librespot[12067]: time="2025-11-01T04:48:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:29 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:29 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:31 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:48:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 527. Nov 01 04:48:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:32 volumio go-librespot[12088]: go-librespot daemon starting... Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=debug msg="app state loaded" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=info msg="zeroconf server listening on port 38093" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=debug msg="obtained new client token: AABqEvQ4x5ea8Q+/MpHKk9X/wRBR4mzGV2NHrd8zoQw8MXmFW1DlPG2ylEA4KsMPeB3HvDr+4s9pjr2z8EKC354yigqUSBVD+frRDA+IL5NDJQyEIRTiGUvYDuJB79gSYalSh4v+RG1M9lRoUfo0EHGMRI95PM56FYlvkUenyrv8nuua6UlreJsv4g0/rmX4atxbIztLbmV4YR5nFuawS2nt60LVyuo7uonTYbb2HOowtJW0rvBeoZ4=" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=debug msg="completed keyexchange" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=debug msg="completed challenge" Nov 01 04:48:32 volumio go-librespot[12089]: time="2025-11-01T04:48:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:32 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:32 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:35 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:35 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 528. Nov 01 04:48:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:35 volumio go-librespot[12096]: go-librespot daemon starting... Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="app state loaded" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=info msg="zeroconf server listening on port 40071" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="obtained new client token: AACT8kjWhYscSf+eaoekRV071oefJDNM9slLKGQ+z1+nOUDAckNZmVzET54w0qkWXVNqKikAS8dZyeXL0E6Z6/TQP79FTsHs5w4POSJ7jVCCdWsFP/OGpblLuHRsk8ECbBvrv45sEP3yf8Aw1nixudP8HvpuCCfEyMifEQhunE6V6TUNeh9wiMrkCoNcSvbr2w4lJnXdLRzsKwMhgHgz3KJJ2HChPqaTekUOLAYpiybP9pBM+c6PlCc=" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="completed keyexchange" Nov 01 04:48:35 volumio go-librespot[12097]: time="2025-11-01T04:48:35Z" level=debug msg="completed challenge" Nov 01 04:48:36 volumio go-librespot[12097]: time="2025-11-01T04:48:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:38 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:38 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 529. Nov 01 04:48:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:39 volumio go-librespot[12105]: go-librespot daemon starting... Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=debug msg="app state loaded" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48: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]" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48: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]" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48: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]" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=info msg="zeroconf server listening on port 33367" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=debug msg="obtained new client token: AAAGj6hgzVdOiQ7C39npi9MvUaF98+5acOHPMSvGtHu5X+a6JB0OYgSzN2TfFBzQwg3V68vRp+3XYcg8LJxaqmGhcV+nlwGTzYkDP1WiiYRTxsx2qKhFA49o+mF6eLzqx6FWcmNDV/t9AoRRCaisqzaIONRqewtX139VcCYHPiCEsD+p3kRTnXrA2U23hHwKdV6BTUk7NaqldTfAVXKAUlK38+XlcrT0TBRsKoiBFwz8d7fePg+l" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=debug msg="completed keyexchange" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=debug msg="completed challenge" Nov 01 04:48:39 volumio go-librespot[12106]: time="2025-11-01T04:48:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:40 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 01 04:48:41 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:41 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 530. Nov 01 04:48:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:42 volumio go-librespot[12128]: go-librespot daemon starting... Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="app state loaded" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=info msg="zeroconf server listening on port 35541" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="obtained new client token: AAA8N12XHqiIuLces6mLXFDqcj2pCx3ICn4gLMSArMu+p79ijRZoyw0udfA0qY3er0V7F1gkmopvUoV8LhoBvIJmfENokkhXTmkNEPLOgaILPqGdl+LEZj/QATtC9QQHrJ3TzHeG+KDmpaPA4vBUlbO8KSi1ttt8Zl42VU0hX5IbrYLDxqRX+DuQdU5xnF5qpRE0vF5vRs73CqkoJngL1VISVDvx5dXrUKeFYZRvXzWJcVWsLbwTPYg=" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="completed keyexchange" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=debug msg="completed challenge" Nov 01 04:48:42 volumio go-librespot[12129]: time="2025-11-01T04:48:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:44 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:44 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 531. Nov 01 04:48:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:46 volumio go-librespot[12137]: go-librespot daemon starting... Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=debug msg="app state loaded" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=info msg="zeroconf server listening on port 43707" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=debug msg="obtained new client token: AABIIVaCmohrg0u1ZumglaODpvjQWinO3P9qZvMajMULN+RXcCWboUFLvCLu43BF/WGIKhq/d49AOt1ycbRrD6eSytKKOZMyoLNCAOPFICFeylzHREifUhaM6WF5+IIRxW4aEizXqDqDBtPllyrJraPC5xNeBrvDKLBjczDnixkw/F7376vpmNSCuWokAV+uQKkKdbnH/h27ZgbUVJwIu/VAD3Hbis76DQC43Q6qQ12xPvX3biv8" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=debug msg="completed keyexchange" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=debug msg="completed challenge" Nov 01 04:48:46 volumio go-librespot[12138]: time="2025-11-01T04:48:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:47 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:47 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 532. Nov 01 04:48:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:49 volumio go-librespot[12148]: go-librespot daemon starting... Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="app state loaded" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=info msg="zeroconf server listening on port 35893" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="obtained new client token: AACzdcjF3xiFmzChCzFhg390NlrqxaNkf+K3feCb86Mt212lBHB/uRS1444+btOt4Rj7S7Z4fVNPI4thJgxmICe66gWbAZ2YRE98mx0WupF4yh2bv4OCZ58EpNowgAnPl/pylsD/Io/2QTwpego2TdTfDjvh8d/eEUb3o/dfbUiLWp6cg/gKtVOQ6AT0zd1LeqCcq7BL5vDUrX853piB56t7xO3zRNBvpkKZyq7S5FREIUq1012YP30=" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="completed keyexchange" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=debug msg="completed challenge" Nov 01 04:48:49 volumio go-librespot[12149]: time="2025-11-01T04:48:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:50 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:50 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 533. Nov 01 04:48:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:53 volumio go-librespot[12170]: go-librespot daemon starting... Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=debug msg="app state loaded" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=info msg="zeroconf server listening on port 38569" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=debug msg="obtained new client token: AAB1iDYVnkN5VPOEBVqiQkpFG6R4GrfsTo8DW49GIIekx2LKzVyjbJ6dv4ftx9JqJfg54EkM8v/tBp0cxGwYloPXb3sJZ+c6YSJdCV8qZ8b/AGSMgnzY86Ifb5/o1qKrM1s2/4kjivkFoTrmQ+hazCir12pnyt1bDqbn9UO8GpYDtIsxPdfdjx+T/fRPtnnKd79NzUso/3XeeRXv1iFqWJZzgtQWnI3/xEXJgi5/mcgzO689ilqj" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=debug msg="completed keyexchange" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=debug msg="completed challenge" Nov 01 04:48:53 volumio go-librespot[12171]: time="2025-11-01T04:48:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:53 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:53 volumio volumio[11117]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 534. Nov 01 04:48:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 04:48:56 volumio go-librespot[12178]: go-librespot daemon starting... Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=info msg="running go-librespot 0.4.0" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="app state loaded" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=info msg="zeroconf server listening on port 41783" Nov 01 04:48:56 volumio volumio[11117]: info: Initializing connection to go-librespot Websocket Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="new websocket client" Nov 01 04:48:56 volumio volumio[11117]: info: Connection to go-librespot Websocket established Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="obtained new client token: AAB8xLCAPbzZXbeUB0B/wtcmbMiE++htWXn1Dabfi22fVWBi2TU6HB/w6syJ1g0PjVxEHGax8l00lrRkrh11/x+YJtkX/cOGI+ZaNKEceyohUIfyIFXuplTe8f0S4RtqOW3ci3C6eTFXE9ugJIQyggXF0IcJ1bmysX5Rp8VHzbdFZbErGmmoYCzh+0ILyDfpq+rXAU/rihLVmQg5LGEz0S6F5gxkCi+x0OHbdEpDOQeTf+2QxwW5CJQ=" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="completed keyexchange" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=debug msg="completed challenge" Nov 01 04:48:56 volumio go-librespot[12179]: time="2025-11-01T04:48:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 04:48:56 volumio volumio[11117]: info: Connection to go-librespot Websocket closed Nov 01 04:48:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 04:48:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 04:48:59 volumio volumio[11117]: info: Getting Spotify volume Nov 01 04:48:59 volumio volumio[11117]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 04:48:59 volumio volumio[11117]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 04:48:59 volumio volumio[11117]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 01 04:48:59 volumio volumio[11117]: errno: -111, Nov 01 04:48:59 volumio volumio[11117]: code: 'ECONNREFUSED', Nov 01 04:48:59 volumio volumio[11117]: syscall: 'connect', Nov 01 04:48:59 volumio volumio[11117]: address: '127.0.0.1', Nov 01 04:48:59 volumio volumio[11117]: port: 9879, Nov 01 04:48:59 volumio volumio[11117]: response: undefined Nov 01 04:48:59 volumio volumio[11117]: } Nov 01 04:48:59 volumio volumio[11117]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 04:48:59 volumio sudo[12201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-01 04:47' Nov 01 04:48:59 volumio sudo[12201]: 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"