Nov 04 14:18:01 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:01 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 95. Nov 04 14:18:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:02 volumio go-librespot[4491]: go-librespot daemon starting... Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=debug msg="app state loaded" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" 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 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" 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 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=info msg="zeroconf server listening on port 40247" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=debug msg="obtained new client token: AAD9Cb1V6zx82lF7ZXUqz5rlgst89Fxn1m67n8kZUHQGUsI5E8oEU5HLeJR6YVfW6yOjl1OmpvxlPQj1xLsINhhFPd2mjTWtsqSDy1/B3tOqQvjTUSg/+ooWA2G3WH0T3W7DpXwrfIUDRh8CCvMjwianv24BN6s/WIYqJnKwW5mfAka+KH7kPdqDBvjHiQYORXcQ+2IMYpgzdC84ImkX42wZ+VbpFZuEgolZHK5KlMMSL1F2o0+z" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=debug msg="completed keyexchange" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=debug msg="completed challenge" Nov 04 14:18:02 volumio go-librespot[4492]: time="2025-11-04T14:18:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:04 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:04 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96. Nov 04 14:18:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:05 volumio go-librespot[4499]: go-librespot daemon starting... Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=debug msg="app state loaded" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" 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 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" 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 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" 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 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=info msg="zeroconf server listening on port 45111" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=debug msg="obtained new client token: AAB13A18g7qdUfosFup1QkEsXTzr/JlcMY9mBaEKUZ+rXqqTMdri0xjwtk4US1u0waVtQ/0MuyE/RPyAztqvb6vjyYvQ9sp2PntW0f0/mWmeAyFwq3BLGjv4w41/hQVJUfCpRJxlM7LXYbuEr/+UhaprjMUAbri0dtk34GY5wsmUetFI0j9rshj6N+QWQ6jiMa0VY5UWhD6r8pmZgrbli9V3XxhkqvafK3RvYL5LHBxU+Sj8YWeYZpQ=" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=debug msg="completed keyexchange" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18:05Z" level=debug msg="completed challenge" Nov 04 14:18:05 volumio go-librespot[4500]: time="2025-11-04T14:18: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 04 14:18:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:06 volumio volumio[3893]: info: CoreCommandRouter::volumioGetState Nov 04 14:18:06 volumio volumio[3893]: info: CorePlayQueue::getTrack 0 Nov 04 14:18:06 volumio volumio[3893]: info: Listing playlists Nov 04 14:18:06 volumio volumio[3893]: info: Listing playlists Nov 04 14:18:07 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:07 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 97. Nov 04 14:18:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:09 volumio go-librespot[4521]: go-librespot daemon starting... Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="app state loaded" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=info msg="zeroconf server listening on port 40073" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="obtained new client token: AAA9s09ugh64z4kC9c8uNCqQTHCSUXYXdIu0ziIsW0UX3LvJUJ6M4rao3Dyf1VAk0GX3p9MXzerAcXYE7OsRRrHBzFYYaXo+uymsD7MWW3VByogkmkcXIofhpELrvQihePHsxZzne6SQdWsvFM0stNMqkWRZDy7j4erLLIUr7qO+ZNuNsPLajUflpsaBwsWbhK0yLBdJIq5B/jVx7XmKL6Ejn0zbRt9r7UvRmTU+dJqfTffdf+te" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="completed keyexchange" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=debug msg="completed challenge" Nov 04 14:18:09 volumio go-librespot[4522]: time="2025-11-04T14:18:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:10 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:10 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98. Nov 04 14:18:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:12 volumio go-librespot[4532]: go-librespot daemon starting... Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=debug msg="app state loaded" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" 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 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" 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 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" 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 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=info msg="zeroconf server listening on port 36565" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=debug msg="obtained new client token: AAAofvbYbJqFGNThHYzAYdr1NlIbK91iEeM0Lclay4iEggP1bU8oQfpMKlpVWlMSKXKyFbnMm2nXrqjXe37sa99kKH+J3wJodg0vdV+RRNY3I7OJAr5SDWL7j4j+V4oyIMJryQ7sgIx9DJ4vs0aGVbOGFpoXLU1uQhsWW90my+EgwjSyxjB9EsXch0E2g7VlqTsoWTGzmk+yetVWvh8rK5gV2NjKQ6YEYJXl0+1dxYXouFSAjeLO51A=" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" 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 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=debug msg="completed keyexchange" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18:12Z" level=debug msg="completed challenge" Nov 04 14:18:12 volumio go-librespot[4533]: time="2025-11-04T14:18: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 04 14:18:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:13 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:13 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 99. Nov 04 14:18:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:16 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:16 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:16 volumio go-librespot[4541]: go-librespot daemon starting... Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=debug msg="app state loaded" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" 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 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" 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 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" 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 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=info msg="zeroconf server listening on port 46727" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=debug msg="obtained new client token: AABtWjVKsLqUfgLu8b4OMMBfuKBgLn9mPoaTZA4cQ1qYojIpATnBMFXM7Xz/gGOlzA81vCXJsC84IHZwdLuxvKlGRVWwtHd/xpD09FdiIl/LnvbxLWJdRaRZghzhBE9HO6luzQuloUCCqbxMlb60yt7hNyaFkOI0zMfpEvVCLjZ58hqxdU/N8Zaah/wRP9D1WPxTkCDTqO5G7MKHOPkz2puRM7/5scfVN5QrgaPQ0icDmTPMODd6" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=debug msg="completed keyexchange" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=debug msg="completed challenge" Nov 04 14:18:16 volumio go-librespot[4542]: time="2025-11-04T14:18:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:16 volumio volumio[3893]: info: CoreCommandRouter::volumioGetState Nov 04 14:18:16 volumio volumio[3893]: info: CorePlayQueue::getTrack 0 Nov 04 14:18:19 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:19 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 100. Nov 04 14:18:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:19 volumio go-librespot[4563]: go-librespot daemon starting... Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=debug msg="app state loaded" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" 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 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" 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 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" 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 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=info msg="zeroconf server listening on port 41667" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=debug msg="obtained new client token: AACXN3OJ16q4IL3jkk0C3jIkXVjAycULZuzr4g+Xu2YEcenN5qVCG5t0efiTin4B/uPw5jnVyULS7TI6dKCfmym0FzoQVWJYT6xsmLwrwEyBqthfg/lSaqiBOBPBYjUt0352XhKSd1HSfTqBOip9kK1tnHgfWgUpQ0Gk6u9BI9T3t4eOuKoQStHdKOFA8KDI6cZjosqX5ELjRNEIi+FG28NWF53fQZyCOZZ76wsdNVu9R65HVdv2NAA=" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=debug msg="completed keyexchange" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18:19Z" level=debug msg="completed challenge" Nov 04 14:18:19 volumio go-librespot[4564]: time="2025-11-04T14:18: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 04 14:18:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:22 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:22 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101. Nov 04 14:18:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:23 volumio go-librespot[4572]: go-librespot daemon starting... Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=debug msg="app state loaded" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" 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 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" 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 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=info msg="zeroconf server listening on port 33251" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=debug msg="obtained new client token: AACPtMW/5bQCIBt9/TivyDFBSDMjuk0lFSU7P2v+oX3l3FZrJEb6klcmITFfn03wvl607syg03LWEwIuWgst0RFoaDuraN9X97mXlTSSDdKx3339rWoGCQrbFEqEhSiW8StXTIKrrn2sSoRQ9Kv2FLjJpwf71NIJf55xajPIYK5dOV/ugsYhSgd8NquhqulCkm1+s+1rqy8k6ydtHLqk547lNNzwlDBa37gUrZq7iDrBb8e+oLGv" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=debug msg="completed keyexchange" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=debug msg="completed challenge" Nov 04 14:18:23 volumio go-librespot[4573]: time="2025-11-04T14:18:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:25 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:25 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102. Nov 04 14:18:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:26 volumio go-librespot[4594]: go-librespot daemon starting... Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=debug msg="app state loaded" Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:26 volumio volumio[3893]: info: CoreCommandRouter::volumioGetState Nov 04 14:18:26 volumio volumio[3893]: info: CorePlayQueue::getTrack 0 Nov 04 14:18:26 volumio volumio[3893]: info: Listing playlists Nov 04 14:18:26 volumio volumio[3893]: info: Listing playlists Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" 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 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" 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 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" 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 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=info msg="zeroconf server listening on port 39133" Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=debug msg="obtained new client token: AACvt4Hsiq81Kn1vZ9Flv/3btT+hFJAQvUoqXiu/qrzRrXFy+HYUNdwC5Rv97oKemnsp1FS6Uh8ZPcBA/FG65NizIMeIYT5gk1pG88BCwdZ5ClWxA15UuM4ruLi8YWvMDSpdNk8Ny+IF6tgk7flRrLehhXGhlrdcmTtF51B3PiXZY+6O40C74+LRE5twBR5vxW7cZWUGm1swSRUagWz0Ymk+9MIcrxIvi0+FMtYnH+hwJEI6Zn3ZQmM=" Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=debug msg="completed keyexchange" Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18:26Z" level=debug msg="completed challenge" Nov 04 14:18:26 volumio go-librespot[4595]: time="2025-11-04T14:18: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 04 14:18:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:28 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:28 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103. Nov 04 14:18:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:30 volumio go-librespot[4602]: go-librespot daemon starting... Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=debug msg="app state loaded" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" 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 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" 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 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" 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 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=info msg="zeroconf server listening on port 43065" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=debug msg="obtained new client token: AAA0IQVT9STFmwI2eFNfWmB4rZxX1fDymLcFuLz/UxZIMzj4jN4SXyFWN0HXEopWBnTZdxIw5BC5nl6Y34JkYmWs67VN3FkRCd2lDjnw7y3fZApD+NXaqyvmKAcOkFUdrvXGi15RM5QERkD/+vJvABpOhxRnAp91p5FRJ81vBZV3n/xJJMHPmNRwCQSVWDf7C7Mh0566bsyYY8TEmTRBMJUgoY74EjXhnRIv9OxtSFS/Md0WXU2T" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=debug msg="completed keyexchange" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=debug msg="completed challenge" Nov 04 14:18:30 volumio go-librespot[4603]: time="2025-11-04T14:18:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:31 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:31 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104. Nov 04 14:18:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:33 volumio go-librespot[4613]: go-librespot daemon starting... Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=debug msg="app state loaded" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" 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 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" 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 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" 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 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=info msg="zeroconf server listening on port 46553" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=debug msg="obtained new client token: AAB9xbzfptPsJF1fplTPJt6IDsGRl60b1M57MjjPdLTQHyPAiV/3LM7hPfQFWvo9xDaOL+BUNCzbKfgaqm4bVBYkNLb1Ac9hJpE8bVRCI/YqVgvHLeT7+jLqEf1bBH8/RSVRLSN+5/iS1wxZErIKf45DdZCik2HUjYF02obAELUh/mGCa93KWvnVX7aqvv8EgjdC/XR/k1n0aLO6MSBC29F5umBiGYLDligLHs/2ZaegvjiTyNFp7zE=" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=debug msg="completed keyexchange" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18:33Z" level=debug msg="completed challenge" Nov 04 14:18:33 volumio go-librespot[4614]: time="2025-11-04T14:18: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 04 14:18:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:34 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:34 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:36 volumio volumio[3893]: info: CoreCommandRouter::volumioGetState Nov 04 14:18:36 volumio volumio[3893]: info: CorePlayQueue::getTrack 0 Nov 04 14:18:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105. Nov 04 14:18:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:37 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:37 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:37 volumio go-librespot[4635]: go-librespot daemon starting... Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=debug msg="app state loaded" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" 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 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" 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 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=info msg="zeroconf server listening on port 42785" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=debug msg="obtained new client token: AABQ8Qt4UQ+YEOS5MAusvA9Gq+MD3JCYIFE/dOMBi3sOYkxhfxk9a5wwP37CSKXLDqtfDAjSGXdPQZojTcek6CPYAJPZm11WgUvGf+3wsyOmus4Tx/PrErbBBmcORUOwJrnL8M3ZLb1T/YaWUfjKHFUQfjDlSUn8qiH4IhDsDWUoSvlY6c3wVrEqs4w1uPr6IZnQGYddKFCs30/8+9VA/0Jvdjiso0MWXXptCxK3uCEZWNtrz5ut" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=debug msg="completed keyexchange" Nov 04 14:18:37 volumio go-librespot[4636]: time="2025-11-04T14:18:37Z" level=debug msg="completed challenge" Nov 04 14:18:38 volumio go-librespot[4636]: time="2025-11-04T14:18:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:40 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:40 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106. Nov 04 14:18:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:41 volumio go-librespot[4643]: go-librespot daemon starting... Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="app state loaded" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=info msg="zeroconf server listening on port 45571" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="obtained new client token: AACMdZ8wvi/93RWXBxNlXt0kJzuTTV1ffOi9PuFGEUlJelXHvEPx7SoRTfoDkNzuaUQ9gTLooH0FVV95aqfwrhc4x6PwxnNRC1OId032CP3Bgp84Rm4riO3INgj7j5P/sVwcAyNamyqAw7oNDQfje9uxXNnhNTAUnaPiWfIZ2j4PNhDkgDV0w2H+v7v0vexwP5MS/0Qj26ZfYXH1E/oj/l04Fh+TlGN33PZDPXqdUAZaKupr8vKRx8k=" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="completed keyexchange" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=debug msg="completed challenge" Nov 04 14:18:41 volumio go-librespot[4644]: time="2025-11-04T14:18:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:43 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:43 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Nov 04 14:18:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:44 volumio go-librespot[4652]: go-librespot daemon starting... Nov 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" level=debug msg="app state loaded" Nov 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" 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 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" 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 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" level=info msg="zeroconf server listening on port 45851" Nov 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" level=debug msg="obtained new client token: AABcWO4QR+ckDdcPHfJllMzkjBUuTgePWIm1yL3oSI7piU4vPy65+t7P/aI4D9upUWi1TPAz0i7OBK32mHvr5Uk8DBy8vuAzs9PEGfN3AeuBbU3PIRs++ds4KCjR+g9lB0k6TD9DvCnHu+rvnBu3L5x+HEhdqI/NeP7W7XpB3OKtIx5yZKVEZcIl7gO8qaUtKahnZ2G36i0N9fnruFWgF94i+4bnqo9pMhjgCZFt/Z2hSMddvV5eVrc=" Nov 04 14:18:44 volumio go-librespot[4653]: time="2025-11-04T14:18:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:45 volumio go-librespot[4653]: time="2025-11-04T14:18:45Z" level=debug msg="completed keyexchange" Nov 04 14:18:45 volumio go-librespot[4653]: time="2025-11-04T14:18:45Z" level=debug msg="completed challenge" Nov 04 14:18:45 volumio go-librespot[4653]: time="2025-11-04T14:18:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:46 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:46 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:46 volumio volumio[3893]: info: CoreCommandRouter::volumioGetState Nov 04 14:18:46 volumio volumio[3893]: info: CorePlayQueue::getTrack 0 Nov 04 14:18:46 volumio volumio[3893]: info: Listing playlists Nov 04 14:18:46 volumio volumio[3893]: info: Listing playlists Nov 04 14:18:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Nov 04 14:18:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:48 volumio go-librespot[4674]: go-librespot daemon starting... Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=debug msg="app state loaded" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" 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 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" 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 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" 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 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=info msg="zeroconf server listening on port 38457" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=debug msg="obtained new client token: AACuW7k6GhZCdpE4Soc7wgc9VjA/lOOqCjbrmjCpS17Kbz07BrrCqE6y6kHzgfe7q8b3WhIDBelbLqrOq0k9Bq15UY03fDEc+TLjSTSIwVr9ElxUMwscdwK0YWMubBm69xCCvKTi3nos9DxsgzmBredFbhgsKTHP/+hQPJWkdmDaeqLc/XPOLtABisQxEoRx9IREQH4Bt52/eMtRTBqAg1mdP64E7dbTDnGqYWI1QPCCF5l2kboQjX0=" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=debug msg="completed keyexchange" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=debug msg="completed challenge" Nov 04 14:18:48 volumio go-librespot[4675]: time="2025-11-04T14:18:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:49 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:49 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Nov 04 14:18:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:51 volumio go-librespot[4682]: go-librespot daemon starting... Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=debug msg="app state loaded" Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" 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 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" 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 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" 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 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=info msg="zeroconf server listening on port 45751" Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=debug msg="obtained new client token: AADqvtON8rXKw9nIG6ZbxMvmir2CXYIsp/17ERMuJdQnQhzEWUZGu97MQxZpCVjKaPaMPyhhfMcePznsYUqj9zJu0CHXnDZgk5GtbDbM8MRwbWg1SCcaT4vVb0+bO21l8X01FcvBTdMAXtunRbqTubYjCWKI3ShgXO73B6kO6XcMBs3NDGaIJOk4NAOh24ILNNlq5554As02awuKKXndiHTqRPN9tXAY69ibAHE9jyqWy+d3sbsUDsI=" Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=debug msg="completed keyexchange" Nov 04 14:18:51 volumio go-librespot[4683]: time="2025-11-04T14:18:51Z" level=debug msg="completed challenge" Nov 04 14:18:52 volumio go-librespot[4683]: time="2025-11-04T14:18:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:52 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:52 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:55 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:55 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Nov 04 14:18:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:55 volumio go-librespot[4693]: go-librespot daemon starting... Nov 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" level=debug msg="app state loaded" Nov 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" 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 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" 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 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" level=info msg="zeroconf server listening on port 40739" Nov 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" level=debug msg="obtained new client token: AAC/qTtGMMCdT4vOHrejZt0Vs74ufuwKEWZ1ct/R1b71H/YjGNZuqxQva8bTUKHvgAjIdyuXPV+E3K+UZkIXLxNxW9cBAnHqMQ9mGw+RnyDH9m4TDaLpZcSzk36jzG1hPCk8g2kU6yLlQtY4QqNRIVkduxL8/tRvDsgSKXIAPvFKMBdO7R62/TyZ+Qj+eucAd/V+RKpKAV0zjsf9sSl+BZso6Rxn4RvlZFz6FnprPZzPLdZZojr2Wco=" Nov 04 14:18:55 volumio go-librespot[4694]: time="2025-11-04T14:18:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:56 volumio go-librespot[4694]: time="2025-11-04T14:18:56Z" level=debug msg="completed keyexchange" Nov 04 14:18:56 volumio go-librespot[4694]: time="2025-11-04T14:18:56Z" level=debug msg="completed challenge" Nov 04 14:18:56 volumio go-librespot[4694]: time="2025-11-04T14:18: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 04 14:18:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:18:56 volumio volumio[3893]: info: CoreCommandRouter::volumioGetState Nov 04 14:18:56 volumio volumio[3893]: info: CorePlayQueue::getTrack 0 Nov 04 14:18:58 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:18:58 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:18:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Nov 04 14:18:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:18:59 volumio go-librespot[4716]: go-librespot daemon starting... Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=debug msg="app state loaded" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" 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 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" 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 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=info msg="zeroconf server listening on port 33273" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=debug msg="obtained new client token: AABkdEcwCn1TntL4xcs3TTUUC8ovvDvrrPc82tZLtHpWE+kFXIbgtfYz3DEdyMY6XqRAwMeSiQhgTJFzH4pXiGToPcYdohc6Il/7qj6HL3bOH7ntuNCdms7ih16n5j/YbtxBXJ6i6wWxlQyN3kFr/O2s1GJ7Mi25h5zxeLTLhYpxTh0CoeLDrrTbBh9snEgKGTTW7Q1o2VCj48m0m1s88Lh5jBbPZOlej7KyhakopohX3UkML5IqzKo=" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=debug msg="completed keyexchange" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=debug msg="completed challenge" Nov 04 14:18:59 volumio go-librespot[4717]: time="2025-11-04T14:18:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:18:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:18:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:19:01 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:19:01 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:19:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Nov 04 14:19:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:19:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:19:02 volumio go-librespot[4724]: go-librespot daemon starting... Nov 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" level=debug msg="app state loaded" Nov 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" 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 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" 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 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" level=info msg="zeroconf server listening on port 35165" Nov 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" level=debug msg="obtained new client token: AAAEEdkvybMGc3YOgGQElywsoMwcqsn3nIIU9Zn3kbF7/LAa9//cjteAaeSZHdUVIlyVT4fcuJZ9Wca0xynr1Au/B04eeiqR8zh+hhvow8vxNtgTlD8e+dtEjWiCvEwDaQJ00RVPHrfgB1CVpmsJTcZdDX7tAeUXqAGSvPAbqQySoE6jOvsQ2vM1I3xfYcCACarHV2ibHuwPX8ZUcE/51rJW4C1sg9SMC7oIEt17ePuv3HjAerd1gp0=" Nov 04 14:19:02 volumio go-librespot[4725]: time="2025-11-04T14:19:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:19:03 volumio go-librespot[4725]: time="2025-11-04T14:19:03Z" level=debug msg="completed keyexchange" Nov 04 14:19:03 volumio go-librespot[4725]: time="2025-11-04T14:19:03Z" level=debug msg="completed challenge" Nov 04 14:19:03 volumio go-librespot[4725]: time="2025-11-04T14:19:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:19:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:19:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:19:04 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:19:04 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:19:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113. Nov 04 14:19:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:19:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:19:06 volumio go-librespot[4733]: go-librespot daemon starting... Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="app state loaded" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=info msg="zeroconf server listening on port 41319" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="obtained new client token: AADTuz8X8oibvJnavU1VHk6e6TIKfO0o2DSArHl6HVdBDW2Gv8DPRC1KpKwLQ9yF94UGo8mrVD42Kzp+XJRuG6Bwe1pv3bA0Dz/wmroVtdCGJaS6srq7qVKQ/Syg7dIwQL2y/JYiDFdd+8ufv9nmi7dEBvw1qoe3VsvXDB0vQxquMJjhpZIsmOxZl/dOrT4/40YjUoFTMGGKY37sjuqNom63akck/flSK0fx6j7VBha5ZpLR8tDVKyE=" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="completed keyexchange" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=debug msg="completed challenge" Nov 04 14:19:06 volumio go-librespot[4734]: time="2025-11-04T14:19:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:19:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:19:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:19:06 volumio volumio[3893]: info: CoreCommandRouter::volumioGetState Nov 04 14:19:06 volumio volumio[3893]: info: CorePlayQueue::getTrack 0 Nov 04 14:19:06 volumio volumio[3893]: info: Listing playlists Nov 04 14:19:06 volumio volumio[3893]: info: Listing playlists Nov 04 14:19:07 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:19:07 volumio volumio[3893]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:19:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114. Nov 04 14:19:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:19:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 14:19:09 volumio go-librespot[4755]: go-librespot daemon starting... Nov 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" level=info msg="running go-librespot 0.4.0" Nov 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" level=debug msg="app state loaded" Nov 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" 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 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" 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 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" level=info msg="zeroconf server listening on port 42177" Nov 04 14:19:09 volumio go-librespot[4756]: time="2025-11-04T14:19:09Z" level=debug msg="obtained new client token: AABNaPG9FEcKHSrtUi5Iz+1B2HzAPkL6Vyf/XOCqYuEd7lyxi8qyePCBwCFsr05NQ/pWdQM/3ldXnJ7TuTEdPDVOJMlQG/ZxLJeIkN0hT7lMw1VtqOwyBnFa9UvWXPMMK/8RUfakSltdRhsVmiPF09xraZIXYQBApnp22SK4pyx44T3IKp3jgl3CMJQyQ9dGYztkkBmV6VysQNIy28Sr8n1aFb0Jta923bS7+jbGCfGlnWNKil9Vmug=" Nov 04 14:19:10 volumio go-librespot[4756]: time="2025-11-04T14:19:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 14:19:10 volumio go-librespot[4756]: time="2025-11-04T14:19:10Z" level=debug msg="completed keyexchange" Nov 04 14:19:10 volumio go-librespot[4756]: time="2025-11-04T14:19:10Z" level=debug msg="completed challenge" Nov 04 14:19:10 volumio volumio[3893]: info: Initializing connection to go-librespot Websocket Nov 04 14:19:10 volumio volumio[3893]: info: Connection to go-librespot Websocket established Nov 04 14:19:10 volumio go-librespot[4756]: time="2025-11-04T14:19:10Z" level=debug msg="new websocket client" Nov 04 14:19:10 volumio go-librespot[4756]: time="2025-11-04T14:19:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 14:19:10 volumio volumio[3893]: info: Connection to go-librespot Websocket closed Nov 04 14:19:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 14:19:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 14:19:13 volumio volumio[3893]: info: Getting Spotify volume Nov 04 14:19:13 volumio volumio[3893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 14:19:13 volumio volumio[3893]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 14:19:13 volumio volumio[3893]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 04 14:19:13 volumio volumio[3893]: errno: -111, Nov 04 14:19:13 volumio volumio[3893]: code: 'ECONNREFUSED', Nov 04 14:19:13 volumio volumio[3893]: syscall: 'connect', Nov 04 14:19:13 volumio volumio[3893]: address: '127.0.0.1', Nov 04 14:19:13 volumio volumio[3893]: port: 9879, Nov 04 14:19:13 volumio volumio[3893]: response: undefined Nov 04 14:19:13 volumio volumio[3893]: } Nov 04 14:19:13 volumio volumio[3893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 14:19:13 volumio sudo[4780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-04 14:18' Nov 04 14:19:13 volumio sudo[4780]: 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"