Oct 27 22:36:02 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:02 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96. Oct 27 22:36:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:02 volumio go-librespot[27849]: go-librespot daemon starting... Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=debug msg="app state loaded" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=info msg="zeroconf server listening on port 45535" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=debug msg="obtained new client token: AAADRTUocMwW7a6HDgOvNcba9n9ZjsgWqt38BCrGhts7oh+FTDreLNQTuCtx1ScebpbuhCZbH7eWy+LDA2Sli+cPFcz27jPEeUQgzm8vibLiG0ovE+PJHy95+IN1ttMBOmULI9mNzC+NBX8uO8xlUZ+KMKERPsBsa06x0xZqJ9TQTRNwSH89L56gB5Kt3PgFnuRE5sK/V/ThKQUR8Qa//Y5wBRLnJA2ilMjdA2PIbzS/3LA6XtVvYPQ=" Oct 27 22:36:02 volumio go-librespot[27850]: time="2025-10-27T22:36:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:03 volumio go-librespot[27850]: time="2025-10-27T22:36:03Z" level=debug msg="completed keyexchange" Oct 27 22:36:03 volumio go-librespot[27850]: time="2025-10-27T22:36:03Z" level=debug msg="completed challenge" Oct 27 22:36:03 volumio go-librespot[27850]: time="2025-10-27T22:36: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 27 22:36:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:05 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:05 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 97. Oct 27 22:36:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:06 volumio go-librespot[27857]: go-librespot daemon starting... Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=debug msg="app state loaded" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36: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 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36: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 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36: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 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=info msg="zeroconf server listening on port 45133" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=debug msg="obtained new client token: AADEFMP49njJv8b4+S0/Sk+/HB/JrTMwD/Bfov8ul2hHr917UN14kOXyAKLe8fa4abrqb957mJvr743miJ6JGU0SvMddv8GBOm1gqiGX1f9G/WLYdHzEH0ct1J9cqMFceuQOiJz+dSH3Bm55sMld5wVFWPZW0EF8X0gkzaKg9x2lwBkkeM5H7thfxGviNRtTOQ5bQDd8IMagqLIh3rFKuEA6gP8RhOkwo5un53MvAolmSdvpva/4kuQ=" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=debug msg="completed keyexchange" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=debug msg="completed challenge" Oct 27 22:36:06 volumio go-librespot[27858]: time="2025-10-27T22:36:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:08 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:08 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98. Oct 27 22:36:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:09 volumio go-librespot[27865]: go-librespot daemon starting... Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=debug msg="app state loaded" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=info msg="zeroconf server listening on port 37971" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=debug msg="obtained new client token: AABoZ1B0s6WE/4L+jW9hkG6FIOOGPu+VyN7N4cQUXslmT3RWN2u+uEcniaZxfDrUUHMHa9yC2HVlN0wJAt2ylJkdjSEePyyTBereCV8lpQuluD81+5TfunLcpI1MHm6rJa11B9cDEQWHqMoLPWu82Fg2LwqLZG897zZlqfvvCRfhIrOAUaDyPabockasgG4eJQD0oszDCkH8lWOJVpzyuAjhI18ZL260ozUfxvaztB+IX6tiQP1hRMs=" Oct 27 22:36:09 volumio go-librespot[27866]: time="2025-10-27T22:36:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:10 volumio go-librespot[27866]: time="2025-10-27T22:36:10Z" level=debug msg="completed keyexchange" Oct 27 22:36:10 volumio go-librespot[27866]: time="2025-10-27T22:36:10Z" level=debug msg="completed challenge" Oct 27 22:36:10 volumio go-librespot[27866]: time="2025-10-27T22:36: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 27 22:36:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:11 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:11 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 99. Oct 27 22:36:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:13 volumio go-librespot[27888]: go-librespot daemon starting... Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=debug msg="app state loaded" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" 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 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" 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 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=info msg="zeroconf server listening on port 44385" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=debug msg="obtained new client token: AAA92nz2cg/G6nrVwNczhKMGvCD/2u3I3DVWWRY5C9eGccoRQ7nB1pYnpqqWYvYSEKIhmQwSon1oQTWgh9JSJwCWK3VNmFcUpTk+9ld6zrEkhFxRcYGUvQTv8UpowtVqplQE5YQS/g7n86UrXt2voWCZ/h77uvCmxz9s+9u6SnzCaFAW34d9Ue5nzMKF+wyYRkbT5LC9J0NiXO2gZTQevn8zoNPfRYh183ZdqDQKqU9x0vu5grq6A6k=" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=debug msg="completed keyexchange" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=debug msg="completed challenge" Oct 27 22:36:13 volumio go-librespot[27889]: time="2025-10-27T22:36:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:14 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:14 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 100. Oct 27 22:36:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:16 volumio go-librespot[27899]: go-librespot daemon starting... Oct 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" level=debug msg="app state loaded" Oct 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" 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 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" 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 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" 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 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" level=info msg="zeroconf server listening on port 36287" Oct 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" level=debug msg="obtained new client token: AABqmYVja1yAWJ4A8SBYcow/PxMiKWR0L8a4raQtxJI63xJzL33njcnyZjc7vwscELuK6E2lh5s+SVixpJV/XWoXGFGeh59fJHvg73hT0CXlBCjsMQacL6VV0SPUwKnSITqVScG4ArJOR90CJC/V2PMwINwZx1lxW2SDzQHrGVPQlBA38fZyZxbHbGocRXws3IvqUNmIRyiAb3GlidpzCE9q+iG4fNsyP0bPfLdZWZ+tzV9oVeeR41A=" Oct 27 22:36:16 volumio go-librespot[27900]: time="2025-10-27T22:36:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:17 volumio go-librespot[27900]: time="2025-10-27T22:36:17Z" level=debug msg="completed keyexchange" Oct 27 22:36:17 volumio go-librespot[27900]: time="2025-10-27T22:36:17Z" level=debug msg="completed challenge" Oct 27 22:36:17 volumio go-librespot[27900]: time="2025-10-27T22:36: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 27 22:36:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:17 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:17 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:20 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:20 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101. Oct 27 22:36:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:20 volumio go-librespot[27907]: go-librespot daemon starting... Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=debug msg="app state loaded" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" 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 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" 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 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=info msg="zeroconf server listening on port 40285" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=debug msg="obtained new client token: AAD8Tk0IADJH+61I4SNbiPJKmhfp8hOnpYrMsop2UHX97Y8TgF5bOc/hHtasOyOAses2oX6L+N2hqXBJvGjsnmVgUK8VX9mhlaR110yzcM06Y3S3hOC0pPOqpKFOiNnZZe5Zii8itODILOvTVFa9SE3Ltkokzc7tzB9/MiDSPpYJvUhPbkf/XMx4A1NlvWDboS1Gezw/2Uxv99KrNjwAuTMAWgdC4+5w7aLeSLHB7rNwnn1zza7f6Wc=" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=debug msg="completed keyexchange" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=debug msg="completed challenge" Oct 27 22:36:20 volumio go-librespot[27908]: time="2025-10-27T22:36:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:23 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:23 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102. Oct 27 22:36:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:23 volumio go-librespot[27929]: go-librespot daemon starting... Oct 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" level=debug msg="app state loaded" Oct 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" 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 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" 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 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" level=info msg="zeroconf server listening on port 36275" Oct 27 22:36:23 volumio go-librespot[27930]: time="2025-10-27T22:36:23Z" level=debug msg="obtained new client token: AAAALDsc/eVZwox8LnwhQ9uwP2T7cNgPt+sSGW3etfo21NRn9B3r20aNxE8fGP+rkNo7FTS1VwXeFWowi8lk8KRRyU61/EbhnhkEjbgnb3dcoYQenpOE3lyfEwcTtRudOayObRU8Y7yYUcpZUzHU5MGsq/GnQMmrLaoXyp8XEoIWIZwiCSQKYJelgZmUud4dVAv3X2gtHx0bIt3SG3zUd2nT35k77+XUKiPQv0c3uZVSvAjXUedcM78=" Oct 27 22:36:24 volumio go-librespot[27930]: time="2025-10-27T22:36:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:24 volumio go-librespot[27930]: time="2025-10-27T22:36:24Z" level=debug msg="completed keyexchange" Oct 27 22:36:24 volumio go-librespot[27930]: time="2025-10-27T22:36:24Z" level=debug msg="completed challenge" Oct 27 22:36:24 volumio go-librespot[27930]: time="2025-10-27T22:36: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 27 22:36:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:26 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:26 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103. Oct 27 22:36:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:27 volumio go-librespot[27937]: go-librespot daemon starting... Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=debug msg="app state loaded" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36: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 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36: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 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36: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 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=info msg="zeroconf server listening on port 33985" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=debug msg="obtained new client token: AAC9/22MNo8+HtLkhGcY7HgW9CEn8IZn7w8rSgRFZKBOZq1uEW+HGLKqMZexgCa9K4vSv7gZyxm7quDUGhoWGonWob4rBxQJdPG5eZfUgDGHZgQqNt6AK5xsStQlKr+oferV4DfIlMF1p0dcRKeFCYtPV6hHMx9XnAkK/J8DNM+JCi1dExr7Q1qAUn5C9zetKWHgXRw9kxiolUlkdDGL/ApmuxwSHc3Jf6ArcDwkQlTg9hT5cCJjeD0=" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=debug msg="completed keyexchange" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=debug msg="completed challenge" Oct 27 22:36:27 volumio go-librespot[27938]: time="2025-10-27T22:36:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:29 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:29 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104. Oct 27 22:36:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:30 volumio go-librespot[27960]: go-librespot daemon starting... Oct 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" level=debug msg="app state loaded" Oct 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" 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 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" 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 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" level=info msg="zeroconf server listening on port 38329" Oct 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" level=debug msg="obtained new client token: AAC/g18lSmlW4Xto3oL0sT08yC8JURJuT7gZH3BIRMk9pFHyFZY7JBOez3lBt4Xymg93g1n5BwrajtTFddGBfPph4uxb7PFvpM2BfTBCOg7kjAMgfDI4wZg3fgi75s6HY6XcNLwFXimC3sq8LanmL/ZUMIzQPBlkJyI9T012jk3v+GjnBQKVHdvVzoKVNVW1dAeOb2TktCQ6DQwF493q8bUK1RLOosY5+AYrMefxnIGfuN8YwRFJfK0=" Oct 27 22:36:30 volumio go-librespot[27961]: time="2025-10-27T22:36:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:31 volumio go-librespot[27961]: time="2025-10-27T22:36:31Z" level=debug msg="completed keyexchange" Oct 27 22:36:31 volumio go-librespot[27961]: time="2025-10-27T22:36:31Z" level=debug msg="completed challenge" Oct 27 22:36:31 volumio go-librespot[27961]: time="2025-10-27T22:36: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 27 22:36:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:32 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:32 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105. Oct 27 22:36:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:34 volumio go-librespot[27970]: go-librespot daemon starting... Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="app state loaded" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=info msg="zeroconf server listening on port 43371" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="obtained new client token: AABYDr+bdIHbfsqEh9+9vxUjdydQvDzXicraPOp/JQ1n2Ic8M/OzlaKWFg0wG0mmbDVGKt2NftDAvwWzW4YH1v1clQ5c0LQ7yTVyHKt1oZ10Gzg1MMdCqOzczBv+JXI0Sh4S0eA+Mb7PLl8mSFVYtQCCgLN2ES1XLTRNQB76R1P4GfmE7sF2lUwDvqv3hTNipL6yYe+G7zRh4wpZPVlAKBxlOS2I8ZG8mURqc+ibWxKBNIK2N3ru9yo=" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="completed keyexchange" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=debug msg="completed challenge" Oct 27 22:36:34 volumio go-librespot[27971]: time="2025-10-27T22:36:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:35 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:35 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106. Oct 27 22:36:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:37 volumio go-librespot[27981]: go-librespot daemon starting... Oct 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" level=debug msg="app state loaded" Oct 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" 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 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" 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 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" level=info msg="zeroconf server listening on port 34753" Oct 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" level=debug msg="obtained new client token: AADL7o7wohhMAObyQUyneENi5nPgAMuiKIr3ebQxancGGXuv19YEHdHTWxbh9h7TLht0rrDpXlHitJw5YCKIV8z13u2d/kfZTfUSyZ/MwBoijJpuJwiBK0BlNo79sHDaBwfGaqGSCN0dtRkGGcqFjNSNQEFsh95hrZZn1P81Yth+uvmXPnUa5gVTIbVLoqZlxw1OiLqjTr4E3AGKjLaJE30N5kqKv5962EpbUlSfTDPMFNsckcQDsog=" Oct 27 22:36:37 volumio go-librespot[27982]: time="2025-10-27T22:36:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:38 volumio go-librespot[27982]: time="2025-10-27T22:36:38Z" level=debug msg="completed keyexchange" Oct 27 22:36:38 volumio go-librespot[27982]: time="2025-10-27T22:36:38Z" level=debug msg="completed challenge" Oct 27 22:36:38 volumio go-librespot[27982]: time="2025-10-27T22:36: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 27 22:36:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:38 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:38 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:41 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:41 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Oct 27 22:36:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:41 volumio go-librespot[28003]: go-librespot daemon starting... Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=debug msg="app state loaded" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" 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 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" 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 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" 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 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=info msg="zeroconf server listening on port 45789" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=debug msg="obtained new client token: AADDPbc3M8gC1SPmx9Dck7+qnBnZh9byOUpv1/v4xaMCPq1iVx6Guh5zBPmvi3oscIcx9rItV18CvuRay/oPsECSKMNjJlomWFb1l+K04hkFBio98bgo7l4pIJHmK/THTeRxuEQa079FuGBByfZqwspjTYXR3bI2vSHfG+yA+TGhbJwnHFUHIS13tfGmN9NAk71GQLTvO8JWA2p5n5YtC0UTYuIEhzB1oQj2dAVGc8+rfoNYds+0soE=" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=debug msg="completed keyexchange" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=debug msg="completed challenge" Oct 27 22:36:41 volumio go-librespot[28004]: time="2025-10-27T22:36:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:44 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:44 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Oct 27 22:36:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:44 volumio go-librespot[28012]: go-librespot daemon starting... Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=debug msg="app state loaded" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" 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 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" 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 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=info msg="zeroconf server listening on port 43169" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=debug msg="obtained new client token: AADSfLzpnGV7OAUE0nXsP+hYs0wOJnil+muIfySPyK3mA502mqkrtZspG/Ds8qRui4T/Tz0in8jEjWW3nkqvyWrc6/kSdtJnVoZBovS6WcSfn52t1BIYstxjeyxzk7amBwiyZjPyP66cK+tpcCKLw/16CSnaIdAjQnAPOI9fN5LpI2Lj+a9/VGIW/zc1fVO0slLSj3tOyEwCechDWVFc0e/zcQ7TR49xi9+FqbgpGsawRXmuK7iS+xM=" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=debug msg="completed keyexchange" Oct 27 22:36:44 volumio go-librespot[28013]: time="2025-10-27T22:36:44Z" level=debug msg="completed challenge" Oct 27 22:36:45 volumio go-librespot[28013]: time="2025-10-27T22:36:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:47 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:47 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Oct 27 22:36:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:48 volumio go-librespot[28021]: go-librespot daemon starting... Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="app state loaded" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=info msg="zeroconf server listening on port 40295" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="obtained new client token: AABT6YqdKsw+qtkuZCnLu/CtowxFX2sHKWwCZ8jQxIk/mYl38skXReb6ciXVBllVxq7sQdlqCs3QN4krZP+4fcA2LCDvNPnlGBnuEPuXBVYdJSy5MvKfmLZnHNWRi39blADamEEzx95RYw5Yvdeg8cBmYQ4lMAru2mdkLznig0Ys2bvh3GOZiREaqoK3o4j4rCLNAtRFJ5od0vCpQckqcOhdfM93EPE6dzUJjwT4onGeuBWVvtAW900=" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="completed keyexchange" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=debug msg="completed challenge" Oct 27 22:36:48 volumio go-librespot[28022]: time="2025-10-27T22:36:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:50 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:50 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Oct 27 22:36:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:51 volumio go-librespot[28044]: go-librespot daemon starting... Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=debug msg="app state loaded" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=info msg="zeroconf server listening on port 37651" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=debug msg="obtained new client token: AABOO0LBMksWDQaY6ln+FhEYOW5+lcbRP3WbkMSbwsbsyu+D8BcNXp7LJqsKIy2mNp/uwCvtsV52HPqoXh9x8DY4LPblzBgbxbYbrDPCXMyW2wF8xzkTObgC4XQMFx62eV9zpYqIatygZxMCaQkeYUqImuM2A64UMEc3FHP/KGADQh3hLa88RwvjfOfSQ+AcUL2nWcbjsFp1afvLPtJ0FXQM57086mD1IhrA6tsMSp8Z4yxhh6+ivYo=" Oct 27 22:36:51 volumio go-librespot[28045]: time="2025-10-27T22:36:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:52 volumio go-librespot[28045]: time="2025-10-27T22:36:52Z" level=debug msg="completed keyexchange" Oct 27 22:36:52 volumio go-librespot[28045]: time="2025-10-27T22:36:52Z" level=debug msg="completed challenge" Oct 27 22:36:52 volumio go-librespot[28045]: time="2025-10-27T22:36:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:53 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:53 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Oct 27 22:36:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:55 volumio go-librespot[28052]: go-librespot daemon starting... Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="app state loaded" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=info msg="zeroconf server listening on port 37987" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="obtained new client token: AACtKjwa11uUvvYu6o8o3cWump02GtiMVfenVo/W8dyOfnoU7FqX7oZIeGXZDVt1AMNNCjcz9FFp1rX4DsTzHGCjJ+ilqXsVVjWWYWMIVIpm3sGBjNZyF5TnYJE2px6wXGbbQLS8D1bNxK+cRBvs61lz8r3bxwLnLDIMZPn38rPPDu1AkWDYzy3cqdzEe8U++wKIcxv2d2RekCx5EZoVJuO9HoBzjxfg0xgnIzm0F4zd6OJyi1KBJ1A=" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="completed keyexchange" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=debug msg="completed challenge" Oct 27 22:36:55 volumio go-librespot[28053]: time="2025-10-27T22:36:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:36:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:36:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:36:56 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:56 volumio volumio[27279]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 22:36:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Oct 27 22:36:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 27 22:36:58 volumio go-librespot[28063]: go-librespot daemon starting... Oct 27 22:36:58 volumio go-librespot[28064]: time="2025-10-27T22:36:58Z" level=info msg="running go-librespot 0.4.0" Oct 27 22:36:58 volumio go-librespot[28064]: time="2025-10-27T22:36:58Z" level=debug msg="app state loaded" Oct 27 22:36:58 volumio go-librespot[28064]: time="2025-10-27T22:36:58Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 27 22:36:58 volumio go-librespot[28064]: time="2025-10-27T22:36:58Z" 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 27 22:36:58 volumio go-librespot[28064]: time="2025-10-27T22:36:58Z" 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 27 22:36:58 volumio go-librespot[28064]: time="2025-10-27T22:36:58Z" 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 27 22:36:58 volumio go-librespot[28064]: time="2025-10-27T22:36:58Z" level=info msg="zeroconf server listening on port 44621" Oct 27 22:36:59 volumio volumio[27279]: info: Initializing connection to go-librespot Websocket Oct 27 22:36:59 volumio go-librespot[28064]: time="2025-10-27T22:36:59Z" level=debug msg="new websocket client" Oct 27 22:36:59 volumio volumio[27279]: info: Connection to go-librespot Websocket established Oct 27 22:37:02 volumio volumio[27279]: info: Getting Spotify volume Oct 27 22:37:02 volumio volumio[27279]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 27 22:37:02 volumio volumio[27279]: info: CoreCommandRouter::volumioGetState Oct 27 22:37:02 volumio volumio[27279]: info: CorePlayQueue::getTrack 0 Oct 27 22:37:02 volumio volumio[27279]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Oct 27 22:37:02 volumio volumio[27279]: SPOTIFY: SPOTIFY VOLUME undefined Oct 27 22:37:02 volumio volumio[27279]: SPOTIFY: VOLUMIO VOLUME 33 Oct 27 22:37:02 volumio volumio[27279]: info: Aligning Spotify Volume to Volumio Volume Oct 27 22:37:02 volumio volumio[27279]: info: CoreCommandRouter::volumioGetState Oct 27 22:37:02 volumio volumio[27279]: info: CorePlayQueue::getTrack 0 Oct 27 22:37:02 volumio volumio[27279]: info: Setting Spotify Volume from Volumio: 33 Oct 27 22:37:03 volumio volumio[27279]: SPOTIFY: SETTING SPOTIFY VOLUME 33 Oct 27 22:37:03 volumio volumio[27279]: info: Sending Spotify command with payload to local API: /player/volume Oct 27 22:37:08 volumio go-librespot[28064]: time="2025-10-27T22:37:08Z" level=debug msg="obtained new client token: AAAf1OfwIJwbqWnwVyxb3wppjGSUGS5/HOPKBZJsbRCIWi4MfDIEPZCwkFZaGt2r9lrMSlBsZss+PtwRYcXxH4JrQD/BSMd2XiaLdoMhlnX0i4IZoWgJtxplR6I9CSn4f+IcoUKgf15ueGVkHFBDd0Wt3aK3nySgTVQNc0a2uPdQE355vJ61PK+GWQcx4XWh7Jzd4wi3vQpolWX9FPNnRBXfCPJiARA5BlgZfQ9stSTRO6O9ATKw/YA=" Oct 27 22:37:09 volumio go-librespot[28064]: time="2025-10-27T22:37:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 27 22:37:09 volumio go-librespot[28064]: time="2025-10-27T22:37:09Z" level=debug msg="completed keyexchange" Oct 27 22:37:09 volumio go-librespot[28064]: time="2025-10-27T22:37:09Z" level=debug msg="completed challenge" Oct 27 22:37:09 volumio go-librespot[28064]: time="2025-10-27T22:37:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 27 22:37:09 volumio volumio[27279]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Oct 27 22:37:09 volumio volumio[27279]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 22:37:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 22:37:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 22:37:09 volumio volumio[27279]: Error: socket hang up Oct 27 22:37:09 volumio volumio[27279]: at connResetException (node:internal/errors:720:14) Oct 27 22:37:09 volumio volumio[27279]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 27 22:37:09 volumio volumio[27279]: at Socket.emit (node:events:526:35) Oct 27 22:37:09 volumio volumio[27279]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 27 22:37:09 volumio volumio[27279]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 27 22:37:09 volumio volumio[27279]: code: 'ECONNRESET', Oct 27 22:37:09 volumio volumio[27279]: response: undefined Oct 27 22:37:09 volumio volumio[27279]: } Oct 27 22:37:09 volumio volumio[27279]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 22:37:09 volumio sudo[28101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-27 22:36' Oct 27 22:37:09 volumio sudo[28101]: 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"