Oct 28 15:13:00 volumio go-librespot[13567]: time="2025-10-28T15:13:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:02 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:02 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Oct 28 15:13:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:03 volumio go-librespot[13575]: go-librespot daemon starting... Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=debug msg="app state loaded" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" 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 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" 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 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" 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 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=info msg="zeroconf server listening on port 34169" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=debug msg="obtained new client token: AAA8sKbqeyR7mOp+a5uxLHOY+APcD2b8sk4P+DsVSHsVy8f0LvBYdVp7ANrGP3gF8ia6K9acBcuIXm1tCYwSTHTdSa3MxgBlnakJzKnQyJc5bVRWPu0avbD65ZMNHPFepWJ4LRd0I0vXwNyT47JWwwp/1atQ5N2WfJr6DFGle/9Cltzk2JY9lb+k+NwMrqUvC595FK47gJxA6AygudYwsmNh0GZJ81BLbbQK57XJwcMw6qXE9sOuhA8=" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=debug msg="completed keyexchange" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13:03Z" level=debug msg="completed challenge" Oct 28 15:13:03 volumio go-librespot[13576]: time="2025-10-28T15:13: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 28 15:13:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:05 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:05 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Oct 28 15:13:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:06 volumio go-librespot[13597]: go-librespot daemon starting... Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=debug msg="app state loaded" Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" 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 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" 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 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" 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 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=info msg="zeroconf server listening on port 41195" Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=debug msg="obtained new client token: AAD1W6JJudd+Afku68H4qdTgnEM8hcpHiazIuGLXNJ1bNV/a4fVQhIaKb4YWZGCGEWbWm7Kn49ZjxerwWKOKlCtAJGhWhX31BGx34T/2WXy9tjpbZDNzxA//sLPXKR8TEX+GFxcr1h6EhtlOBZIC2Y3D+DPpAdNeBGX2RD5hDQHGNl2/C3Bwa5fZEsYJBvCOViyU2ta8NENy8TaHaomuWlkdYowHBsxG0kpLFebAgquBg+YJYM1X5Zk=" Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=debug msg="completed keyexchange" Oct 28 15:13:06 volumio go-librespot[13598]: time="2025-10-28T15:13:06Z" level=debug msg="completed challenge" Oct 28 15:13:07 volumio go-librespot[13598]: time="2025-10-28T15:13:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:08 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:08 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Oct 28 15:13:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:10 volumio go-librespot[13605]: go-librespot daemon starting... Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=debug msg="app state loaded" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13: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 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13: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 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13: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 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=info msg="zeroconf server listening on port 43291" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=debug msg="obtained new client token: AACDSgR7cPZkmxLMGMzlM5dAvWTlWX2pXFMAilE2Fj5mxh+/+sHkR7cklCh0UYv/0dwDXAoJNIhjl6Rxp3smoMQ3PiXDnErLT5ohGoKDPfYgr9msMXzKv41GsaF1Y05nvj2bIMWmOFvjHGWGwh49Eow7rBOu6gEqHn0+E4h8IaMNpzirZd3OLa26r++oNrWL/RpvQqq9Pms1649UtwRSbiN3QZbRcIBigVZB2ZNKKOPd9KN6pjBlWHo=" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=debug msg="completed keyexchange" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13:10Z" level=debug msg="completed challenge" Oct 28 15:13:10 volumio go-librespot[13606]: time="2025-10-28T15:13: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 28 15:13:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:11 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:11 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Oct 28 15:13:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:13 volumio go-librespot[13616]: go-librespot daemon starting... Oct 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" level=debug msg="app state loaded" Oct 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" 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 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" 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 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" 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 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" level=info msg="zeroconf server listening on port 41723" Oct 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" level=debug msg="obtained new client token: AAC6sGjgs5gC1H4PIHKAquMc/ATLmtS6tGGm5RDSr0DbiZTrfNGIaZYz+99GauUeAI1PxxBrjZUKCUGLfksMZ6NG5oj4n8W715OGuE+gD3HYrU52sk0IFozS0JRlHc6rHbcew2910wKEId4J20WL+lShTIqwVV4FX++Tk9rSNstuMtH1nzVeXaCumXEKr5toIJnAU7y+qdDwKrtN8JAdA2n5mHHVsXUonVGGtnlRZCogxvhXUmlQxsc=" Oct 28 15:13:13 volumio go-librespot[13617]: time="2025-10-28T15:13:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:14 volumio go-librespot[13617]: time="2025-10-28T15:13:14Z" level=debug msg="completed keyexchange" Oct 28 15:13:14 volumio go-librespot[13617]: time="2025-10-28T15:13:14Z" level=debug msg="completed challenge" Oct 28 15:13:14 volumio go-librespot[13617]: time="2025-10-28T15:13:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:14 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:14 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:17 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:17 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Oct 28 15:13:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:17 volumio go-librespot[13639]: go-librespot daemon starting... Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=debug msg="app state loaded" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" 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 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" 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 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" 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 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=info msg="zeroconf server listening on port 44377" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=debug msg="obtained new client token: AABo+XB4AHdfVa7yodCBYKuyCmSeAP9bBPwhsiB1Iu6LB0LpSPtrJlfDSf62RVQ3Mfe53e/JSZtxYsvQ/rgV5n1rxDr5SRzxeMdnrmVTOGR8uKsJ1BrCGiOrzQkhc+AHGlK8RF9udoUqoJp7/aZ2C8P4MWQPPDmqTPXNebzwjxJGknyBffuml8Mzu6+Dff+S2IOKw977SWNqOFJzQQFpUCUOUgwToqG5YTVkPWbI+JiVMtpiYcaVePk=" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=debug msg="completed keyexchange" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13:17Z" level=debug msg="completed challenge" Oct 28 15:13:17 volumio go-librespot[13640]: time="2025-10-28T15:13: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 28 15:13:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:20 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:20 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Oct 28 15:13:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:20 volumio go-librespot[13648]: go-librespot daemon starting... Oct 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" level=debug msg="app state loaded" Oct 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" 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 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" 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 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" 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 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" level=info msg="zeroconf server listening on port 44155" Oct 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" level=debug msg="obtained new client token: AABPj9TrrCe21buWHBfUcG1V9d/9SqfHqhLkPnxc9P5GVodoZfHtkyQ+hzrj+oqU4MCI7HqITrPLsPlJpFfj4o6xVmP0gxe8rPn77PwempNMIIdKMMU77syUGbEzhQTzg301GILWaJQ9jXxrnj+FoZ4mIHgOTPuemfMfw2lvTX4Os/UofeV/DXTHxzdTNHlXnj6rp7RoJ73xh667trkvEBric+pqsCt1u0ZldzB+ptk4i/cts4vTuHM=" Oct 28 15:13:20 volumio go-librespot[13649]: time="2025-10-28T15:13:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:21 volumio go-librespot[13649]: time="2025-10-28T15:13:21Z" level=debug msg="completed keyexchange" Oct 28 15:13:21 volumio go-librespot[13649]: time="2025-10-28T15:13:21Z" level=debug msg="completed challenge" Oct 28 15:13:21 volumio go-librespot[13649]: time="2025-10-28T15:13:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:23 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:23 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Oct 28 15:13:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:24 volumio go-librespot[13656]: go-librespot daemon starting... Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=debug msg="app state loaded" Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" 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 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" 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 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" 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 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=info msg="zeroconf server listening on port 36605" Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=debug msg="obtained new client token: AAARmgwICax2nowVHtlzKhCP6uAekEjxy+bfrgNwuJbhr2GhpQiEXfIcI9m3QZQp9agaKwr6NXhNMz9BAg0cEyEZSiatvAc7D13oJDrUnzmRszzKj4a0oQXBDzmHyH2s4RO57McLmU1CAjsyaQ8wbJuHPxq1O7Lv8QmG6PIWKlqv6S1In5mQRPJUagQm+mi6Y9K2ih092pA3YHFGvoztvrLrGDqpKaMfBtRMeoa1IQfSuac/zOHb6nU=" Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=debug msg="completed keyexchange" Oct 28 15:13:24 volumio go-librespot[13657]: time="2025-10-28T15:13:24Z" level=debug msg="completed challenge" Oct 28 15:13:25 volumio go-librespot[13657]: time="2025-10-28T15:13:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:26 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:26 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Oct 28 15:13:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:28 volumio go-librespot[13678]: go-librespot daemon starting... Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=debug msg="app state loaded" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" 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 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" 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 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" 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 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=info msg="zeroconf server listening on port 38873" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=debug msg="obtained new client token: AADMbgJuSppDyVjnoIHydZvj5xw2koSAAS+FBfnixJ06jF0795ncp3OdMhf8nAUnpAL4WltwNtfGwrVr5nYJKzC0jyDuHfz7CfW9rZcG1pARC8y/DmMCz6NkerOYX9E3vV5KkfAcIKFfttrQOcmKNIlESjKA2DIXgwa0u9r/+ELyTtA+SdRj1sFEC5jnoB9hfDDYC/UWx+mSc5wy/WDkA6s21yX0JGlZZCHhNinwzHa1Hq8zNbeO3sA=" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=debug msg="completed keyexchange" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=debug msg="completed challenge" Oct 28 15:13:28 volumio go-librespot[13679]: time="2025-10-28T15:13:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:29 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:29 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Oct 28 15:13:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:31 volumio go-librespot[13686]: go-librespot daemon starting... Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=debug msg="app state loaded" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" 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 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" 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 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=info msg="zeroconf server listening on port 41757" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=debug msg="obtained new client token: AACZkwlT0nOiup92Y3Naif2LVPPNA8dOf8SicKtVrK4XSxKxsM0sZv6tCQ2oOx+phKRuOY7cVxdhLFc768CmoyhYQZVULHQ2RBkZRlk1a0WRkkKtkCF1+TE5Gty33TVVMa/bK4TWLL1P8gjniIAucyg+QEhAVJvxe4HsGq4KAcr1MJW2ZIeXULUEQ/wXrSyuLPR2+Rpp0f7ad7VLYjFbUDSYV8TYB5tHjwSuFWTTsAUXv+iC+n5EV/8=" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=debug msg="completed keyexchange" Oct 28 15:13:31 volumio go-librespot[13687]: time="2025-10-28T15:13:31Z" level=debug msg="completed challenge" Oct 28 15:13:32 volumio go-librespot[13687]: time="2025-10-28T15:13:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:32 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:32 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:35 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:35 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Oct 28 15:13:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:35 volumio go-librespot[13698]: go-librespot daemon starting... Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=debug msg="app state loaded" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" 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 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" 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 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" 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 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=info msg="zeroconf server listening on port 40859" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=debug msg="obtained new client token: AABzSA308xlxNYe0vwTheTukdPQIt8qE70dj+qGUgrWJTJpsTIDIoB7yhiJa6eS3tayGawKW8sTwZfOu8YI7FCMA09z3aHcdCfDosdepZOVRQBEmaXUrYNKX42w9yQARpcfsyAr/QWR9f9HGToHmZWr9r3iSeSe+tZ9doeciYwK32MBJojB+5klcYIXizpAX0GxwpK9wsCrTPItt1r5bhwNXqmUwhZqjhlhGK6dz02KPhMp1TB9oBkA=" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=debug msg="completed keyexchange" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=debug msg="completed challenge" Oct 28 15:13:35 volumio go-librespot[13699]: time="2025-10-28T15:13:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:38 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:38 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Oct 28 15:13:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:38 volumio go-librespot[13720]: go-librespot daemon starting... Oct 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" level=debug msg="app state loaded" Oct 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" 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 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" 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 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" 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 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" level=info msg="zeroconf server listening on port 35085" Oct 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" level=debug msg="obtained new client token: AACN7qQ9yTGoBI6h2gUCucbR8WZifFAbaEBsSoK6h2Z70wA5P9Qd6+JJyD4qvIlTgVmVGwa9dOifvoJDagQQJNYbGAJL2VH0jPLYiaWNS8rcQyy38+/kbp04NtrKH7YlwwrIKvF74XSVMoDWN/Y//x/TbtcoCQgHOEUc99NgA2yl9/YysQYuj/HzN4VMcHT5adRnFaXJkg/zKJadtLysk/Jle/Y0bUEPL2OnnN2jU78PebnKASZIkZU=" Oct 28 15:13:38 volumio go-librespot[13721]: time="2025-10-28T15:13:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:39 volumio go-librespot[13721]: time="2025-10-28T15:13:39Z" level=debug msg="completed keyexchange" Oct 28 15:13:39 volumio go-librespot[13721]: time="2025-10-28T15:13:39Z" level=debug msg="completed challenge" Oct 28 15:13:39 volumio go-librespot[13721]: time="2025-10-28T15:13:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:41 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:41 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Oct 28 15:13:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:42 volumio go-librespot[13729]: go-librespot daemon starting... Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=debug msg="app state loaded" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" 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 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" 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 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" 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 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=info msg="zeroconf server listening on port 37273" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=debug msg="obtained new client token: AADa8pWTS7PvCw7c3THXGtcTlBi7kZWE5s4m9xg9NzfjMT2vyE3syiRhyvA9FJQV61YrOU6TuLYI8mhrw1RYoAWo0PObJdKYg2809CdSzVLV6Dr2Kg334Lll4F78lVan87vO9CCyg0NcYScrOlNiewTK8JkyyzoxOr9zGt0JRVmRHaqsRIDZX8CPT29aLspk+3dt91isaYxnPOkBtn6QHyZ2y3+eEFhOa5uT0LWTtKm9kW9F/Oushgc=" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=debug msg="completed keyexchange" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=debug msg="completed challenge" Oct 28 15:13:42 volumio go-librespot[13730]: time="2025-10-28T15:13:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:44 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:44 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Oct 28 15:13:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:45 volumio go-librespot[13737]: go-librespot daemon starting... Oct 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" level=debug msg="app state loaded" Oct 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" 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 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" 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 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" 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 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" level=info msg="zeroconf server listening on port 40595" Oct 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" level=debug msg="obtained new client token: AAC/h1LzxvKrgkGfYfI6GwgtkIKMF1LEoa9qJXOpPTq/3RxNkXHAIJPLkUpozX9VvvkB0upxU83+Vv/FkB3RA4URibTZjTJTNwNLb+NSZKs8m+YmaY2VPkliDjqET76Url7pnFHsZntoWDW+u68KqxiPI6yLRQHElSNz+Lfv+hSsOU/DUUrPpawS87wr27vQULjLkhbbGpCTLfJjnZX0flvjNrXArm7cBR4FHC+QuKSmFBTXMU6p198=" Oct 28 15:13:45 volumio go-librespot[13738]: time="2025-10-28T15:13:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:46 volumio go-librespot[13738]: time="2025-10-28T15:13:46Z" level=debug msg="completed keyexchange" Oct 28 15:13:46 volumio go-librespot[13738]: time="2025-10-28T15:13:46Z" level=debug msg="completed challenge" Oct 28 15:13:46 volumio go-librespot[13738]: time="2025-10-28T15:13:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:47 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:47 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Oct 28 15:13:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:49 volumio go-librespot[13759]: go-librespot daemon starting... Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=debug msg="app state loaded" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" 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 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" 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 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" 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 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=info msg="zeroconf server listening on port 35593" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=debug msg="obtained new client token: AAClDQbNRP+0MTRyjakik0nt3GkluF4sK8f7Suz09OGO9FzJ11kCP7eBlAWLW0SYmLk7+3XxGPcU9mfSRuPtY+HyUKuyqpsrJRQKdwBPkvMXCK9SLUoom71WOZyTq+71gEOqkjh2iP+z3DgV0CFEeUyzVCxDmtfJPYI2oB4OHmtHR8RNR4k8abk7rs+yU7/l5fOnEf+C1YL/bWjV3TSCP902iIhcCMurJhNZdsWomCsSl+h6FJ6cgbk=" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=debug msg="completed keyexchange" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=debug msg="completed challenge" Oct 28 15:13:49 volumio go-librespot[13760]: time="2025-10-28T15:13:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:50 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:50 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Oct 28 15:13:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:52 volumio go-librespot[13770]: go-librespot daemon starting... Oct 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13:52Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13:52Z" level=debug msg="app state loaded" Oct 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13:52Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13:52Z" 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 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13:52Z" 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 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13:52Z" level=info msg="zeroconf server listening on port 41381" Oct 28 15:13:52 volumio go-librespot[13771]: time="2025-10-28T15:13:52Z" level=debug msg="obtained new client token: AADVTH1lZUS1AU5LxR3Taiu3z5dfJDSb4FkIwuJwFLNDXqvaiGVVOVnUqvu324UYSBpVyX5VJJLRvDT/A1gxPFPit1oOjeNsxeJ4DprompLGOu6tvVLPNaq+CRx0lLW8j6QVFNi/ria7IHDtD2W93QTuMAeG05K3HQGh1XSGAjmBLxotzqY8Fv5GgPtpOS2rRjzhtDj/+DVUPALj8pzjrS+/DqZlwknx1CcCevWOQPnlMfmkLbwQB1U=" Oct 28 15:13:53 volumio go-librespot[13771]: time="2025-10-28T15:13:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:53 volumio go-librespot[13771]: time="2025-10-28T15:13:53Z" level=debug msg="completed keyexchange" Oct 28 15:13:53 volumio go-librespot[13771]: time="2025-10-28T15:13:53Z" level=debug msg="completed challenge" Oct 28 15:13:53 volumio go-librespot[13771]: time="2025-10-28T15:13:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:53 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:53 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Oct 28 15:13:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:56 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:56 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:56 volumio go-librespot[13778]: go-librespot daemon starting... Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="app state loaded" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=info msg="zeroconf server listening on port 45141" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="obtained new client token: AAB0AtMec2wT94us/yW6QY0pKx5GClOWjxYTRDj4C8SVFnTstM7BblcKTbr3XGiepEE8Ltbs3J3l25RiMTWn2duvN1p+rHcPHHBI7uRNcNNzB3t37u3xKSKXVwKL9qzwzF+czNL1Jrl1+lbXFwtfcTW7mV3/sd2900HkftLLQe7tkc75W2RO+fmZTLwUJboqZGPX8IJzP4/0mDXT7420yR/klr/tkQxp/fJ5xB0IMWgYpjNnXXiIjwM=" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="completed keyexchange" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=debug msg="completed challenge" Oct 28 15:13:56 volumio go-librespot[13779]: time="2025-10-28T15:13:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:13:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:13:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:13:59 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:13:59 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:13:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Oct 28 15:13:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:13:59 volumio go-librespot[13800]: go-librespot daemon starting... Oct 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13:59Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13:59Z" level=debug msg="app state loaded" Oct 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13:59Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13: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 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13: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 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13: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 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13:59Z" level=info msg="zeroconf server listening on port 41465" Oct 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13:59Z" level=debug msg="obtained new client token: AAA8w92XAOVfOKiJWLCsjPwAvSLB7AbU+rIh/MzCQtljxSpMSO+P/KwYE1TYtN5U9dPtxXY8djhA5TrCl5esM0n3YYqswg9mjRs5UuuNlErfme3UbXg/EL/ytakItpa1PQd4fh7VBDIaL7mxRGSK/gQK1BgAEafpKEKWTdOAKeVt+Xolzxam++JXeWPoie5SGtizMxI6xzlvhUtbgZmTrKKK7eKAys4bDshOmDAep94HMTX5vfBIFtw=" Oct 28 15:13:59 volumio go-librespot[13801]: time="2025-10-28T15:13:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:00 volumio go-librespot[13801]: time="2025-10-28T15:14:00Z" level=debug msg="completed keyexchange" Oct 28 15:14:00 volumio go-librespot[13801]: time="2025-10-28T15:14:00Z" level=debug msg="completed challenge" Oct 28 15:14:00 volumio go-librespot[13801]: time="2025-10-28T15:14:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:14:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:02 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:02 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Oct 28 15:14:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:03 volumio go-librespot[13810]: go-librespot daemon starting... Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=debug msg="app state loaded" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14: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 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14: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 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14: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 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=info msg="zeroconf server listening on port 38969" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=debug msg="obtained new client token: AACaN+RJP9EfpTaRTwZsDObGkI/NhkLX5Is3yYWVU635cwy/Ev5ha3s1btoMhR4PYDuuQq19mOuFQKSxk6cz/wSA35HRZCh06eT9LG6uavy1DYWn/7KLRZsnrc7PMydyqKAHJvkeFzvpd/XQEblikP1Ey6ysHCwF2RF3uo8DyAEP0eqgtF6cgUgO68aaJ0y9lf0yiAB8Q8FJxxoFqlcsiysYR8uvF3VCoSVEavq7csSuJVAkmHoeWW4=" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=debug msg="completed keyexchange" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14:03Z" level=debug msg="completed challenge" Oct 28 15:14:03 volumio go-librespot[13811]: time="2025-10-28T15:14: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 28 15:14:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:05 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:05 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Oct 28 15:14:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:06 volumio go-librespot[13832]: go-librespot daemon starting... Oct 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" level=debug msg="app state loaded" Oct 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" 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 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" 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 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" 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 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" level=info msg="zeroconf server listening on port 36799" Oct 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" level=debug msg="obtained new client token: AAArXAjdBHuXUPSbPHNicJUPahzAiKgYx6qQq6ftqu8ZmWnvT4kOCuGcycwqXlJCxBd10K73SpwB4gEHnNxyHt6JBfWKgTrVxdbv/axaLNABmMwhVRVBpnUmBkgF0JhJvZIOZkpDXFDGaJScA699enwK3bCK1LVUskf8mzjOomBkKt3CREUxJ94nE/Qq4fl5TSM6IvXUsZLZRQZGasqh1KLMNaqDgCdz963yIl/LbCIhbaDhVwWNkm4=" Oct 28 15:14:06 volumio go-librespot[13833]: time="2025-10-28T15:14:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:07 volumio go-librespot[13833]: time="2025-10-28T15:14:07Z" level=debug msg="completed keyexchange" Oct 28 15:14:07 volumio go-librespot[13833]: time="2025-10-28T15:14:07Z" level=debug msg="completed challenge" Oct 28 15:14:07 volumio go-librespot[13833]: time="2025-10-28T15:14:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:14:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:08 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:08 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Oct 28 15:14:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:10 volumio go-librespot[13840]: go-librespot daemon starting... Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=debug msg="app state loaded" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" 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 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" 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 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" 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 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=info msg="zeroconf server listening on port 39401" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=debug msg="obtained new client token: AAC/BnMUbzT1qmgpA8b915P5JhFQJX5mNyy8vZavpKyaGT4EBQPJRfZpPvu8de+aPorx3gQZ8TZ3Pz3MKA0L2W7uA8pPb/U/eO8Jwe7YoQPLNtpUYHcShgsYeaC0jdPufEQNJ7eg0R7pKWefbJ2f0L9g3UGI0gR9lMDdlAmIqd0YOtXc9AhkAVQVgXw1aQM6i9ym7AfKEay+Tja5I8sStTYHfE2I8gWahefJ2W2XOyAuLwnUaOCbqZ4=" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=debug msg="completed keyexchange" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14:10Z" level=debug msg="completed challenge" Oct 28 15:14:10 volumio go-librespot[13841]: time="2025-10-28T15:14: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 28 15:14:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:11 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:11 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Oct 28 15:14:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:13 volumio go-librespot[13851]: go-librespot daemon starting... Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=debug msg="app state loaded" Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" 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 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" 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 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" 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 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=info msg="zeroconf server listening on port 36579" Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=debug msg="obtained new client token: AACWaJiyF2rjTfEXnfWCDUkUzOnrfCqpS/DKpqlry+aeJNxrlqACWqTrLlHTVdndC2D277Cb79vWF+3qWeJcGVkjLnYIhwb4//ydOEdVswfpm/m6r0vMfE5PRSdmkKU9OklvmHwXuNIKC0mBbCZ/kHN+eO/5Wez8QllecVNZq7SDULFpUY+8pRBW+V+vpnZ3PrRcy/desxJZoGf1XBWkW3AQjUVUMLgkvp4YWB4N/fM/6i9Xc7fZgc8=" Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=debug msg="completed keyexchange" Oct 28 15:14:13 volumio go-librespot[13852]: time="2025-10-28T15:14:13Z" level=debug msg="completed challenge" Oct 28 15:14:14 volumio go-librespot[13852]: time="2025-10-28T15:14:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:14:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:14 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:14 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Oct 28 15:14:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:17 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:17 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:17 volumio go-librespot[13873]: go-librespot daemon starting... Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=debug msg="app state loaded" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14: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 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14: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 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14: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 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=info msg="zeroconf server listening on port 36877" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=debug msg="obtained new client token: AACRvUm4y58FS0Zbnh6CZwa4KBUjB2EXp9vqrnl1kFjc0kxhOrvwqikU8Z6zsXpDb3vvo6EVZ9lZjodPxEsu3sy0ihNU2s6E97wxKPiLDn8ml+f8tUVDIbZHjanULQyWjdlSwBThSD4kxJSr//U348X7A5BpgpTt+yukI99ijDM5A8IubS4DIR6XZSQ5Ze+7ft4ns7ZUmAyJXYxheQexNB1dtsQzhEkt/AsHD2pn4x9oGMJj4MrVVUQ=" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=debug msg="completed keyexchange" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14:17Z" level=debug msg="completed challenge" Oct 28 15:14:17 volumio go-librespot[13874]: time="2025-10-28T15:14: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 28 15:14:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:20 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:20 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Oct 28 15:14:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:20 volumio go-librespot[13882]: go-librespot daemon starting... Oct 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14:20Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14:20Z" level=debug msg="app state loaded" Oct 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14: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 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14: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 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14: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 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14:20Z" level=info msg="zeroconf server listening on port 40281" Oct 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14:20Z" level=debug msg="obtained new client token: AAAkbGpNVAvf/nTkdAfh4QCFJgxIy2L39ePuSe3oMKwrQjmOXZ6AB4ZHlb1dfMPkHpVIvIoEcoR84BdJypAOfJVamA6CbmTk0lTlXnufAePnQuAi4CkX6JRwzUH49Q6kX1wGRMW+jiIkE7xS4tVEDD3d/n05LOA8L+CLlMS3BwBD+kbtYR6dckNGabSnsc51+72CHfgwDuTEjsLgZ5kmZB1s9sch9GRM04EmqO0APYoNQCEpeCx/CTY=" Oct 28 15:14:20 volumio go-librespot[13883]: time="2025-10-28T15:14:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:21 volumio go-librespot[13883]: time="2025-10-28T15:14:21Z" level=debug msg="completed keyexchange" Oct 28 15:14:21 volumio go-librespot[13883]: time="2025-10-28T15:14:21Z" level=debug msg="completed challenge" Oct 28 15:14:21 volumio go-librespot[13883]: time="2025-10-28T15:14:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:14:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:23 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:23 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Oct 28 15:14:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:24 volumio go-librespot[13891]: go-librespot daemon starting... Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=debug msg="app state loaded" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" 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 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" 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 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" 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 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=info msg="zeroconf server listening on port 40669" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=debug msg="obtained new client token: AABv5+ST/1HdR6C0VkwL9GSYf4VLXBG3++/MEMLGwULv3uO4qCplKjZy1kkNYy2FrqCsY4HDi42XhBIBqLNDN61Umx82z2f2xkcsFKPoHMGFQJwi3fpGwTe3VYTYjJ4IFAeNd3NLnFJDkgz5vDKlt93wgfLcTMGLnyaBOUkdG814tRRpDaBbuqPPqhfsuzW5BpyE48xX037vAVpbAp7TlpY/xPKU/Jm0G8RDksccR+eAPU/Xs5OUXLk=" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=debug msg="completed keyexchange" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14:24Z" level=debug msg="completed challenge" Oct 28 15:14:24 volumio go-librespot[13892]: time="2025-10-28T15:14: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 28 15:14:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:24 volumio kernel: usb 3-1: USB disconnect, device number 19 Oct 28 15:14:25 volumio kernel: usb 3-1: new high-speed USB device number 20 using xhci-hcd Oct 28 15:14:25 volumio kernel: usb 3-1: New USB device found, idVendor=262a, idProduct=13de, bcdDevice= 0.01 Oct 28 15:14:25 volumio kernel: usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 28 15:14:25 volumio kernel: usb 3-1: Product: ZDC-205A-SG Oct 28 15:14:25 volumio kernel: usb 3-1: Manufacturer: Covia Inc. Oct 28 15:14:25 volumio kernel: hid-generic 0003:262A:13DE.0013: No inputs registered, leaving Oct 28 15:14:25 volumio kernel: hid-generic 0003:262A:13DE.0013: hidraw0: USB HID v1.00 Device [Covia Inc. ZDC-205A-SG] on usb-xhci-hcd.1-1/input0 Oct 28 15:14:25 volumio volumio[12889]: info: Oct 28 15:14:25 volumio volumio[12889]: ---------------------------- USB Audio Device Detached Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 28 15:14:25 volumio (udev-worker)[13901]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Oct 28 15:14:25 volumio volumio[12889]: info: Oct 28 15:14:25 volumio volumio[12889]: ---------------------------- USB Audio Device Attached Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::Close All Modals sent Oct 28 15:14:25 volumio volumio[12889]: info: Preparing to save Alsa Options, stopping services first Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::volumioGetState Oct 28 15:14:25 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::volumioPause Oct 28 15:14:25 volumio volumio[12889]: info: CoreStateMachine::pause Oct 28 15:14:25 volumio volumio[12889]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"ZDC-205A-SG","alsacard":"ZDC205ASG"},"i2s":false} Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 15:14:25 volumio volumio[12889]: info: Setting mixer PCM for card ZDC-205A-SG Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 28 15:14:25 volumio volumio[12889]: info: Updating Volume Controller Parameters: Device: 5 Name: ZDC-205A-SG Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 28 15:14:25 volumio volumio[12889]: info: Disabling external Volume Control Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 15:14:25 volumio volumio[12889]: info: Preparing to generate the ALSA configuration file Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 28 15:14:25 volumio volumio[12889]: info: Asound.conf file unchanged, so no further update is needed Oct 28 15:14:25 volumio volumio[12889]: info: Output device has changed, restarting MPD Oct 28 15:14:25 volumio volumio[12889]: info: Output device has changed, restarting Shairport Sync Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 15:14:25 volumio sudo[13937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 28 15:14:25 volumio sudo[13937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 15:14:25 volumio sudo[13937]: pam_unix(sudo:session): session closed for user root Oct 28 15:14:25 volumio sudo[13941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 28 15:14:25 volumio sudo[13941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 15:14:25 volumio volumio[12889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 15:14:25 volumio volumio[12889]: info: MPD Permissions set Oct 28 15:14:25 volumio volumio[12889]: info: VolumeController:: Volume=33 Mute =false Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::volumioGetState Oct 28 15:14:25 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:25 volumio volumio[12889]: info: CoreStateMachine::pushState Oct 28 15:14:25 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::volumioPushState Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::volumioGetState Oct 28 15:14:25 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:25 volumio volumio[12889]: info: Starting Shairport Sync Oct 28 15:14:25 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 28 15:14:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 28 15:14:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 28 15:14:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 28 15:14:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 28 15:14:25 volumio volumio[12889]: error: [ytcr] Error connecting MPD: Oct 28 15:14:25 volumio volumio[12889]: (Error) read ECONNRESET Oct 28 15:14:25 volumio volumio[12889]: Error: read ECONNRESET Oct 28 15:14:25 volumio volumio[12889]: at Pipe.onStreamRead (node:internal/stream_base_commons:217:20) Oct 28 15:14:25 volumio volumio[12889]: Retrying in 5 seconds... Oct 28 15:14:25 volumio sudo[13952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 15:14:25 volumio sudo[13952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 15:14:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 28 15:14:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 28 15:14:25 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 28 15:14:25 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 28 15:14:25 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 28 15:14:25 volumio systemd[1]: shairport-sync.service: Consumed 1.615s CPU time. Oct 28 15:14:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 28 15:14:25 volumio sudo[13952]: pam_unix(sudo:session): session closed for user root Oct 28 15:14:25 volumio volumio[12889]: info: Shairport-Sync Started Oct 28 15:14:25 volumio sudo[13955]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 28 15:14:25 volumio sudo[13955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 28 15:14:25 volumio sudo[13955]: pam_unix(sudo:session): session closed for user root Oct 28 15:14:25 volumio volumio[12889]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 28 15:14:25 volumio mpd[13972]: 2025-10-28T15:14:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 28 15:14:25 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 28 15:14:25 volumio sudo[13941]: pam_unix(sudo:session): session closed for user root Oct 28 15:14:25 volumio volumio[12889]: error: updateQueue error: null Oct 28 15:14:26 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:26 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Oct 28 15:14:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:27 volumio go-librespot[13989]: go-librespot daemon starting... Oct 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" level=debug msg="app state loaded" Oct 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" 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 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" 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 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" level=info msg="zeroconf server listening on port 34293" Oct 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" level=debug msg="obtained new client token: AAA1rzHZxY2A2jaKlj3ZsIMQABGOizzkhoT0XWupxlnFyX8ZGUlGBKiYsTQuTex5e+aX4jJufMODA+CGwG6Ar1wJtiDK60vS8W/UrHLCSAzRs7tXM2AGvIGPrC3pcHV2dh8AsZ1KNG3wn34ViewQrs+e+RxWlIln0viqBlFTJYaagOMPqNfM9TRQ7f+D7stFLL7M+lJ0DJrEiVt8F7Lvd/6c4UXod5E7fduTI5h5C1BjPrlIHIGhu54=" Oct 28 15:14:27 volumio go-librespot[13990]: time="2025-10-28T15:14:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:28 volumio go-librespot[13990]: time="2025-10-28T15:14:28Z" level=debug msg="completed keyexchange" Oct 28 15:14:28 volumio go-librespot[13990]: time="2025-10-28T15:14:28Z" level=debug msg="completed challenge" Oct 28 15:14:28 volumio go-librespot[13990]: time="2025-10-28T15:14:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:14:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:28 volumio sudo[13998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 28 15:14:28 volumio sudo[13998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 15:14:28 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 28 15:14:28 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 28 15:14:28 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 28 15:14:28 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 28 15:14:29 volumio mpd_monitor.sh[14000]: MPD Monitor Service: Starting MPD Monitor Service Oct 28 15:14:29 volumio sudo[13998]: pam_unix(sudo:session): session closed for user root Oct 28 15:14:29 volumio volumio[12889]: info: Successfully started MPD Monitor Oct 28 15:14:29 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:29 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:29 volumio volumio[12889]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 28 15:14:29 volumio volumio[12889]: info: CoreStateMachine::getcurrentVolume Oct 28 15:14:29 volumio volumio[12889]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 15:14:29 volumio volumio[12889]: info: VolumeController:: Volume=33 Mute =false Oct 28 15:14:29 volumio volumio[12889]: info: CoreCommandRouter::volumioGetState Oct 28 15:14:29 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:29 volumio volumio[12889]: info: CoreStateMachine::pushState Oct 28 15:14:29 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:29 volumio volumio[12889]: info: CoreCommandRouter::volumioPushState Oct 28 15:14:29 volumio volumio[12889]: info: CoreStateMachine::updateTrackBlock Oct 28 15:14:29 volumio volumio[12889]: info: CorePlayQueue::getTrackBlock Oct 28 15:14:29 volumio volumio[12889]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 15:14:29 volumio volumio[12889]: info: VolumeController:: Volume=33 Mute =false Oct 28 15:14:29 volumio volumio[12889]: info: CoreCommandRouter::volumioGetState Oct 28 15:14:29 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:29 volumio volumio[12889]: info: CoreStateMachine::pushState Oct 28 15:14:29 volumio volumio[12889]: info: CorePlayQueue::getTrack 0 Oct 28 15:14:29 volumio volumio[12889]: info: CoreCommandRouter::volumioPushState Oct 28 15:14:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Oct 28 15:14:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:31 volumio go-librespot[14008]: go-librespot daemon starting... Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=debug msg="app state loaded" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" 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 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" 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 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=info msg="zeroconf server listening on port 38215" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=debug msg="obtained new client token: AADGAlZWXColo7mHAAy8wza59P1n8Kk7YFsDN69lPdLhTpQAElTilzrD9EwfqxJ+HRbaePx24m/2upa2wZx5ILDiakdW7oB0S5YJ72lZSreiA2McgEFjF+/d8Fop/iwqtKnWh2z5gpVyXFbvoIk5mGpqzcB7ZEXZsikLH67EPDokySX3KkbP8rlFwzYt01lYJ7FRfE/Tss1arFZ0VWf0URNxrfifFpVoAeY3L5lfKamQHZrbUR3bRXY=" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=debug msg="completed keyexchange" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14:31Z" level=debug msg="completed challenge" Oct 28 15:14:31 volumio go-librespot[14009]: time="2025-10-28T15:14: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 28 15:14:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:32 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:32 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Oct 28 15:14:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:34 volumio go-librespot[14016]: go-librespot daemon starting... Oct 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" level=debug msg="app state loaded" Oct 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" 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 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" 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 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" 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 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" level=info msg="zeroconf server listening on port 40145" Oct 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" level=debug msg="obtained new client token: AACknLFMme80LIVjUVkEY/xBzVhw0mPkEMMX4UC7tXW7dV4GdpPx/kKBVjvy799RWmabUliixBURNLQ+yr1Skrz8F3MBJb03GKKSZl5fDyRBtn5e7zPO16JPtZmPUR+P92CyAQocUOF1Nb4itnW2uva0cdA46OOYjSgBRmv2yqY8eg3PImHQB1aR2lFiR9wTNLieXL4+VMzYDwHLPFe2O5nTGunZppZpyqZSrQyNVexQwT5rlNDBC1c=" Oct 28 15:14:34 volumio go-librespot[14017]: time="2025-10-28T15:14:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 15:14:35 volumio go-librespot[14017]: time="2025-10-28T15:14:35Z" level=debug msg="completed keyexchange" Oct 28 15:14:35 volumio go-librespot[14017]: time="2025-10-28T15:14:35Z" level=debug msg="completed challenge" Oct 28 15:14:35 volumio go-librespot[14017]: time="2025-10-28T15:14:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 15:14:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:35 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:35 volumio volumio[12889]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Oct 28 15:14:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 15:14:38 volumio go-librespot[14038]: go-librespot daemon starting... Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=info msg="running go-librespot 0.4.0" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=debug msg="app state loaded" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 15:14:38 volumio volumio[12889]: info: Initializing connection to go-librespot Websocket Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=debug msg="new websocket client" Oct 28 15:14:38 volumio volumio[12889]: info: Connection to go-librespot Websocket established Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14: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-gew4.spotify.com:80]" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" 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 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" 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 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=info msg="zeroconf server listening on port 44097" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=debug msg="obtained new client token: AAAon18/E41MmdZgkL0YhALeyIgA71pNHGVsH81mGPVxysqUwZTT2G6cu2XFGFTui/SHF4Vvnph9XABn+hDPxuxCHPSfo9iKFUqWTTb4yoIBsB0bKTHVvban6zOXUyAUkPikpdjZyBTWHDX2YoAalSC/L3O+ya65mN0g4Z6xmh3OWIqs93wZ5lNKD2EcBStrPcLRuIT35IVfZWa+Bm50SqlKPFaYjZhEEhged7Dv7YfnrdBJLefcYBg=" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" 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 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=debug msg="completed keyexchange" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14:38Z" level=debug msg="completed challenge" Oct 28 15:14:38 volumio go-librespot[14039]: time="2025-10-28T15:14: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 28 15:14:38 volumio volumio[12889]: info: Connection to go-librespot Websocket closed Oct 28 15:14:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 15:14:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 15:14:41 volumio volumio[12889]: info: Getting Spotify volume Oct 28 15:14:41 volumio volumio[12889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 15:14:41 volumio volumio[12889]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 15:14:41 volumio volumio[12889]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 28 15:14:41 volumio volumio[12889]: errno: -111, Oct 28 15:14:41 volumio volumio[12889]: code: 'ECONNREFUSED', Oct 28 15:14:41 volumio volumio[12889]: syscall: 'connect', Oct 28 15:14:41 volumio volumio[12889]: address: '127.0.0.1', Oct 28 15:14:41 volumio volumio[12889]: port: 9879, Oct 28 15:14:41 volumio volumio[12889]: response: undefined Oct 28 15:14:41 volumio volumio[12889]: } Oct 28 15:14:41 volumio volumio[12889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 15:14:41 volumio sudo[14061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 15:13' Oct 28 15:14:41 volumio sudo[14061]: 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"