Oct 29 14:18:00 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:00 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101.
Oct 29 14:18:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:03 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:03 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:03 volumio go-librespot[16878]: go-librespot daemon starting...
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="app state loaded"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=info msg="zeroconf server listening on port 45833"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="obtained new client token: AABTFpgk3hjCeQHsTTxHSQf5sOaHxXQSTcPpSM5y76x7qE9Ib7aSstDZ8co7enWmn3csv5hIcXsn8VUKv0Yua5FiJdLHXJMVRuerlu5RyBvZcYRoPMPX+VbM9LdqquRSGxaDW/IL/nHJZ5Evjev8Ee6mNUH0Ol9CN6Hd11079TvVCExwwMtjRZKHtvc/k8jTH1x/srFmZVtQMR+3/1GEDHsAOrsxx5eZvh0Lid4kzbrZhoBYFuWT"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="completed keyexchange"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=debug msg="completed challenge"
Oct 29 14:18:03 volumio go-librespot[16879]: time="2025-10-29T14:18:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:06 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:06 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102.
Oct 29 14:18:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:06 volumio go-librespot[16886]: go-librespot daemon starting...
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=debug msg="app state loaded"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18: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]"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18: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]"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18: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]"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=info msg="zeroconf server listening on port 35295"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=debug msg="obtained new client token: AADNkxfNEuK9xUc9108tEEkEwQcnlwYZE1R+zDmploXM4q8gEykoeyQaBkCrh8855sic8AA4smvMJDGwpdCRD9iozVHsFx+NTDqkJLlMnn8FtweMnRgZdeSsoJRt9ZGR+8N6QQ3S/GK1T0rl5Q0OSc9ffEpmXMb1zFbGdVYdYAwXxSoAb5Ckc4xdH2WXI28B2QG5Y9VkUdgfAPCW8mw2kuWSInMaGaHbR8jP+/13YRjceKI7owZB7yc="
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=debug msg="completed keyexchange"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=debug msg="completed challenge"
Oct 29 14:18:06 volumio go-librespot[16887]: time="2025-10-29T14:18:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:09 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:09 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103.
Oct 29 14:18:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:10 volumio go-librespot[16908]: go-librespot daemon starting...
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="app state loaded"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=info msg="zeroconf server listening on port 40387"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="obtained new client token: AABwSl8jjJX+iluY2BBNRyPx8ED3EfZcbhTJSf0qOO1Z1fPUFMfEc1pwKpycOf1XgbgH4TbB6bttMH14UuSjePK7ss+IIrL1dDoAlkhX0E4Gys0QC0/h/6Sum63ktqEa/5njQIJNIXAK7zuXkcfGxcYBtKkE7RDJ61n1d3ItP1R2GMUTeNpCcv36cvmH0id8hFChyES2Vrj8RC70alnxVJ1jrC1kPVk0GhHhN+qYG31uS+2DvK2K"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="completed keyexchange"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=debug msg="completed challenge"
Oct 29 14:18:10 volumio go-librespot[16909]: time="2025-10-29T14:18:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:12 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:12 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104.
Oct 29 14:18:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:13 volumio go-librespot[16920]: go-librespot daemon starting...
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="app state loaded"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=info msg="zeroconf server listening on port 42599"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="obtained new client token: AAALnVzg1ZkzBLa05ZrM2nnNuUYUn2Ccm81zN4wSwXRwPSl6MUe7EU1VPgJ7bC0tf/27RURTWRmLLJKsbR4wfZwrQfL/i1KMhFJxiICJQL9ERAgNX/H/h6l4Lpzk5i3tXwTpbrgUPX6J+66rOQuBSwkB/b3eX7ixlapP/Z42UclyxQQMeH3oB2IFtsc3dM7K10FDi/5wCQBOj+709fHKNaRFZRIAl6l1rl4Xkj6+DhXIPw/xwbnNhqE="
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="completed keyexchange"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=debug msg="completed challenge"
Oct 29 14:18:13 volumio go-librespot[16921]: time="2025-10-29T14:18:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:15 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:15 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105.
Oct 29 14:18:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:17 volumio go-librespot[16942]: go-librespot daemon starting...
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="app state loaded"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=info msg="zeroconf server listening on port 39961"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="obtained new client token: AADUaaJQYBCE4zQoEkt/xNWYZR+YKFBBG2LP9ULLln4hvGwGhUF7BeWfDJO8QxK7RTdIEbWuwcOAOz7rucoJhdMtMGji8UR3pLFhNYd6YClrFpM3RyMqEfHV9nRkRGeyg8VXygxVp8z3HXiSh8msO3KBoOjr4OIDF6Y0TK7uuTVd6XWPjFjk8pY7QW0Vq3Ww9wZcQ9pTiDX2qTJgBSglkPSy2XzF7N2TFhes4nANJjkz5TOsUBag"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="completed keyexchange"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=debug msg="completed challenge"
Oct 29 14:18:17 volumio go-librespot[16943]: time="2025-10-29T14:18:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:18 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:18 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106.
Oct 29 14:18:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:20 volumio go-librespot[16951]: go-librespot daemon starting...
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="app state loaded"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=info msg="zeroconf server listening on port 33099"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="obtained new client token: AACp5V6120sxJ6MKiByPWVqRh7KqHfMPbVB2ATly1RNGn4ir5luALNHYAtNSUkUT896ENiAfJwK5h3+EeOg1qNmPsknvR1aUWX+D+iFVIU3jndJIUMew6Sj2Y4fp+f7yoqHviEwecVzGHpTIwgd8UThA5R4her9MCfzzOo/M8HlQAjkRNtMdC1C/1rmin9Nv734FUuLf+lM1fQhn998mx6sBnHRDQMD0wzzczau0zhuvN7e4helcwrU="
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="completed keyexchange"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=debug msg="completed challenge"
Oct 29 14:18:20 volumio go-librespot[16952]: time="2025-10-29T14:18:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:21 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:21 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107.
Oct 29 14:18:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:24 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:24 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:24 volumio go-librespot[16959]: go-librespot daemon starting...
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="app state loaded"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=info msg="zeroconf server listening on port 37471"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="obtained new client token: AAAQugZG5ipFwQf5fjpVumL/VuGujMwQgQWatHnMo7UOptBb1IbNwemRziw+6WkSQILVDldvBU5FGuR1RFB1LcJhFad8aS2W8fc7vO2s60RmryHX9fiLRxbXuByP98iFYAHxYSqFBNuBRWVyb8/qmAeRo6fJswgPgY+yvjqC3kPY5bqWlrT83250PIe/x1pyrKlNHRBBT/mqzGZIAOem8EWzRtT4+8FW+vwKvCbZBfMy0BcSe7TM"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="completed keyexchange"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=debug msg="completed challenge"
Oct 29 14:18:24 volumio go-librespot[16960]: time="2025-10-29T14:18:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:27 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:27 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108.
Oct 29 14:18:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:27 volumio go-librespot[16981]: go-librespot daemon starting...
Oct 29 14:18:27 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="app state loaded"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=info msg="zeroconf server listening on port 36595"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="obtained new client token: AAA2wntYXbq+CjQrGD4D9ZSb4orhiSdpqujQ3rjEnOcPhrJ4L6+GGeWXvcYO9p30gsPZhZsI/G7hrsOPZ7wnNq4F4wGbw5Xs7Qa8mXpqNAQz3UKYnxz5NJrzWp7f3VGt2YFJEkLsDmF/W4SBqj7sBv098xZyYA4NnVPHTofP7gTh7vw4Vw92B7Q3QxuoZIQB+lfAmxT06IpfyCebVWOTlDhhO6CGhCMC953c9DEPgR02zXK+/7hw+Fo="
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="completed keyexchange"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=debug msg="completed challenge"
Oct 29 14:18:27 volumio go-librespot[16982]: time="2025-10-29T14:18:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:27 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Oct 29 14:18:27 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Oct 29 14:18:30 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:30 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109.
Oct 29 14:18:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:31 volumio go-librespot[17015]: go-librespot daemon starting...
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="app state loaded"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=info msg="zeroconf server listening on port 34295"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="obtained new client token: AAA5psA2cefO2ccU+WSylsLluon2wcthyjcNTb1VLbLeq/CUjINdBTyVrvYqyEhf2N3aKXmDtIryH/xQclt/2Er2s2Y5D7zOWV9LsB8b3VnnsPJNQolClbQXEuijnxcZ+yVTDHqyHXJKpkwR1VyXxvwE/n4DGInPvHJsOsa/0786h6P30f26prH9hZxK4jjp74yKS3W515KK7/TSe6lHxVqeEUOy9yPxgFJlbOzcdSM9Eh8X+NDX"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="completed keyexchange"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=debug msg="completed challenge"
Oct 29 14:18:31 volumio go-librespot[17016]: time="2025-10-29T14:18:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:33 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:33 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110.
Oct 29 14:18:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:34 volumio go-librespot[17027]: go-librespot daemon starting...
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="app state loaded"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=info msg="zeroconf server listening on port 44505"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="obtained new client token: AACqM5ULBz79YfAFr2KRTSAcXNSxtF61Fyl4KAtFQlRDs85yAywnh+BG2j89fmNBOBP6yc5HSlGKjkikEkO6KW8M4fMASTmuNH417wN0iIUUYJmG+rZ+hophKuaMdrDyuUkchuhit5MloGnw++hmtWs21e7EPOwhLyl/1jx9UoGad8OT/yn/vo7mir7qcAabTFIPh3M8TRVb1+QqdNI/W1RRiMnsY32G76euGTceeJbc33eSdyaotIQ="
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="completed keyexchange"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=debug msg="completed challenge"
Oct 29 14:18:34 volumio go-librespot[17028]: time="2025-10-29T14:18:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:36 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:36 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111.
Oct 29 14:18:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:38 volumio go-librespot[17049]: go-librespot daemon starting...
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="app state loaded"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=info msg="zeroconf server listening on port 44965"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="obtained new client token: AABhJc/eQ8bJUTFoNywvHdLJZkPcFgtwyHG5fb7wCqf+K5orrbNK6BtBJx7+Zk3IG2pkJA1wmEdll3gvjP9RCErh8cCCnM0EdhimZ5SnRF7YYViD+hQlCDv1E0ZMR9AJy3lEwLb6odhRLTN8TbR6hdT1er+aAgB+mu5DUVhTNO1+QKwOrmPFC85YwJtmPV9yulB67XlEuLbvglfyBH2Ng1A9bWai2f46GMjFlzQ5KBJ5antnzuN+"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="completed keyexchange"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14:18:38Z" level=debug msg="completed challenge"
Oct 29 14:18:38 volumio go-librespot[17050]: time="2025-10-29T14: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 "
Oct 29 14:18:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:39 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:39 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112.
Oct 29 14:18:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:41 volumio go-librespot[17057]: go-librespot daemon starting...
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=debug msg="app state loaded"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14: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]"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14: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]"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14: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]"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=info msg="zeroconf server listening on port 46151"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=debug msg="obtained new client token: AADDnFmeUwHht6EauxVt9fub7dLTT6wkeefaezZilwLmAWY76UYzH0nAvpEUzKElC5sx0u+1wT/FP2taq1fTlX6p20mHJpWUbhNH7DOe11WzRmfVFTm8VyDDYI2/0qXmT+FcL1Hn4NuO7L01XAA9ckmwKfQ/itGhM310oLqJArajG95dp/+Q8z119QeBLAWUdQi+zXrpLm/4BvsQOORPh65otLkX94BtkB3wJTwgTphZjdtxNyO7sYI="
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=debug msg="completed keyexchange"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14:18:41Z" level=debug msg="completed challenge"
Oct 29 14:18:41 volumio go-librespot[17058]: time="2025-10-29T14: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 "
Oct 29 14:18:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:42 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:42 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113.
Oct 29 14:18:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:45 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:45 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:45 volumio go-librespot[17065]: go-librespot daemon starting...
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="app state loaded"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=info msg="zeroconf server listening on port 39563"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="obtained new client token: AAD0de3BK4ECsdSwGYm5grxcqzqhy7I7nkyztTIqcAAiYU+KVUF3JN84GgM2r/CQnQ1ZXUFsj4v5rtF/B5qz6iHplUCLabZenMa8DKyOqdryTCj3K1u95W7zWNqvXQeALmduNZmE7nDhKnrqoaCe8878uO0ZwRll+AswKMjbfb0oxhpUYmxVJ6/CTOUFzbzIKszui0a0wmqzjfuihBN3+moFDWioUCv9cEHbWzl1sQ1Fa//s0b/J"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="completed keyexchange"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14:18:45Z" level=debug msg="completed challenge"
Oct 29 14:18:45 volumio go-librespot[17066]: time="2025-10-29T14: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 "
Oct 29 14:18:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:48 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:48 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114.
Oct 29 14:18:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:48 volumio go-librespot[17087]: go-librespot daemon starting...
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="app state loaded"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=info msg="zeroconf server listening on port 42957"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="obtained new client token: AADChtulGIowiNLR3KwRlRCiqfnBZouMydVP5McWAV7+mx0ByaLCgM3mJbtrYFatg7rZdba7/+NA5iJsM/DCk3AOaGRjKMkPnAzEZHV1J1ZJsyK3cVvRe3E7m6Jg0PTggcf9hI5jSD0CVGntQkYzu3xEvsWUVbeEuDvE7PL5rZXkm6dDlbf7Jcew9bcXHStBrEhDK9bYSjU8hHpZGqVT9djyc8V98iiZxvvOofnADlEz5sT0TmroUac="
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="connected to ap-gew1.spotify.com:443"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="completed keyexchange"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14:18:48Z" level=debug msg="completed challenge"
Oct 29 14:18:48 volumio go-librespot[17088]: time="2025-10-29T14: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 "
Oct 29 14:18:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:51 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:51 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 115.
Oct 29 14:18:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:52 volumio go-librespot[17096]: go-librespot daemon starting...
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="app state loaded"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=info msg="zeroconf server listening on port 37027"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="obtained new client token: AAAAuozpqBoTeyP1GvbeDKOQoSEMfJg2fTVMLbcnvPw7djToD2NXduSry5NkjS4JbDNzb+oumdFRCyOG9oIXYspkGAu7YwjTCzxjPZM3xVUK6TMGLeqkd+vzx5bbVQOGNU/IKegvur92xPWiNphNDSrgfx2vGkAZKFS4YLvROsddufNDcZcO2r1DHTAaKssekzWmK4ViP9O2Q7HX2Z2kSoVz+nYpXHGDj5tPeOdsByHr1ozR85LK"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="completed keyexchange"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14:18:52Z" level=debug msg="completed challenge"
Oct 29 14:18:52 volumio go-librespot[17100]: time="2025-10-29T14: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 "
Oct 29 14:18:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:54 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:54 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 116.
Oct 29 14:18:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:55 volumio go-librespot[17107]: go-librespot daemon starting...
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=debug msg="app state loaded"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14: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]"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14: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]"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14: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]"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=info msg="zeroconf server listening on port 36547"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=debug msg="obtained new client token: AADksDuhtQnwK408pyLexZnbr90qVpTxiuDrKayQMRu317W4gO6r4oy7Clo2EQMqOi52oMI/v/2fTS4htB5OepRLgd+SSuDrfyx5+g6x5B2B54OWsKyFJD18BxE9pZDNovvbbyGgUKwqd7llPbYa1yajCPBjNsKXunxwYdLfFMMUzPzQmkMzrof7EyXXvzOvAG2Krxue/WUFnKe1NEHYIpYDv3b0Is+LrpdqEeF2h3xwNmPsiYAZOfg="
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=debug msg="completed keyexchange"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=debug msg="completed challenge"
Oct 29 14:18:55 volumio go-librespot[17108]: time="2025-10-29T14:18:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:18:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:18:57 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:18:57 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:18:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117.
Oct 29 14:18:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:18:59 volumio go-librespot[17129]: go-librespot daemon starting...
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=debug msg="app state loaded"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=info msg="zeroconf server listening on port 37951"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=debug msg="obtained new client token: AABbOVJcJJGr/hWXbjHR6yJRDXnknakSYcMLa20RiYQpKLiqD+4LQklPk+UFhi0MNHqXFjOIC7il33wFDySKojOIWWslPuSqd+GQuE+Nelryd91KWxec3OOawvdMgUBF37PW2+jTMuHlxhjky+MU7ytbmR+H77WNPoPwH/C+JcEY2jxV750rYOzm8OsIg61e/ir5HzKo6WQ9yX3yrZNU9tG/pcyIgfxfphm8j+Hv0jd97ibvobng"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=debug msg="completed keyexchange"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14:18:59Z" level=debug msg="completed challenge"
Oct 29 14:18:59 volumio go-librespot[17130]: time="2025-10-29T14: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 "
Oct 29 14:18:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:18:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:19:00 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:19:00 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:19:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 118.
Oct 29 14:19:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:19:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:19:02 volumio go-librespot[17137]: go-librespot daemon starting...
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=debug msg="app state loaded"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14: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-gew4.spotify.com:80]"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=info msg="zeroconf server listening on port 42655"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=debug msg="obtained new client token: AABoyU1zql3Oh66wUaUYz9iKM6cVBrxvKSdgPEWIbi6c0+hkKrugK5RChgw+Y8nMNe8x1GKVI9ItcYFDNHBgq+LbwnCrvd4cKCSP7Juzgs7yoNRubJ8BUzRuQ2kYitAA7L5HE7cTHVrnrc1AH6GIgc6XLINnO6TXQ778PfvBma/kc5w4Rxq4UKB6HZz65W4sHnpSJxoRtJsWxYHJev8GYoIJNb9KCFw4yskmMZRdgBOjKAlgflbc7Tc="
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=debug msg="completed keyexchange"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=debug msg="completed challenge"
Oct 29 14:19:02 volumio go-librespot[17138]: time="2025-10-29T14:19:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 29 14:19:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:19:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:19:03 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:19:03 volumio volumio[15170]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:19:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 119.
Oct 29 14:19:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:19:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 29 14:19:06 volumio go-librespot[17145]: go-librespot daemon starting...
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=info msg="running go-librespot 0.4.0"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=debug msg="app state loaded"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 29 14:19:06 volumio volumio[15170]: info: Initializing connection to go-librespot Websocket
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=debug msg="new websocket client"
Oct 29 14:19:06 volumio volumio[15170]: info: Connection to go-librespot Websocket established
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14: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]"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14: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]"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14: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]"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=info msg="zeroconf server listening on port 39017"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=debug msg="obtained new client token: AABL3nIQ/CBq/baVad+yPQy49b0SyeXCr10Q6+d/AjzuiW9x/fm+6Y3HVRWARFNSgiZFv9QUdYBLVvKG4U6T81qqtAKJJUvJPHIFk5HkwSCsYIeDlg339zcxiR2LgK1YERqt4Rre2hSIrIJzF1yOYs45pBDBZS2fQhgI8cQf88pVQ43kmmlKqUYVxOsr4v6th3CMR4H9Fzckikj/Kwc6oG7z06SP7qQyuVdWUyhJYMjuUJnrmTp0"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=debug msg="completed keyexchange"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14:19:06Z" level=debug msg="completed challenge"
Oct 29 14:19:06 volumio go-librespot[17146]: time="2025-10-29T14: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 "
Oct 29 14:19:06 volumio volumio[15170]: info: Connection to go-librespot Websocket closed
Oct 29 14:19:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 29 14:19:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 29 14:19:09 volumio volumio[15170]: info: Getting Spotify volume
Oct 29 14:19:09 volumio volumio[15170]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 29 14:19:09 volumio volumio[15170]: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 29 14:19:09 volumio volumio[15170]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Oct 29 14:19:09 volumio volumio[15170]: errno: -111,
Oct 29 14:19:09 volumio volumio[15170]: code: 'ECONNREFUSED',
Oct 29 14:19:09 volumio volumio[15170]: syscall: 'connect',
Oct 29 14:19:09 volumio volumio[15170]: address: '127.0.0.1',
Oct 29 14:19:09 volumio volumio[15170]: port: 9879,
Oct 29 14:19:09 volumio volumio[15170]: response: undefined
Oct 29 14:19:09 volumio volumio[15170]: }
Oct 29 14:19:09 volumio volumio[15170]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 29 14:19:09 volumio sudo[17181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-29 14:18'
Oct 29 14:19:09 volumio sudo[17181]: 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"