Nov 01 15:02:00 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:00 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Nov 01 15:02:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:01 volumio go-librespot[7847]: go-librespot daemon starting... Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=debug msg="app state loaded" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" 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 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" 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 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" 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 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=info msg="zeroconf server listening on port 39475" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=debug msg="obtained new client token: AACbB7R0vdYYc4UUIvc08c8Q3wdHbF6JlPqANxfdJTY2kEY4QoC1ONtt3Qj9WasTNx1SR46G6OePa0UG2uLmIrMz6U71qc6c77r25MfVFeehaYyFEUk56iN7mr5TNdnFmjfaOCq197UnQcxRuoUvnd/0dpiamYxGvYsO9GtDXoNkJ7XRIm9FHzxiek8CztXrJr0Jf1NcQ9Fg4ICp2jarXuJuu2+WuFB4SEXrB4Hq5dQh4IJFDjTFam0=" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=debug msg="completed keyexchange" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" level=debug msg="completed challenge" Nov 01 15:02:01 volumio go-librespot[7848]: time="2025-11-01T15:02:01Z" 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 15:02:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:03 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:03 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Nov 01 15:02:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:05 volumio go-librespot[7856]: go-librespot daemon starting... Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=debug msg="app state loaded" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02: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-gae2.spotify.com:80]" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" 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 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" 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 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=info msg="zeroconf server listening on port 44187" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=debug msg="obtained new client token: AADFXTFc6oKXjqunqCbIpnkQjggBZziZKQvt/8M2zXrkbPugTMTEnXy6BP6hqCubWl0IzsnmxEcUpOyQZvgHI/RtslPDi2IoZuMYN0krSEcsc5Y16HehbVnOICxgDENKMsGIGelECb0SizS/NuqlbnI+hSwiKKzZC+X1geF4vsnvAPw3c7CjxcFqjV6xAGABBtkLVXvFS1JTvWEqNduAB+U+Brg1mC2a7wn84LzE1odCKotmSHtV" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=debug msg="completed keyexchange" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" level=debug msg="completed challenge" Nov 01 15:02:05 volumio go-librespot[7857]: time="2025-11-01T15:02:05Z" 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 15:02:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:06 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:06 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Nov 01 15:02:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:08 volumio go-librespot[7864]: go-librespot daemon starting... Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=debug msg="app state loaded" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" 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 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" 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 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" 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 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=info msg="zeroconf server listening on port 43657" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=debug msg="obtained new client token: AAA2AEtzPpkqS7408QTMibcv/65GZ7hkZ1/xhr4u2JN71hBrnUquNzm/uF9C5hC7kr2fG8tTZQiEmV4klHwIzjZUzcKDF3xbSKx4E1gj80I+uq/Ue69QPdULNSFiwKWWUkMaywYCi7FML62xbYUQ+VIDcSmKdLeb4+sCdrKBCbtOrQFu13+tfEaZSVAFNIw7fM2GeF/h4e7q+K6p5lLfOm6/YAbiDIGjt0P2hkr4ONoDPDHc+SX5vss=" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=debug msg="completed keyexchange" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" level=debug msg="completed challenge" Nov 01 15:02:08 volumio go-librespot[7865]: time="2025-11-01T15:02:08Z" 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 15:02:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:09 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:09 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Nov 01 15:02:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:12 volumio go-librespot[7887]: go-librespot daemon starting... Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=debug msg="app state loaded" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" 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 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" 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 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=info msg="zeroconf server listening on port 39389" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=debug msg="obtained new client token: AAAEJwK3ratys4r30Yszs9voCmvIRrKWmPgxLgtZtv2uNmykf0GUVGU5paBM56OBwWiFGTzKStK9DcUseQXk8Qn+waz8phqMpSgCpbU0lpEJzFbMqY+gtAhIDe03yTAx4BWTJr53+XnH33EtRqfaaID5CfIUUDXeFYlpY19g8+zWE0SNS8n8vtg+GOsAplLoXP1olq+ItezbQRt3QrEbz8EIhSBgSOY2OJGGRCi3MvZrEzlucjfx" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=debug msg="completed keyexchange" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" level=debug msg="completed challenge" Nov 01 15:02:12 volumio go-librespot[7888]: time="2025-11-01T15:02:12Z" 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 15:02:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:12 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:12 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Nov 01 15:02:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:15 volumio go-librespot[7896]: go-librespot daemon starting... Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=debug msg="app state loaded" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" 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 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" 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 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" 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 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=info msg="zeroconf server listening on port 39337" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=debug msg="obtained new client token: AAAS9HCv4WE0ORH574Qd7sODFjAmUHkNzNhLRx1v42p6DPpfsqJ/L3Nvw1NySAFg/6kD9sbKWZQWDjVUQdPq30yI7ccXppHWiuTkhCcTYGBg7YYA7iZ9KyOzNdgVvs5aYfexjBJAAam+gBcdYT6HzsNpsFsRUM/pw79CGXYkQs5M+2qlc82A7VkjOH3Op0YoYgUU15JJtxAki1RfBLR3Z6xWQqTo5brDAoPBNMMo7fk7Yyz1FGDKj8c=" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=debug msg="completed keyexchange" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02:15Z" level=debug msg="completed challenge" Nov 01 15:02:15 volumio go-librespot[7897]: time="2025-11-01T15:02: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 15:02:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:15 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:15 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:18 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:18 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Nov 01 15:02:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:19 volumio go-librespot[7908]: go-librespot daemon starting... Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=debug msg="app state loaded" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" 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 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" 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 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" 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 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=info msg="zeroconf server listening on port 41047" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=debug msg="obtained new client token: AACeYHXyK1TVDybnJSqhN9I7FkE1IPaSvV03TBSYFrXTMO49fNDGLwe6TORkc8PJad21CUmKGOgD1lQAmaP6bqAE74dBwrxKWU7qk6Stl0OyVnL+BFnrV0iWCT2Gt+faEYKPIM1zJFLjyVEi2mJpjy/1c3sYenAPkD8WvC9tygiBItUcYBrg2jnW9Av6PNcbyyxzU9cnM0gjS8tBOVfvt4117uBwCSnmVNbmoKsifmp1GZTb48Pm" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=debug msg="completed keyexchange" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" level=debug msg="completed challenge" Nov 01 15:02:19 volumio go-librespot[7909]: time="2025-11-01T15:02:19Z" 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 15:02:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:21 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:21 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Nov 01 15:02:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:22 volumio go-librespot[7930]: go-librespot daemon starting... Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=debug msg="app state loaded" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" 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 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" 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 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" 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 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=info msg="zeroconf server listening on port 36309" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=debug msg="obtained new client token: AAD0eGIpJrcQ44OD1nbSXf+snqjfYuEfv2GT5Sv+PwuM02o3ewbGjZKL4A/KZPF0j1kGGhVTw3NkKb6wiSB6HZ3eECsHBbB1ukXw3V1zTruhsbOl0jqkZUwn7feuwjQdN/c2thlA33V4t5VtH+ieXafFv08gQeowqchrVYNDWpySuF2REvyMDq4/zOjpCFR/EuMNG3evae+zf7wner8MbTJOVpjWy3qzYXShhw6qO+ePJHG7NYCZCcs=" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=debug msg="completed keyexchange" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" level=debug msg="completed challenge" Nov 01 15:02:22 volumio go-librespot[7931]: time="2025-11-01T15:02:22Z" 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 15:02:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:24 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:24 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Nov 01 15:02:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:26 volumio go-librespot[7939]: go-librespot daemon starting... Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=debug msg="app state loaded" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02: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 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02: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 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02: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 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=info msg="zeroconf server listening on port 35835" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=debug msg="obtained new client token: AAAqbNFE/ZUO6ipruL+QGJA4jPRCR/CZsbzVuDp/cGpBM9lI5qZMmEr7zamXXAhFYkfCiy79ZT6xGIExG03hEkA4u8HcbLm+eiKUFY4A+e1F6K/oNGqIU4b2U0Y5iuyM11dgGnRWenhM/bGSz8np/zLqQfNaC/G7KrnXQRqSygQD2f4lonPmm6UWbcJ65y+WOZ4Lps8/4qUezn3Wi7AAogQTiPOMcstX2mHt6Caqi3Uat68+ZnfB" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=debug msg="completed keyexchange" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" level=debug msg="completed challenge" Nov 01 15:02:26 volumio go-librespot[7940]: time="2025-11-01T15:02:26Z" 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 15:02:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:27 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:27 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Nov 01 15:02:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:29 volumio go-librespot[7947]: go-librespot daemon starting... Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=debug msg="app state loaded" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" 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 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" 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 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" 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 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=info msg="zeroconf server listening on port 35675" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=debug msg="obtained new client token: AADpBDvfOTDRVYjPBm4C+5/xeF26IySPRx5FZuJIvdn+BCtlEgRefokU6g17JBfk3I2HZVymTfgZn3lW1HmjLoJJ/99PVAtf2wOEaiIei+OTiVJ3CKL6gQKkZoyfK8sjLSS9xrLBrG5c/zr2pXjcbHpWa8Tvvat5TaMortklgAMQKw5HHGG4m1tADxZEd2qwZfZ3Xef1Ub5iL1u1j7zLn151Od8gpPcrM0o1eKqDaUT/ArwStf4V7u0=" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=debug msg="completed keyexchange" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" level=debug msg="completed challenge" Nov 01 15:02:29 volumio go-librespot[7948]: time="2025-11-01T15:02:29Z" 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 15:02:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:30 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:30 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Nov 01 15:02:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:33 volumio go-librespot[7969]: go-librespot daemon starting... Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=debug msg="app state loaded" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02: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 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02: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 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02: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 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=info msg="zeroconf server listening on port 40083" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=debug msg="obtained new client token: AAAg3QZEnFbgBjspC8u4qexYVJxQkRIhwA0kZloBEif4T2gpYD6YZUz4a9GSLdJDutjQRo0lZIKBZPB1je/+eR9KaPVXviea7x63tHoSTJRz8gR0CcGeg6m5SLBJWkCJ9pAPYwfPGEYAgbv5L5aGSUNXFDhZzSgLaMna9KjX36lHjUXpZ1CV3sCWG3HKafzVBv9bGnER792PvgQ4v3Q9ySRFIu+pwkdOpHE17WuybP2X6rJNBK9T" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=debug msg="completed keyexchange" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" level=debug msg="completed challenge" Nov 01 15:02:33 volumio go-librespot[7970]: time="2025-11-01T15:02:33Z" 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 15:02:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:33 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:33 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Nov 01 15:02:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:36 volumio go-librespot[7980]: go-librespot daemon starting... Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=debug msg="app state loaded" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" 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 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" 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 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" 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 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=info msg="zeroconf server listening on port 38691" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=debug msg="obtained new client token: AADnntYpBmMb68kA+Jyj3eNu0VstvWWqXxTCHZMUs4A3z9fgBhj5+0Wx4XjbLWPj1/g7Lw8tFLVfj7OgOTS7mbcvuX5HIe3ja1lrktvFAajBFWt/NQXny3C56bUel8FDOFuKDahYOzUIG3r9Am5Wn4PNFGY4b9ceUeSsnIqPtcZT+bUj+pXk1a7pYvwKkyo/9X733K9TtBIeiZ3FgC6i3Gl04h3uclfmeGB5a7YPd+zF2fNT6V8yy78=" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=debug msg="completed keyexchange" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" level=debug msg="completed challenge" Nov 01 15:02:36 volumio go-librespot[7981]: time="2025-11-01T15:02:36Z" 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 15:02:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:36 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:36 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:39 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:39 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Nov 01 15:02:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:40 volumio go-librespot[7988]: go-librespot daemon starting... Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=debug msg="app state loaded" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" 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 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" 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 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" 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 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=info msg="zeroconf server listening on port 36023" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=debug msg="obtained new client token: AABK7QCZwv8J5NetsFkcmzKnOdpe3l6hBiPCST2iLVma9CbBBYl4SMhIVxByf+hCixLS08F8z/5eOtcgdEBOw+yADgPQsgQimyb8bC2xImBVaftHuT+nD9YbokZstStG93ewmmuRKwsjAPU39ORVoBSlLnJdDrpFiqAvkPqlEnjf/dOODHL8JRjXkxRLtRc2Y8/70z4VyZtxWT2m4+zSf/RyAl56E30nB7uIOYe87ZJQ2FDOMJT6" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=debug msg="completed keyexchange" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" level=debug msg="completed challenge" Nov 01 15:02:40 volumio go-librespot[7989]: time="2025-11-01T15:02:40Z" 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 15:02:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:42 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:42 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Nov 01 15:02:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:43 volumio go-librespot[8011]: go-librespot daemon starting... Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=debug msg="app state loaded" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" 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 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" 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 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" 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 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=info msg="zeroconf server listening on port 36369" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=debug msg="obtained new client token: AADhgM+jaJpr6M8KooNRqB42UJAIK7bVb4G03IZSN0M8TSPEL1N0Rm1VYJFUV8naBV8vRInkeMBMaOIwNuQR+U4alBoCC9GPfCO4zEMMCB5OuS05+d6rsRiUwfM1njLd3gzf6nui5BOh6UAUEhbp/dlD+4sAXGzVDgCfS3MR5wv0do0s2wRo/bo7QBAvziphrZjVHnEFlBP02rY7B8eUmku9H5m2iBIcfA5XgZ6nipv/PMOCKOGWgFc=" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=debug msg="completed keyexchange" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" level=debug msg="completed challenge" Nov 01 15:02:43 volumio go-librespot[8012]: time="2025-11-01T15:02:43Z" 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 15:02:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:45 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:45 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Nov 01 15:02:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:47 volumio go-librespot[8019]: go-librespot daemon starting... Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=debug msg="app state loaded" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" 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 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" 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 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" 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 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=info msg="zeroconf server listening on port 38173" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=debug msg="obtained new client token: AAAH+lykUsk6vHhb6FmhqiFiy4COObnn2co2q543JbGWy4hpdLgXmUNPOTBrA303D1IKDphsWM5ZfYTS46Kh4Pin06nSY3ZWf/L7kikEMUXbSQ6CAO8izjVEs6NEzmTdddS/L8QgMzAiyoZ44rhisJSRcI7cp6tzVcuLpxOU24bDmVtwYsTVSk9Bjp88di1bK8HAkrtvT7WFWII35YPlVSHUzZyD2uMM8MKgDuXMf99KwYc7zr3o" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=debug msg="completed keyexchange" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" level=debug msg="completed challenge" Nov 01 15:02:47 volumio go-librespot[8020]: time="2025-11-01T15:02:47Z" 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 15:02:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:48 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:48 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Nov 01 15:02:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:50 volumio go-librespot[8041]: go-librespot daemon starting... Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=debug msg="app state loaded" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" 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 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" 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 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" 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 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=info msg="zeroconf server listening on port 38777" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=debug msg="obtained new client token: AACly65sAhH79zampulpuQRN1ayjGTvwuLuE6v/mMqAoTSIjPwmtzDKsVF5DUzDD5Y4WIltN/bSHgd2PQa9hZz4ZqNo128H0XJ4Tt+Vo9DmJkKydPBvWmPvfqOKQC7E3ry5tWNHxcNSTysgyxqAIgEEBPk/KMr0u5EJYOM1fuC/59iQ3xgN5oUaKV2//nUU6VNuGqwSR89HUi886lULJXypm6TABzEkbCb5mahZdGSaCSkmxR65xe1s=" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=debug msg="completed keyexchange" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" level=debug msg="completed challenge" Nov 01 15:02:50 volumio go-librespot[8042]: time="2025-11-01T15:02:50Z" 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 15:02:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:51 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:51 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Nov 01 15:02:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:54 volumio go-librespot[8049]: go-librespot daemon starting... Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=debug msg="app state loaded" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" 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 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" 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 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" 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 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=info msg="zeroconf server listening on port 35807" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=debug msg="obtained new client token: AADQ4NxXvRFEnBTpCiho7BmueexNxrDA+n5x1XL4/fWHG2zIBVdvNvlht0YhoS0V3Rw5xWWIrsH1zCX9ffNAgw3dMCv4LZZmNjMM5LCYDBFfKsZmWBZYV+tcoQaqh4MxzacX5A9VQXGDh99jA5BXU2HGwlAr0x8rFZYdbM3YPPywJizACNZi1kQSz4104UQIHFF5g0LRn1Gr6kooa9ld3uo6kyxS6/xvwhW4Be4TCzU/xE6Iem2G" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=debug msg="completed keyexchange" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" level=debug msg="completed challenge" Nov 01 15:02:54 volumio go-librespot[8050]: time="2025-11-01T15:02:54Z" 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 15:02:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:02:54 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:54 volumio volumio[7349]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:02:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Nov 01 15:02:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:02:57 volumio go-librespot[8060]: go-librespot daemon starting... Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=debug msg="app state loaded" Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" 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 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" 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 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" 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 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=info msg="zeroconf server listening on port 39375" Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=debug msg="obtained new client token: AADsyu08bhXn5sQM9z8VGYkNESOfxSAKL7Ou+umgewECwEPGl9SiTeoGsNL275xO85CxLuGsqyUdEHnWIW/h6m2eucFQa5Gx5VFW6To0QIkbEgp6pzDCM8MfdClKYymL2ZlQDrAqYPHSsh3LB3EtNtlxkqKfeJ9PzRLwqrqGCkHxtKOF96SD95S1+lau8iPhAdu0ZDzgIyXHNYKvAkie0GSoK0MrRUx4CjHHrfJU9ohz12VjM0nAmKk=" Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 01 15:02:57 volumio volumio[7349]: info: Initializing connection to go-librespot Websocket Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=debug msg="new websocket client" Nov 01 15:02:57 volumio volumio[7349]: info: Connection to go-librespot Websocket established Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=debug msg="completed keyexchange" Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" level=debug msg="completed challenge" Nov 01 15:02:57 volumio go-librespot[8061]: time="2025-11-01T15:02:57Z" 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 15:02:57 volumio volumio[7349]: info: Connection to go-librespot Websocket closed Nov 01 15:02:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 01 15:02:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 01 15:03:00 volumio volumio[7349]: info: Getting Spotify volume Nov 01 15:03:00 volumio volumio[7349]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 15:03:00 volumio volumio[7349]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 01 15:03:00 volumio volumio[7349]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 01 15:03:00 volumio volumio[7349]: errno: -111, Nov 01 15:03:00 volumio volumio[7349]: code: 'ECONNREFUSED', Nov 01 15:03:00 volumio volumio[7349]: syscall: 'connect', Nov 01 15:03:00 volumio volumio[7349]: address: '127.0.0.1', Nov 01 15:03:00 volumio volumio[7349]: port: 9879, Nov 01 15:03:00 volumio volumio[7349]: response: undefined Nov 01 15:03:00 volumio volumio[7349]: } Nov 01 15:03:00 volumio volumio[7349]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 15:03:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Nov 01 15:03:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:03:01 volumio sudo[8097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-01 15:02' Nov 01 15:03:01 volumio sudo[8097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 15:03:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 01 15:03:01 volumio go-librespot[8094]: go-librespot daemon starting... Nov 01 15:03:01 volumio go-librespot[8099]: time="2025-11-01T15:03:01Z" level=info msg="running go-librespot 0.4.0" Nov 01 15:03:01 volumio go-librespot[8099]: time="2025-11-01T15:03:01Z" level=debug msg="app state loaded" Nov 01 15:03:01 volumio go-librespot[8099]: time="2025-11-01T15:03:01Z" level=info msg="api server listening on 127.0.0.1:9879" 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"