Nov 05 01:45:00 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:45:00 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:45:02 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:02 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104. Nov 05 01:45:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:02 volumio go-librespot[14368]: go-librespot daemon starting... Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=debug msg="app state loaded" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=info msg="zeroconf server listening on port 46315" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=debug msg="obtained new client token: AABMPfj32++xWEPjyzFcEFmVqiCryetqHdKHZDW0S/WVv2j/R8qylB9y5Yf7TnCcfqkU49PBhi/pagyKQWE5VqRFLrfFWJo0VUk3OBlJWKxVZOirthvE0xuaNcf3uSYNigzz7obOBL33BI450NBetuhD0RzzZ0uJdevEtqT5Savr0YrAyTf09LVf+1oOK46IPJmqG3eNKt62HpnCjZrPjREB37cguLjgsEGld/IOVi34vx7ZwJl5u9E=" Nov 05 01:45:02 volumio go-librespot[14369]: time="2025-11-05T01:45:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:03 volumio go-librespot[14369]: time="2025-11-05T01:45:03Z" level=debug msg="completed keyexchange" Nov 05 01:45:03 volumio go-librespot[14369]: time="2025-11-05T01:45:03Z" level=debug msg="completed challenge" Nov 05 01:45:03 volumio go-librespot[14369]: time="2025-11-05T01:45:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:05 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:05 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105. Nov 05 01:45:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:06 volumio go-librespot[14390]: go-librespot daemon starting... Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="app state loaded" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=info msg="zeroconf server listening on port 35075" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="obtained new client token: AAB5z/9R+AJG3auD/InQ7c5ZxArWnHWcBulxe1xSQ+bZp72+K5GaLi3cLldOWNVj8h2pUzivTM8brscfzhORt5CdpBHjjc4ImAUiKbnzfP72URhrt+mNG8D+z8/tCr9a4tfmeIVp/EIZ3mSPwiRto8COTi/t/Ntua29RsmL4oxKBH59UejkVQBwtgWm25TexapN1YlIB/Z7OALLMn8xWhM9XbKf2fS0OJlNE9hI5hPhiU+5KXMIhwzk=" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="completed keyexchange" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=debug msg="completed challenge" Nov 05 01:45:06 volumio go-librespot[14391]: time="2025-11-05T01:45:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:08 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:08 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106. Nov 05 01:45:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:09 volumio go-librespot[14398]: go-librespot daemon starting... Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=debug msg="app state loaded" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=info msg="zeroconf server listening on port 40939" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=debug msg="obtained new client token: AAC2W1ksEIqlgLukKgcfOfdeD7QehYSNKvlNn32zOuqw0Yd+zJ/CQlbrF4d9QWJ0gRhmKK62gRoZsMJrYHYKdvTdZHsJE+BizmCd1bxvHEGSrylqk4JC94buOtrZ1q8llrG3MQpqeZrpeQfIpm4kkGYbHmM0JocVcad+Y+MV+XWn+ouLsbNtD+vLxL9uDY3WsWaE6ZZVOVZRQEmUaqc3cEQigiGNlgtCsfNfr56hrGhkp880DxhhePM=" Nov 05 01:45:09 volumio go-librespot[14399]: time="2025-11-05T01:45:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:10 volumio go-librespot[14399]: time="2025-11-05T01:45:10Z" level=debug msg="completed keyexchange" Nov 05 01:45:10 volumio go-librespot[14399]: time="2025-11-05T01:45:10Z" level=debug msg="completed challenge" Nov 05 01:45:10 volumio go-librespot[14399]: time="2025-11-05T01:45:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:10 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:45:10 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:45:10 volumio volumio[13728]: info: Listing playlists Nov 05 01:45:10 volumio volumio[13728]: info: Listing playlists Nov 05 01:45:11 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:11 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Nov 05 01:45:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:13 volumio go-librespot[14420]: go-librespot daemon starting... Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=debug msg="app state loaded" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45: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]" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45: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]" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45: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]" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=info msg="zeroconf server listening on port 38371" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=debug msg="obtained new client token: AADOCzYoTCPEvJSiSBXp/Mc7jhT1J6fAX8lmj/TwUNTt6o94QzBcWdprH4Y3NgzEwmkNUr6eXdvdnwUJuxNTapqUOXg5uqqZX/6Gmx+Jft1fnxuXn0lLbVEodXqT0jfQnKK9Vv7+EforV7JpX3m/8YUtHb/m9QZqpm+LsOglq38AzY8JGzHK7kmBauTbN4Yd8881V9gyr04jlWUNoFT6EKwBmK7gdmVuDM/jEuZS1JIfp5M16fDnbX4=" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=debug msg="completed keyexchange" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=debug msg="completed challenge" Nov 05 01:45:13 volumio go-librespot[14421]: time="2025-11-05T01:45:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:14 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:14 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Nov 05 01:45:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:16 volumio go-librespot[14428]: go-librespot daemon starting... Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="app state loaded" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=info msg="zeroconf server listening on port 39141" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="obtained new client token: AAAVdqcr3BaWyNV6MIpr1HjKIdP9LxPc5uxQVzPdBw8bh7BuDGzHWkM3MrjY1j3gd1R7HbEXNSjj2uIDynZCklf4AXk6MR7G7SYDCO9W214ySl+OfK5WukEf8THkzx2FERuXm/URKek+NrMQK+Jk6cUl3iM/ehOOFAcHj13g60HUiiANPkawLCFDotypgfg84AdCfV8ZuLc5OpDIsyILxouQykYF5tq+aedknOQXtx/lBqFzt/0sg1A=" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="completed keyexchange" Nov 05 01:45:16 volumio go-librespot[14429]: time="2025-11-05T01:45:16Z" level=debug msg="completed challenge" Nov 05 01:45:17 volumio go-librespot[14429]: time="2025-11-05T01:45:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:17 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:17 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Nov 05 01:45:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:20 volumio go-librespot[14440]: go-librespot daemon starting... Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=debug msg="app state loaded" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45: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]" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45: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]" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45: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]" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=info msg="zeroconf server listening on port 41083" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=debug msg="obtained new client token: AACS/N8Ldn73CFn2QwZyQz4as7E2CstX0EV0+noDhq0l/4pXG+xBDW7Bkii+NtpO6Tpg2PrhwbrNjwOjNw0Re/yrTiIiBCgkxED9MzJvKBs4HOFkFE1AlC20dWXDZI/Hs5AY8el/aa0j83azundZ6E4sPceZB2j6h0BFT4jQ+u+LoV6if+6XY/5JVDTCtJwIF9ks3IndrkMvz/2A2aU0hWaeyzrKyYVAbGdF9Cb3QYpm+1hHb0RRn1Y=" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=debug msg="completed keyexchange" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=debug msg="completed challenge" Nov 05 01:45:20 volumio go-librespot[14441]: time="2025-11-05T01:45:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:20 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:20 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:20 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:45:20 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:45:23 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:23 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Nov 05 01:45:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:23 volumio go-librespot[14462]: go-librespot daemon starting... Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=debug msg="app state loaded" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=info msg="zeroconf server listening on port 39617" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=debug msg="obtained new client token: AAAvl8RTnHYHYaoMZqUNCl8vsLZ076S6upDuFf1Y8/v4EsrtnnO7JU8sdoTufKUfqBFYgd0GNemPQh4pTIQM4DhqQ4CRH2RUJYkqWZZmgfEyujnpidev/tajBp5wb3nygDxqBFaFT24m7tOWA2XfQqoWEMBDQLE3yob9ddkB+OlMMCyc9R8vMhrO6Orni/LBVBNwOUtU8lNCr2Uh7cgI9R8k7cheyPLOSJ5cTX3/KKhiexnNdltG8WI=" Nov 05 01:45:23 volumio go-librespot[14463]: time="2025-11-05T01:45:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:24 volumio go-librespot[14463]: time="2025-11-05T01:45:24Z" level=debug msg="completed keyexchange" Nov 05 01:45:24 volumio go-librespot[14463]: time="2025-11-05T01:45:24Z" level=debug msg="completed challenge" Nov 05 01:45:24 volumio go-librespot[14463]: time="2025-11-05T01:45:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:26 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:26 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Nov 05 01:45:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:27 volumio go-librespot[14470]: go-librespot daemon starting... Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="app state loaded" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=info msg="zeroconf server listening on port 45259" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="obtained new client token: AAD9REpK4qDZu+fHUPfWGEi5PH77pPe2il2n7RWayxYDlEJaDplZ7Y/4kJQB5AICOPMHc6htnZ/lOsQlrT1tHEoAT1SGW2uoYHpX738bNHajwJAlyx1ftRK3pBnpPGqHIG1nx9bl3p3Mf/50mpzbgAAp7oXDJpsFpVy2q8BOsbA8nYkI3xwmwRJx7Rk0DvvFObA8GjI/jMFsRDV3FeNa+GHzgNsZuWX56W3Ohdp1lfAvvWUFoANhf+U=" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="completed keyexchange" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=debug msg="completed challenge" Nov 05 01:45:27 volumio go-librespot[14471]: time="2025-11-05T01:45:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:29 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:29 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Nov 05 01:45:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:30 volumio go-librespot[14479]: go-librespot daemon starting... Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=debug msg="app state loaded" Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:30 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:45:30 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:45:30 volumio volumio[13728]: info: Listing playlists Nov 05 01:45:30 volumio volumio[13728]: info: Listing playlists Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=info msg="zeroconf server listening on port 38475" Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=debug msg="obtained new client token: AADl24Iejx4OxZfTPD/w8QI0qgQRaTWYFHwz3E58A/eHkmnSwDO8UXUIDZpllfsoUotcEz1a71uWgfgUeRnRVP0tPd02abjjdVypsR2hFX5/6NAlIGJEZ8HVZsQHTeylxuJ68i+/dhzmCDXeD5kXmRrHgtQSnkToQ+TKumJBluMKyoWStXBkeqq+nyAA05MbxYHeN6X+30Z/lf6DGYTqXnbj44JOGudxUxLk4JPH10ylRdpwM0I8mlM=" Nov 05 01:45:30 volumio go-librespot[14480]: time="2025-11-05T01:45:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:31 volumio go-librespot[14480]: time="2025-11-05T01:45:31Z" level=debug msg="completed keyexchange" Nov 05 01:45:31 volumio go-librespot[14480]: time="2025-11-05T01:45:31Z" level=debug msg="completed challenge" Nov 05 01:45:31 volumio go-librespot[14480]: time="2025-11-05T01:45:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:32 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:32 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113. Nov 05 01:45:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:34 volumio go-librespot[14502]: go-librespot daemon starting... Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=debug msg="app state loaded" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=info msg="zeroconf server listening on port 34371" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=debug msg="obtained new client token: AACy0BYKGd6yuy9CPWmF+iex6mUM5EiGZgPcrCzIpvcQIFVvFDiZ16FqduM7VodVlRAsU8zQWwBD+igcmaHERxrzjJM3QHsyyFKj8lxKFYw0oN/vgGa7Ph2qbQvsXNNuQNnBHmA4h7AWayHp3Q8+o2SfO3AmZL6uzoD24YrrOaBDRn8nq3Vpb7oKDerTjsFWq2KClDA+ffRK/7X1k0NjHowXd3/+S6aCYQQjPB6aEuGXY5UKKzp1v4c=" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=debug msg="completed keyexchange" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=debug msg="completed challenge" Nov 05 01:45:34 volumio go-librespot[14503]: time="2025-11-05T01:45:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:35 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:35 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114. Nov 05 01:45:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:37 volumio go-librespot[14511]: go-librespot daemon starting... Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=debug msg="app state loaded" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=info msg="zeroconf server listening on port 39563" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=debug msg="obtained new client token: AAA17YXIn03/HdB/uiLtc8Cc+9XFo12FFnn9F6dzWrjBw8e0TqaM536T66l4YYg2EOFzC8nVgUPlcEpnud6lOWEO2siImlWhpeVIzgKW2d+1OLYynm0OWVT9LRuBYYNVifJMqjz8P8YOLzMaUIwc5bW25SxbbfNvVJgKaElTIspHhQ8O2wrDT4AispkzlDDDh+h76IP7EBuWZW41yuszAHsgWbxdQYBDjsoQCERK9RlXsrbKrNTIW8A=" Nov 05 01:45:37 volumio go-librespot[14512]: time="2025-11-05T01:45:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:38 volumio go-librespot[14512]: time="2025-11-05T01:45:38Z" level=debug msg="completed keyexchange" Nov 05 01:45:38 volumio go-librespot[14512]: time="2025-11-05T01:45:38Z" level=debug msg="completed challenge" Nov 05 01:45:38 volumio go-librespot[14512]: time="2025-11-05T01:45:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:38 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:38 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:40 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:45:40 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:45:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 115. Nov 05 01:45:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:41 volumio go-librespot[14523]: go-librespot daemon starting... Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=debug msg="app state loaded" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=info msg="zeroconf server listening on port 36031" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=debug msg="obtained new client token: AAAC480cnDLhhU8meHfu4QAkejxuYyDF7ApzkQhKDWWbOnI9EDfSwQLq3PHw3rkxTikiKc/r9LeZsIizo3c7lBB3qJW3Weslfy5YreSL6kxLOJhWn4ASr1CaRVjy6IYuxWGLURAMEYYqy/uf0Gruj+w4P5Nb8n2htQVDsycDSNFwgvepCxLP3NpTn2Gh38P9XQs+q3X0M+0joXz/WNN3jz5vYEOW7t1ULVSkokeij9t2JGDlbbO3yvc=" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=debug msg="completed keyexchange" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=debug msg="completed challenge" Nov 05 01:45:41 volumio go-librespot[14524]: time="2025-11-05T01:45:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:41 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:41 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:44 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:44 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 116. Nov 05 01:45:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:44 volumio go-librespot[14546]: go-librespot daemon starting... Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="app state loaded" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=info msg="zeroconf server listening on port 37677" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="obtained new client token: AABzDrW3ZQFlklGt1Vn36oDUbGGqJM43m69ob7Q7/Y6b7hhwjc1dU9CUp6c6/3K+zRuyybewqxJTGcYKohwHIrUOKd1oWX+eYdSApQXax5fGWmnOw5UTBtwRzMw6nrR7aSlQUFiRzVU6a4FSr3PP3d3RTKVi5WwLgVe8u4eC3mDfBvsUrhb9+Pz3k0KjgOhUzZhzX1LjDliuWWj9HuaPzdUBWtIW2vwKmFzy1pvCB3+fEapWYSBf+Rk=" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="completed keyexchange" Nov 05 01:45:44 volumio go-librespot[14547]: time="2025-11-05T01:45:44Z" level=debug msg="completed challenge" Nov 05 01:45:45 volumio go-librespot[14547]: time="2025-11-05T01:45:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:47 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:47 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117. Nov 05 01:45:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:48 volumio go-librespot[14554]: go-librespot daemon starting... Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="app state loaded" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=info msg="zeroconf server listening on port 42525" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="obtained new client token: AABHJahxdqa7bb8VuUh2AKdmn1DaoS5zKG6oUh61KWlugLMzRto6LakDH/OxzA+LMOk2BV6pQH8M6rEuI3MZkwmnHR5kcoj3OB/bMQOJGNkr7+CcA0aamTDXqCayrQLOCNTgsOkY9SjU8g3b0oOf79Kyq1KGuPAXQTjkYN2aLPeQpAElLXFsrGqncbgd1FRs5v2bTKQ9T7QhDFH2S9XvjOpaWwjNPkTa65nUvLUR9ADX7zrB5IXQv/c=" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="completed keyexchange" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=debug msg="completed challenge" Nov 05 01:45:48 volumio go-librespot[14555]: time="2025-11-05T01:45:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:50 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:50 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:50 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:45:50 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:45:50 volumio volumio[13728]: info: Listing playlists Nov 05 01:45:50 volumio volumio[13728]: info: Listing playlists Nov 05 01:45:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 118. Nov 05 01:45:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:51 volumio go-librespot[14564]: go-librespot daemon starting... Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="app state loaded" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=info msg="zeroconf server listening on port 42055" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="obtained new client token: AACby7P3z9AtUMnB7ps2YJGrn5fsdT4vbFi8KVx0d6Zf66vkR/bzRMi8FAYwWh9ag60Z9fz6IZ6GV+OaPqmKhWIrrvnf+/WNB66YIoUdiJrY5Wgx4WUMufrdU2D+9r92h5iJ+RZ7Dr7RgBpE/nEu5p5VGAik5958XOr2wsXn0TZ/f4KrUlmthQbdOOkhhwh0aKJbTpyP0ZjWM+w3yHvYymDifqCyrJmhDOnRcmUF3oZLiTX2K3bLanM=" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="completed keyexchange" Nov 05 01:45:51 volumio go-librespot[14565]: time="2025-11-05T01:45:51Z" level=debug msg="completed challenge" Nov 05 01:45:52 volumio go-librespot[14565]: time="2025-11-05T01:45:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:53 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:53 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 119. Nov 05 01:45:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:55 volumio go-librespot[14587]: go-librespot daemon starting... Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="app state loaded" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=info msg="zeroconf server listening on port 46653" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="obtained new client token: AAAidRzgs1Hb1Sieo8YYEiLP9TB+XIfsdaZC9/OPobppl2xVE4KwMYDbcwOY94Dod13O41lf9NAn0e8bPLb7yVXVjy3pI7BDksG8Lp8zNUQwTAdXl/nzq5BSZcmUWQxME35tzAA8URPj4F91ATkwXxpuXXko+nXvTnOyXphlhYkeSNtbaCLRWQ3jCNRWDvCSMy/nk3WguDg50l7V0C93/Uoa85gX2N5+bQpTt+51EoqN5QL4V/p2b/M=" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="completed keyexchange" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=debug msg="completed challenge" Nov 05 01:45:55 volumio go-librespot[14588]: time="2025-11-05T01:45:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:56 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:56 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:45:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 120. Nov 05 01:45:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:45:58 volumio go-librespot[14598]: go-librespot daemon starting... Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45:58Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45:58Z" level=debug msg="app state loaded" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45:58Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45: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]" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45: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]" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45: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]" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45:58Z" level=info msg="zeroconf server listening on port 35217" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45:58Z" level=debug msg="obtained new client token: AAD12fmyawGw41UsGr17mIaJ5iSjjsl2r1cZpdOF3rT8T4/syvRjBdqcgbk28HX8wEkeuDHPOP2roI+kRkfV3DbYqG70SGzrbtuH86aXjxKqHazJbAn7qIJjmHVU+Ny6CcPhOrhJpTgkU3Fm8BWl/FcmvxqVqf4FvU8KMqs8q8d6kUiSXzXmE3jw2ufTAQFHqBH4sjYA5AHzPsYvfKyu0AxEhWDS7+FDIJRN6HWRCcJFkG2FluG1rXw=" Nov 05 01:45:58 volumio go-librespot[14599]: time="2025-11-05T01:45:58Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:45:59 volumio go-librespot[14599]: time="2025-11-05T01:45:59Z" level=debug msg="completed keyexchange" Nov 05 01:45:59 volumio go-librespot[14599]: time="2025-11-05T01:45:59Z" level=debug msg="completed challenge" Nov 05 01:45:59 volumio go-librespot[14599]: time="2025-11-05T01:45:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:45:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:45:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:45:59 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:45:59 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:00 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:46:00 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:46:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 121. Nov 05 01:46:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:02 volumio go-librespot[14606]: go-librespot daemon starting... Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="app state loaded" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=info msg="zeroconf server listening on port 43333" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="obtained new client token: AABKApGWCTGPTNFUaeHn1axtWcP3Cd3x7uMje2j3+eHy0NeaREIXCmflAvHoM/JkA5ou4KFbM8ppE+fyJOsStykrKH7IrykP/2xIeD0p9VXKYMmAc43LBm03hwEN3xHFnq7sQ00P4V5FXBGXLNJDiZN3wes3WVC6RA8QJVr1hSgjTzvgPqacXNeA7PiVaseyBR3AkiKetfRgmLXxril66XSBcqpcAIpovY1+jPBtbf3fFVDvykehYKo=" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="completed keyexchange" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=debug msg="completed challenge" Nov 05 01:46:02 volumio go-librespot[14607]: time="2025-11-05T01:46:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:02 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:02 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:05 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:05 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122. Nov 05 01:46:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:05 volumio go-librespot[14628]: go-librespot daemon starting... Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=debug msg="app state loaded" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=info msg="zeroconf server listening on port 41227" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=debug msg="obtained new client token: AACPx1pCgejIjiVPz+xu/GzNc05nnerpviUntnwnd44CepA9JXpl5vW1HalmdHbejiusfZLgsv2yioWR9dlofBvy3J7ctPqyXOyNMw2TOfgGZGNBSba2RgA4Yfa9SVPwZ+uaU29PuLhrr7rX8oSqY6xftEEkEeM/+aZOTW/byWp7qX9Ub3Z4TRBUKldrDRMCJRsFwZ5orwPwybwv0jRlvJviAkxcCDyTGzca8ytS4h3u2ZkXTjZarbw=" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 05 01:46:05 volumio go-librespot[14629]: time="2025-11-05T01:46:05Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 05 01:46:06 volumio go-librespot[14629]: time="2025-11-05T01:46:06Z" level=debug msg="completed keyexchange" Nov 05 01:46:06 volumio go-librespot[14629]: time="2025-11-05T01:46:06Z" level=debug msg="completed challenge" Nov 05 01:46:06 volumio go-librespot[14629]: time="2025-11-05T01:46:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:08 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:08 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123. Nov 05 01:46:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:09 volumio go-librespot[14636]: go-librespot daemon starting... Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=debug msg="app state loaded" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=info msg="zeroconf server listening on port 33829" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=debug msg="obtained new client token: AAAtMu66LGtLWrOs00Uca7qSZwk8X7bNz9ACLa35Ltynqd8jQ3VtBluC0848FBq2OEZcCvDSp8DoHeUYRg/TUC6n5zh+nG/phYP51oYMFi7PdaXTqsvxyIL9VR6fMPCI2b/vV7W8w5UFsM4LZYey40DB1S11KeGtUepCh4s5b0ye2puqEysoB7Q8J6f4OEA+eRfWH/JeJDF79QnfW80coQ4OHnqQ+C/bok1RTQdYZcXEzvYJhxKFka0=" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=debug msg="completed keyexchange" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=debug msg="completed challenge" Nov 05 01:46:09 volumio go-librespot[14637]: time="2025-11-05T01:46:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:10 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:46:10 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:46:10 volumio volumio[13728]: info: Listing playlists Nov 05 01:46:10 volumio volumio[13728]: info: Listing playlists Nov 05 01:46:11 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:11 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124. Nov 05 01:46:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:12 volumio go-librespot[14644]: go-librespot daemon starting... Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=debug msg="app state loaded" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=info msg="zeroconf server listening on port 46757" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=debug msg="obtained new client token: AADAuLKN4KLsGF1CnAtbdZlsEwn0RkliJmBRiHXn8Auj0UfYJ144qHTmnKEmCJpLSo5dq30Mz9GVlWDWPmN5IxRDngP/7/CR2PP3uuqpLTf/0LoY3ucJ6J4pj+wYkEJ9IgmL3RX9hMTKTblxGKKmW5CnO8d7yw7kt7BL6Gy/gz4JXVKc1vq+yfuI2+fFGkiX3B/TkHd4Vnxt21unL7j0UbPg5c4Twte1Eo+flVHnwpbTWnco+Kj4K5o=" Nov 05 01:46:12 volumio go-librespot[14645]: time="2025-11-05T01:46:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:13 volumio go-librespot[14645]: time="2025-11-05T01:46:13Z" level=debug msg="completed keyexchange" Nov 05 01:46:13 volumio go-librespot[14645]: time="2025-11-05T01:46:13Z" level=debug msg="completed challenge" Nov 05 01:46:13 volumio go-librespot[14645]: time="2025-11-05T01:46:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:14 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:14 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 125. Nov 05 01:46:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:16 volumio go-librespot[14667]: go-librespot daemon starting... Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="app state loaded" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=info msg="zeroconf server listening on port 39375" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="obtained new client token: AADvMFCrOCdaCF9VxqixRUc1eDdmvz0LyJ3mZ+p9l1xaqKIX/XGDk0lw70+T0ozWM2Bh4QIyOaFiJWk5LRAat5u2aoa1IqCGPdsdjubTJ8gPnAxvLBbq0BRY+mSfZgKCpCg8B/1e31TwpeaGRT2J5pcY/kD2+Z/VtUhg69uXqvENkrtHEfzeGPVMVkKnCD6Qtc+57nrSXIon25rKKFcxRTnHve3lFuAB1TdIqYpmsi6st5OZFafAx8Y=" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="completed keyexchange" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=debug msg="completed challenge" Nov 05 01:46:16 volumio go-librespot[14668]: time="2025-11-05T01:46:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:17 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:17 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 126. Nov 05 01:46:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:19 volumio go-librespot[14679]: go-librespot daemon starting... Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=debug msg="app state loaded" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=info msg="zeroconf server listening on port 42423" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=debug msg="obtained new client token: AAAbzjIO2TsB7FRJGZ6LwN5rjPbZgv3n2ASTgO3BTiPmBWpzNGrcMIvaV7TVvrirYZVVg6iTXPi89dKA+4qPN1SUs9t95tmiE3dTugK6gudpwBVQ6B0ZoXZAjNfczRZDMoTMNRFpBRkVoVTI3rFTTQpi6wdbjsdnYx5be9RCn85nRDmjYiweAgQvFD2hGdhFPqE2Iz0lSFcRKi2mMvMquMzm0VH84sYDfprKiGekp1eU00VOMY7EOeE=" Nov 05 01:46:19 volumio go-librespot[14680]: time="2025-11-05T01:46:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:20 volumio go-librespot[14680]: time="2025-11-05T01:46:20Z" level=debug msg="completed keyexchange" Nov 05 01:46:20 volumio go-librespot[14680]: time="2025-11-05T01:46:20Z" level=debug msg="completed challenge" Nov 05 01:46:20 volumio go-librespot[14680]: time="2025-11-05T01:46:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:20 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:20 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:20 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:46:20 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:46:22 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 01:46:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 127. Nov 05 01:46:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:23 volumio go-librespot[14701]: go-librespot daemon starting... Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="app state loaded" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=info msg="zeroconf server listening on port 41941" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="obtained new client token: AABLci+/cecgiXVu3basL2gNpeDysI3BtIijvY7I+FBdJmzM6fwFCQfgATcQ9FGhlzvz8DU6XRqSHvAmlzVSk1dTuMgmCIVUaIIo/mleXktNJDWIb+AqII7+piuhY7XQJSCYpM/NE3n+xgjMvuRU2mnIL/QFo1RexEXismDLaAjDTlelZPrqEXGL2sS8uPwc2ppqEfALrnrKws/OQeaE+fd6uMT4T1DPW/+54e1lG7sVuYJWPGDrIAo=" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="completed keyexchange" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=debug msg="completed challenge" Nov 05 01:46:23 volumio go-librespot[14702]: time="2025-11-05T01:46:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:23 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:23 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:26 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:26 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 128. Nov 05 01:46:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:26 volumio go-librespot[14710]: go-librespot daemon starting... Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=debug msg="app state loaded" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=info msg="zeroconf server listening on port 39523" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=debug msg="obtained new client token: AACmBzwVaKDg6bZlv1NVyYUf2XVjdwRBB8XsRJvI/tXp54MZoSGQzBnc3j3SD5VtX7uigSlYVldL7lEzewBwi9wexV9BzdFPNDZN0Xp7oZ7GdeGK/W1lHCFex+4v1jAJRlwpO0OywakDojlW/aKDfmB7q0/11ZfP+usWyYh76rZheqwjQpNBRv12dyAC3v76Et0BBXIgcir/yjvV/GAaOzAdpy+G+R8+COPw3+4KnvABWATddLolBr0=" Nov 05 01:46:26 volumio go-librespot[14711]: time="2025-11-05T01:46:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:27 volumio go-librespot[14711]: time="2025-11-05T01:46:27Z" level=debug msg="completed keyexchange" Nov 05 01:46:27 volumio go-librespot[14711]: time="2025-11-05T01:46:27Z" level=debug msg="completed challenge" Nov 05 01:46:27 volumio go-librespot[14711]: time="2025-11-05T01:46:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:29 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:29 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129. Nov 05 01:46:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:30 volumio go-librespot[14718]: go-librespot daemon starting... Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="app state loaded" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=info msg="zeroconf server listening on port 38163" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="obtained new client token: AADSw0KN9ivQb5/ks4e1Y9LlSWT/PS4vs/SeJP/ZSo6BE1/Oz+DfKfhUthiHG0QvUv5n+Sg1k87vWwaA0qL2lk3p/K72wsNB/oTb8UWpoW+a61eyREqodVTYFwIrS3Y/jwOUcBSRV5K9CCvs8nv5Ps/uoK6DvaPJvde2a6ja4jJ5J+ZaNbR/+GhJRpgPX8767Dk9Tx4Hm18qQI56qjOS/830HZpVRqDrXAiq5AU0e8BI46QrQfWZGi8=" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="completed keyexchange" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=debug msg="completed challenge" Nov 05 01:46:30 volumio go-librespot[14719]: time="2025-11-05T01:46:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:30 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:46:30 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:46:30 volumio volumio[13728]: info: Listing playlists Nov 05 01:46:30 volumio volumio[13728]: info: Listing playlists Nov 05 01:46:32 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 01:46:32 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:32 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 130. Nov 05 01:46:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:33 volumio go-librespot[14740]: go-librespot daemon starting... Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=debug msg="app state loaded" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=info msg="zeroconf server listening on port 42445" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=debug msg="obtained new client token: AAD8usna7AenCAG9q0GBq95gxT5hCdngV9hV8jPlgWWxAAA6fT4sSANsF98abRz6HQ123cstfSTS85LRNcw2CrM44CGwrqRPmCtDLfc+bd1Tn5O0zFVb/m+aFjO/5365gX4GcKLwGyGdNTmtqUlNM4eOLSfixT0MToDCCPVUV3gd7/iBrfyrwI0n52oVXMUa2v4RTu1icE8CtmyeOE4CCkhEK9XUpODWdkAZmHZxz6bWLOEkqfOdNfk=" Nov 05 01:46:33 volumio go-librespot[14741]: time="2025-11-05T01:46:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:34 volumio go-librespot[14741]: time="2025-11-05T01:46:34Z" level=debug msg="completed keyexchange" Nov 05 01:46:34 volumio go-librespot[14741]: time="2025-11-05T01:46:34Z" level=debug msg="completed challenge" Nov 05 01:46:34 volumio go-librespot[14741]: time="2025-11-05T01:46:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:35 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:35 volumio volumio[13728]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 01:46:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 131. Nov 05 01:46:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 01:46:37 volumio go-librespot[14748]: go-librespot daemon starting... Nov 05 01:46:37 volumio go-librespot[14749]: time="2025-11-05T01:46:37Z" level=info msg="running go-librespot 0.4.0" Nov 05 01:46:37 volumio go-librespot[14749]: time="2025-11-05T01:46:37Z" level=debug msg="app state loaded" Nov 05 01:46:37 volumio go-librespot[14749]: time="2025-11-05T01:46:37Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 01:46:37 volumio go-librespot[14749]: time="2025-11-05T01:46:37Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 01:46:37 volumio go-librespot[14749]: time="2025-11-05T01:46:37Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 01:46:37 volumio go-librespot[14749]: time="2025-11-05T01:46:37Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 01:46:37 volumio go-librespot[14749]: time="2025-11-05T01:46:37Z" level=info msg="zeroconf server listening on port 45641" Nov 05 01:46:38 volumio volumio[13728]: info: Initializing connection to go-librespot Websocket Nov 05 01:46:38 volumio go-librespot[14749]: time="2025-11-05T01:46:38Z" level=debug msg="new websocket client" Nov 05 01:46:38 volumio volumio[13728]: info: Connection to go-librespot Websocket established Nov 05 01:46:40 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:46:40 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:46:41 volumio volumio[13728]: info: Getting Spotify volume Nov 05 01:46:41 volumio volumio[13728]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 05 01:46:41 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:46:41 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:46:41 volumio volumio[13728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Nov 05 01:46:41 volumio volumio[13728]: SPOTIFY: SPOTIFY VOLUME undefined Nov 05 01:46:41 volumio volumio[13728]: SPOTIFY: VOLUMIO VOLUME 33 Nov 05 01:46:41 volumio volumio[13728]: info: Aligning Spotify Volume to Volumio Volume Nov 05 01:46:41 volumio volumio[13728]: info: CoreCommandRouter::volumioGetState Nov 05 01:46:41 volumio volumio[13728]: info: CorePlayQueue::getTrack 0 Nov 05 01:46:41 volumio volumio[13728]: info: Setting Spotify Volume from Volumio: 33 Nov 05 01:46:41 volumio go-librespot[14749]: time="2025-11-05T01:46:41Z" level=debug msg="obtained new client token: AAAJdIMUfskLuiMkOm/iex54Qi65W9o1/+XaAgIMWR4QTOmb6a6XEJ3sHbAu6yNZEjiZ0vsZ1z/H2wH/CEZomalbGNQKw2owmjPDjDklCBuME4SGNpltDbipwxoYPoEggnLw+auPS2dpajqqDEf2KpRoQKYsRT+XlUH0i7wwxfjkxtkDrBy3wQES1JLyi4+uq+4+5ZVx1F/n93jBJYDRbEH69ThYkaIlCQDcHT70BCy7A1rChCRMbgM=" Nov 05 01:46:41 volumio go-librespot[14749]: time="2025-11-05T01:46:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 01:46:41 volumio go-librespot[14749]: time="2025-11-05T01:46:41Z" level=debug msg="completed keyexchange" Nov 05 01:46:41 volumio go-librespot[14749]: time="2025-11-05T01:46:41Z" level=debug msg="completed challenge" Nov 05 01:46:41 volumio go-librespot[14749]: time="2025-11-05T01:46:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 01:46:41 volumio volumio[13728]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 01:46:41 volumio volumio[13728]: Error: socket hang up Nov 05 01:46:41 volumio volumio[13728]: at connResetException (node:internal/errors:720:14) Nov 05 01:46:41 volumio volumio[13728]: at Socket.socketOnEnd (node:_http_client:519:23) Nov 05 01:46:41 volumio volumio[13728]: at Socket.emit (node:events:526:35) Nov 05 01:46:41 volumio volumio[13728]: at endReadableNT (node:internal/streams/readable:1376:12) Nov 05 01:46:41 volumio volumio[13728]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Nov 05 01:46:41 volumio volumio[13728]: code: 'ECONNRESET', Nov 05 01:46:41 volumio volumio[13728]: response: undefined Nov 05 01:46:41 volumio volumio[13728]: } Nov 05 01:46:41 volumio volumio[13728]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 01:46:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 01:46:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 01:46:42 volumio sudo[14773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-05 01:45' Nov 05 01:46:42 volumio sudo[14773]: 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"