Nov 01 14:17:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 210. Nov 01 14:17:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:00 volumio go-librespot[27517]: go-librespot daemon starting... Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=debug msg="app state loaded" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" 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 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" 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 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" 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 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=info msg="zeroconf server listening on port 46515" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=debug msg="obtained new client token: AACO/bRXimeAztqtJhz58C+DhuVytV77ow/L3SKqDz/+w/MO9xUxWRyRlqtFdRYSYpKD5/p5+O5SUQQ/+1KxworagqS7TfA+7MUey12lOtkpWlA1QcRpRYii1W8WiAlesq6F3C+NDXEy2TmSzFkFZhlbSt70dwbNuwob34JKYwEGp/X6n3ljkXG8SIgkIzkFRoZyCPDNi0XBCTRiMKOV2usW+7blVMqoVP8IiYuDxwa8xpldMTkPQZA=" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=debug msg="completed keyexchange" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=debug msg="completed challenge" Nov 01 14:17:00 volumio go-librespot[27518]: time="2025-11-01T14:17:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:02 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:02 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 211. Nov 01 14:17:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:04 volumio go-librespot[27540]: go-librespot daemon starting... Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=debug msg="app state loaded" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" 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 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" 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 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" 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 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=info msg="zeroconf server listening on port 43197" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=debug msg="obtained new client token: AADRqtB+WxnT0i8zwFd+yaSYYqq8oeiVPcJr0dUA7WAjtkCJK3WpV/MFcHg7rh4h2g+/AUTcJnulw44YuO+etdO9wqk/aaCSYZtaF+WTCcK8Ep24ltzzoH4Ep3xH/Qd7RNVEeWI+xSmvo0jM57G1G3FRJGt7cl3/Yfv4EWF/IK0n/WNVLrBLvWqIcjL0KVEqzZ4l8qWPcE4kVHWWs4iKpGeYKSmMMCEVX2zaJjykCYIPxqu1nC/d" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=debug msg="completed keyexchange" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=debug msg="completed challenge" Nov 01 14:17:04 volumio go-librespot[27541]: time="2025-11-01T14:17:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:05 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:05 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 212. Nov 01 14:17:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:07 volumio go-librespot[27551]: go-librespot daemon starting... Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=debug msg="app state loaded" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" 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 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" 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 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" 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 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=info msg="zeroconf server listening on port 46475" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=debug msg="obtained new client token: AADm94qaZ9mRyO7xUPcbU6jrn74POJ4jw2JwZ6Zx2AYDDz2WfbjD2NRKiHsHvX3GC1dYwT1eiUrty4iampa0Fdz8gqL5yJPWR302Mc4TujJuMj2l2/XPssf8em+G3flRIx/u7K7Sv4Fn8wn7esHiBQarqL06ttR88GLmWU8U1tvjO4fqPXW8i+5ySKeKUMpa6Kwel0c/Nb3hOYgLUDAOT8L65c43DP7I6NkCXXyf7HI3pK/FjKqWyrU=" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=debug msg="completed keyexchange" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=debug msg="completed challenge" Nov 01 14:17:07 volumio go-librespot[27552]: time="2025-11-01T14:17:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:08 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:08 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 213. Nov 01 14:17:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:11 volumio go-librespot[27559]: go-librespot daemon starting... Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=debug msg="app state loaded" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" 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 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" 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 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" 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 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=info msg="zeroconf server listening on port 41867" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=debug msg="obtained new client token: AAC7heCxjBzevPNeLoi1rR/HCgggHIXQXEJqpdzCbxs8r0uEl2Qp69jbbvlhfsVOUt0EK+7RU+0THEXb+jBkN6UQN9d1iuu3gGmvlpETTJCySEZjTbELtnLmTtXS1hceS0TfWtkecy7N2TJMfuHUOPx5aL75EgN2IhCTn3SCVmI/Sisdn18wxfwt9bKPXN1CSKlcTP/q7BcbdPERNJbTnK2cWJFlqNP3ObEPPsfBCdxIc13WTwRh" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=debug msg="completed keyexchange" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=debug msg="completed challenge" Nov 01 14:17:11 volumio go-librespot[27560]: time="2025-11-01T14:17:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:11 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:11 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 214. Nov 01 14:17:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:14 volumio go-librespot[27581]: go-librespot daemon starting... Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=debug msg="app state loaded" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" 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 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" 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 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" 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 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=info msg="zeroconf server listening on port 33237" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=debug msg="obtained new client token: AABUCwPvFDQ1XEf9JDSUYCWEhROITOHtUXqSKGeH+dGlm21DmXUY0ArCplWzKpgz57svdjAfrpCnCwD7yg1FqRop+Hp0Zbet34E/vW9M5zUovfaQCcQzdDkupefcR8PcZISS+eQZkRERcumL/PuBEMKPaxLlKO78xV6NfSzRolfcRG/QYHMLQ/Mpsx14EOfzER55g9zglZvylD0dtV4wfbteIGvcHJ6dASbYwg5w3SvMFsTUlo03WwA=" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=debug msg="completed keyexchange" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=debug msg="completed challenge" Nov 01 14:17:14 volumio go-librespot[27582]: time="2025-11-01T14:17:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:14 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:14 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:17 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:17 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 215. Nov 01 14:17:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:18 volumio go-librespot[27589]: go-librespot daemon starting... Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=debug msg="app state loaded" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" 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 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" 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 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" 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 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=info msg="zeroconf server listening on port 43801" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=debug msg="obtained new client token: AAANcbZ/lhnUJQCFsEFfIUzUkUkoYY+SaFbvqneKmjBn8cyY5sfXOMisWhtExccu/kW52GfVovm8XyC6w2fobQ17VhtUmpGqfN7CoAq3ql+OaWJ4NsbV5O3xZc/vDPHAtFgFcSOBTPwJ7/9CSGOXNs+ej/GjsTICOvTQv2HOZTyMRLgLPzWXarmoCrB9LRSwYIcj1AGX526VKju1dKHDwTNzAaX2OPXxg7h4UMOPCOwoJoYkLJTF" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=debug msg="completed keyexchange" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=debug msg="completed challenge" Nov 01 14:17:18 volumio go-librespot[27590]: time="2025-11-01T14:17:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:20 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:20 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 216. Nov 01 14:17:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:21 volumio go-librespot[27612]: go-librespot daemon starting... Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=debug msg="app state loaded" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" 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 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" 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 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" 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 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=info msg="zeroconf server listening on port 41309" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=debug msg="obtained new client token: AABjRm/H4GzExVGwytlHeMHGG9PZtWuzktDfnb1qYr+DBUr5bY4VseSjnNkzKq4kuEOBi7YBQuxFdYPkLT4/9rHSvuidjFAXO33HWnN3cwR/KBC8K/LSoYufDWn1rARpg5760m++4zqbw6kfbGdpXY8jsogdonCzdxUtD03Pcm3Jd4D/vCa++2HMlonnA7Ka4aYJTl3rub10Lt5g0uO79KdBzPeTcwq6oPiFGn7HsiyWv/EIQqqByu0=" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=debug msg="completed keyexchange" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=debug msg="completed challenge" Nov 01 14:17:21 volumio go-librespot[27613]: time="2025-11-01T14:17:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:23 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:23 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 217. Nov 01 14:17:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:25 volumio go-librespot[27621]: go-librespot daemon starting... Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=debug msg="app state loaded" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" 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 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" 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 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" 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 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=info msg="zeroconf server listening on port 42673" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=debug msg="obtained new client token: AACnMbFbvGRAyzkD9JQ/R+n0JHTD4lDFZRP6a6N+TbSEfuxKIxnFVxS+vsq837pfclJb9Tts2ApXPKimZgKTCgKvaIS2Ex+O1NWVUSdHyVawLcoQHdQYi0+g2tUhMeDJb5PR3VIGIQYwMf0EaNCTDtOcqH590zlDe/PdiIfZDo763w+2+AWXLcrutFF0RqgVgWUOJNTa/NeXPsIjxMzi30Cg0Xz+DPGiOQ0EBJF8F1LEXcUmkko9" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=debug msg="completed keyexchange" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=debug msg="completed challenge" Nov 01 14:17:25 volumio go-librespot[27622]: time="2025-11-01T14:17:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:27 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:27 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 218. Nov 01 14:17:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:28 volumio go-librespot[27632]: go-librespot daemon starting... Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=debug msg="app state loaded" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" 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 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" 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 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" 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 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=info msg="zeroconf server listening on port 33193" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=debug msg="obtained new client token: AAAZwP1ZBBMCuiM64vBY4z0lljRG8TJ7lq/IwvMohiivXbw1NtMJx6xrlp+5drh1562xgOftKDkGabVNOA0zXlI9TGBNiB+/zadyOzZz0m3zSQoFlpzJ2h4/n+g724pWyAvWHPxfeFUSYxNkunRZFynQh3u8YZrD8TYvXmj86fBVQMQQlwtDO6gwCroBJLnC8fi7K3HEhhv9bPY43bEEzicAdGVdXAz4f6f6iLU6PUfEgjNR6OfvkgU=" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=debug msg="completed keyexchange" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=debug msg="completed challenge" Nov 01 14:17:28 volumio go-librespot[27633]: time="2025-11-01T14:17:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:30 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:30 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219. Nov 01 14:17:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:32 volumio go-librespot[27654]: go-librespot daemon starting... Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=debug msg="app state loaded" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" 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 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" 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 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" 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 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=info msg="zeroconf server listening on port 45303" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=debug msg="obtained new client token: AABMs2kZY/PQAeQmw+Zdmj+4ZO8u0518dtctnckVO8Rvz8EV+Di1C8M7tbbmKX8gH/0VTMVARaLyfYb64iNIol8W/LmvXU08pXZ6mPMgDalOABnUs6/qcIx5Q2qBG7SZw5aRK5ZkVgxO2MHeJT2ygI79WHFYB+jLYF8IxmOZCrv8cDySgwrZ7TRepeDAVpQcNXHlAAgYudO4JMrwIAeHhsUIdsRM+lR5PD23NKtaMQy4xcr+2EqK" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=debug msg="completed keyexchange" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=debug msg="completed challenge" Nov 01 14:17:32 volumio go-librespot[27655]: time="2025-11-01T14:17:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:33 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:33 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 220. Nov 01 14:17:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:35 volumio go-librespot[27663]: go-librespot daemon starting... Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=debug msg="app state loaded" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" 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 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" 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 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" 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 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=info msg="zeroconf server listening on port 46277" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=debug msg="obtained new client token: AABo0sd5nnAKO8adoni4NMFc8GN1g42nt7CjxIOz4zc479law/KNMvW+msWpQezRkpOv8cxeB848fce2QFUHUHmvgN8FSx5qgApbqol+ozNSEO+XGYGwNyf60A/0UofaOP5yKMvgS2HQsPS52t7UDNkomosMit146c5u00rn9D62yX1FmnXUQN4ezmJZSiuoZhNkLM1pyycvpYzV0Rm9W4PSOz332djN9w56JJ/83zeqgCgkyNRKdsw=" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=debug msg="completed keyexchange" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=debug msg="completed challenge" Nov 01 14:17:35 volumio go-librespot[27664]: time="2025-11-01T14:17:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:36 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:36 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:39 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:39 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 221. Nov 01 14:17:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:39 volumio go-librespot[27671]: go-librespot daemon starting... Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=debug msg="app state loaded" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" 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 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" 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 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" 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 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=info msg="zeroconf server listening on port 37819" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=debug msg="obtained new client token: AACBqURmKt+nvnKTaz2twsueu6ZMhMq2d8//bm6guM239rmNeWYcC8boKSStQX81fFHM/vezRNUlMqBlLutrNSCWlJhwzuSgjoOZPns/rsjzn8DjY7ryLqAnMCNfpp6CP2MJ/ypji9zwtzuXGmxM10+RybOZOv8ERourgaq6tolWQwvHK91xyvtJnuiWl/OqzkWxAnAwqkauabdqO0u/esEofjfRRv8X/9OGF+D7Z7RXYnmrrVpM" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=debug msg="completed keyexchange" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=debug msg="completed challenge" Nov 01 14:17:39 volumio go-librespot[27672]: time="2025-11-01T14:17:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:39 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Nov 01 14:17:39 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Nov 01 14:17:39 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Nov 01 14:17:42 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:42 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 222. Nov 01 14:17:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:42 volumio go-librespot[27718]: go-librespot daemon starting... Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="app state loaded" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=info msg="zeroconf server listening on port 33537" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="obtained new client token: AACOeEP85bBKfsl6Drfy1cXIW0CbiA5gGBLjEnhoC++oBuA32GfI4jsgB8ENcJ1RiMsWsv1V7i600PnFfEAeFLXV2cC1v+76o/PNMetvYZuf5q3TVR4GeQ9hAXOeEgY+3f21h45lZy90A+CsONL5J+Qa8AT5jLBW95xzgFn0Of+S77aHqzIvTyKdNbEPH/Uzj5owJxtXqDv9ILUqxDqvUHaxmnQpkqgrOKtDnhlFY81IhvFwCo/uxMk=" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="completed keyexchange" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=debug msg="completed challenge" Nov 01 14:17:42 volumio go-librespot[27719]: time="2025-11-01T14:17:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:45 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:45 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 223. Nov 01 14:17:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:46 volumio go-librespot[27727]: go-librespot daemon starting... Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=debug msg="app state loaded" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" 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 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" 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 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" 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 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=info msg="zeroconf server listening on port 45303" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=debug msg="obtained new client token: AACllIvcxVoK6MQzYvWCwQBo8f9brJaq63vc71WuzVncgM17Aal5YBvswUvQFzxDEsePirv+e0gZ0r5zSHD/2yHgIBMdeGa1/fUd+YXLjSL1ZENIcE1tbBTK5K9h9z589b3xfqYgbxdtvlFgOhEALTZCZ4XZ6vIRf5iF/WlzcQRHbhf+qmRXuPb8fHu293mMmrFsoal5B7dQFv69P+vcatElUQGZu6dV3Mse+l5RLx8K7yHI+M+a" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=debug msg="completed keyexchange" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=debug msg="completed challenge" Nov 01 14:17:46 volumio go-librespot[27728]: time="2025-11-01T14:17:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:48 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:48 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 224. Nov 01 14:17:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:49 volumio go-librespot[27738]: go-librespot daemon starting... Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=debug msg="app state loaded" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" 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 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" 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 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" 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 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=info msg="zeroconf server listening on port 38225" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=debug msg="obtained new client token: AABG8qI4IJ4NWsJZKylH0aMOIilAi9I27qyooADrea0kCCXO9cb3fklV80CTwgRDOj5qERhAdT8U7z63uDlBthqhgP5yyZyCKlveJYEXDe4F725MQJYfEsV61Y0rvGxO/5/TJloS2KoiQ+TvyhtBoRqGkrkwulrCr0Hi+uD8Ueg2K5Ngbi0yvDVNsXIUrGYalzgf9PWja9wV8sJ3aIEWrwaf/xw7T+ckeTjkxtf13+HW0bJ6CaDYhOs=" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" 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 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=debug msg="connected to ap-gew1.spotify.com:80" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=debug msg="completed keyexchange" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=debug msg="completed challenge" Nov 01 14:17:49 volumio go-librespot[27739]: time="2025-11-01T14:17:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:51 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:51 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 225. Nov 01 14:17:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:53 volumio go-librespot[27761]: go-librespot daemon starting... Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=debug msg="app state loaded" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" 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 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" 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 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" 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 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=info msg="zeroconf server listening on port 46809" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=debug msg="obtained new client token: AABoQwXOflivMi20KFUtV2qPVMQLIg/DZFD/S/Hy/h9wrVCaxUGvX+NH63IFZcavNS2DGWsIGLydaI7EvtaFSMpa5xCjzWk8cJvgmMy+r54ufVZ/fXwGkCvXrnjmoYOj8+tRCdJKUOt0zm6hYo17oIvviLlAe3NnRtg3mIBufAElL21Lfve6oVlDBZjC/Ttdb/85bPc/9wn/EV4VeO3fsNxnK/fdr4+UoSFwPH5VC2QviNh9bC8t" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=debug msg="completed keyexchange" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=debug msg="completed challenge" Nov 01 14:17:53 volumio go-librespot[27762]: time="2025-11-01T14:17:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:54 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:54 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:17:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 226. Nov 01 14:17:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:17:56 volumio go-librespot[27769]: go-librespot daemon starting... Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=debug msg="app state loaded" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" 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 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" 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 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=info msg="zeroconf server listening on port 45289" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=debug msg="obtained new client token: AABR4bB8/AExreMPZZ/oh6P9YqXcgbDOtun5u1VOjY5a+5yi1qjVkICrsiL82kMw7ADQqICqgcBo7TAwJNTS5f/r0x6SNEGHMajQxgP97E68RI6KZxEmqleCdUB9G1YwlkIDU0K315UOBOrk3BCHheTOgBOd2scF66M6+fDI+GpnTjG588xRR94WgMmvjZyEzRg4YxiiHh/mc55WocaV+H9Sd2A6FJaFm6hcZpc8vXqKBtVnsvfv8OI=" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=debug msg="completed keyexchange" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=debug msg="completed challenge" Nov 01 14:17:56 volumio go-librespot[27770]: time="2025-11-01T14:17:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:17:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:17:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:17:57 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:17:57 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:18:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 227. Nov 01 14:18:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:00 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:18:00 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:18:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:00 volumio go-librespot[27777]: go-librespot daemon starting... Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=debug msg="app state loaded" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" 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 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" 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 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" 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 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=info msg="zeroconf server listening on port 33371" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=debug msg="obtained new client token: AACxj5GX8c1M1zc4JH5C1r4jgwCZQGHi1vLZ5OSqs8QiZHX7OG2oyBIVvT+QeoY/6i0DkqqwAIbAENXkbHBqTOk/Hldf+MzB+4K25zWRTYxytlzud5hlt+1hsr/XHAbsMXBv+j/YiwQZKtk5GgQi5xsE2s+6wPeyTdjcGwYlEdNtFVDspA+Cro28ZUib3/oBmtoGs9+NToWBZS3UjG8tn+fTar9Pddwcr5a8PY40/UcWA4bZWJOe" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=debug msg="completed keyexchange" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=debug msg="completed challenge" Nov 01 14:18:00 volumio go-librespot[27778]: time="2025-11-01T14:18:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:18:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:18:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:18:03 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:18:03 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:18:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 228. Nov 01 14:18:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:03 volumio go-librespot[27800]: go-librespot daemon starting... Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="app state loaded" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=info msg="zeroconf server listening on port 38841" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="obtained new client token: AACMz/UUYA3x2jKCg9LRdm/yKjIqDO1Qgh7Od6l7X690FA2rfVNyr8D62X4+L1lCpnWPEOdgo36FAaZohz5modZTRQKVJ13FOWyuMjQGlIIi31MfchV8V+E/eQNROheOjL/lXVUmWxGbbKHeqLUHzvpvFlIViK1KaHIU51AUgeRNMyt4jtlVI81cd6FDIVKK1iYF+6KBzcsdcd+4Rc+6AFUoDhWEAXgV6c4d8buUcjbtBaEpoLkDVZQ=" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="completed keyexchange" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=debug msg="completed challenge" Nov 01 14:18:03 volumio go-librespot[27801]: time="2025-11-01T14:18:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:18:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:18:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:18:06 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:18:06 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:18:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 229. Nov 01 14:18:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:07 volumio go-librespot[27811]: go-librespot daemon starting... Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=debug msg="app state loaded" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" 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 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" 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 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" 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 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=info msg="zeroconf server listening on port 46555" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=debug msg="obtained new client token: AABcORvrFleU81TzTuDmblXQilEFb+GTYkBVwAeLL1tu+VSqnOkbVams9JCeYq9KBWff9l0W+G8/deFBjCMT4FLEoQqy5kzR26j/Wn/9gwQNmyncG3EZnG8Y/dP3n+6Xk8PzSM9oEvQZ36b//ATlSQgnMem0Hy1PSgTdpC2T+4TNhmU1ZQvFJagDHFEbirr6CYjDWTvcbmo0BlIUNKVCrujC8NROARffT4G7IV5qwBpDXRD60hs9" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=debug msg="completed keyexchange" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=debug msg="completed challenge" Nov 01 14:18:07 volumio go-librespot[27812]: time="2025-11-01T14:18:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:18:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:18:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:18:09 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:18:09 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:18:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 230. Nov 01 14:18:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:10 volumio go-librespot[27819]: go-librespot daemon starting... Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="app state loaded" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=info msg="zeroconf server listening on port 43889" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="obtained new client token: AAALUAyg49lbmU1dDQV8FVt+eYURcPT04z540YALBkLQ33phvyqtNO3wMaK9nqEUHtSd4pugGh5GNZCjg5D1f37nI1asGddHCuZdZoj7I13rzpBxMJmf4MewpEtv4hlFITytVCLWO84fOaNJzS1cnp6LJdh3Fc0xttYPm25RvULoPWPVbvHrBsxIXgA/wupkA45oPGUZa0Y0kxzrX4IR1wXpx+cgtay96blhNhhO5oOxqeKWHQaYXfQ=" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="completed keyexchange" Nov 01 14:18:10 volumio go-librespot[27820]: time="2025-11-01T14:18:10Z" level=debug msg="completed challenge" Nov 01 14:18:11 volumio go-librespot[27820]: time="2025-11-01T14:18:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:18:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:18:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:18:12 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:18:12 volumio volumio[26352]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:18:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 231. Nov 01 14:18:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 14:18:14 volumio go-librespot[27841]: go-librespot daemon starting... Nov 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" level=info msg="running go-librespot 0.4.0" Nov 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" level=debug msg="app state loaded" Nov 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" 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 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" 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 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" 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 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" level=info msg="zeroconf server listening on port 46663" Nov 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" level=debug msg="obtained new client token: AAAmtM1Y16qxoy8WfKXcC5MUoVITPZVbMvhYn/Z1HBWxITBu57DwyNm1NLjjpYPDLMjtTJ0aFZ/6mRepFNIPyKbAKMgENo2R/fOzIOxL++3Ort8etybeP433rw9ymbsg6Ijz9VnG0M+U/MKQVBTqCArs0u3lJIfRKEZFq31eoUm28gdFBivOMQ0UhATu8MQMXMFMCdIzb7RhGLsQLCuUvh/yLVJdnwhpCn5YrwtAnr48RISePfHy6dg=" Nov 01 14:18:14 volumio go-librespot[27842]: time="2025-11-01T14:18:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 14:18:15 volumio go-librespot[27842]: time="2025-11-01T14:18:15Z" level=debug msg="completed keyexchange" Nov 01 14:18:15 volumio go-librespot[27842]: time="2025-11-01T14:18:15Z" level=debug msg="completed challenge" Nov 01 14:18:15 volumio volumio[26352]: info: Initializing connection to go-librespot Websocket Nov 01 14:18:15 volumio go-librespot[27842]: time="2025-11-01T14:18:15Z" level=debug msg="new websocket client" Nov 01 14:18:15 volumio volumio[26352]: info: Connection to go-librespot Websocket established Nov 01 14:18:15 volumio go-librespot[27842]: time="2025-11-01T14:18:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 01 14:18:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 14:18:15 volumio volumio[26352]: info: Connection to go-librespot Websocket closed Nov 01 14:18:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 14:18:18 volumio volumio[26352]: info: Getting Spotify volume Nov 01 14:18:18 volumio volumio[26352]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 14:18:18 volumio volumio[26352]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 14:18:18 volumio volumio[26352]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 01 14:18:18 volumio volumio[26352]: errno: -111, Nov 01 14:18:18 volumio volumio[26352]: code: 'ECONNREFUSED', Nov 01 14:18:18 volumio volumio[26352]: syscall: 'connect', Nov 01 14:18:18 volumio volumio[26352]: address: '127.0.0.1', Nov 01 14:18:18 volumio volumio[26352]: port: 9879, Nov 01 14:18:18 volumio volumio[26352]: response: undefined Nov 01 14:18:18 volumio volumio[26352]: } Nov 01 14:18:18 volumio volumio[26352]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 14:18:18 volumio sudo[27863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-01 14:17' Nov 01 14:18:18 volumio sudo[27863]: 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"