Oct 29 01:55:02 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:02 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 497. Oct 29 01:55:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:03 volumio go-librespot[29699]: go-librespot daemon starting... Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="app state loaded" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=info msg="zeroconf server listening on port 37355" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="obtained new client token: AADhnUjA8DxWv2sJhfyPQr8Twyukn9MIwosYQXi0j+3r1TvLF+MdjuNyhDRjyCTwY2FIDsqjDVWqKelcryuTVhVmCjmWrrluf1JYglQdr6+U2YxsJ9QemhTv+k4vB6/xz+iAMMijO89IZIp4N/yoAhGCB0Q7K2TjGmChwHdr7+3gFis7NHaocW5QfaRtHVTSCcylIks1lF0kS8HEiLHjtxiPkyUTj4Zi6pziCy5mk6SOLyiYV+J+" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="completed keyexchange" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=debug msg="completed challenge" Oct 29 01:55:03 volumio go-librespot[29700]: time="2025-10-29T01:55:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:05 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:05 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 498. Oct 29 01:55:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:06 volumio go-librespot[29707]: go-librespot daemon starting... Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="app state loaded" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=info msg="zeroconf server listening on port 46701" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="obtained new client token: AACCrBjqILOAnwbcrSH6j5XbWvELg94tPH34q4t3mQU9fVXh66Z13nWpX6nkKy2TJBclE+9tssp2unWk3m+vm/xCCrNJJnrP7HqWqE97QV/U0qXE0zRN57bKjyqZspj+TR+mIv7F9XRkGZBZdvJro4aB8gfTDz4l+RonqYaxQ2qpg3VKqyNodcdpgxVXfef0vUWCrWqoCTKO8nfilBAwv8n3Tm1Ot9UA3tHkMLgkKckEm2I+JAiAR6A=" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="completed keyexchange" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=debug msg="completed challenge" Oct 29 01:55:06 volumio go-librespot[29708]: time="2025-10-29T01:55:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:08 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:08 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 499. Oct 29 01:55:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:10 volumio go-librespot[29718]: go-librespot daemon starting... Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=debug msg="app state loaded" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=info msg="zeroconf server listening on port 41459" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=debug msg="obtained new client token: AADUS7frI+tNCGIx1Hs4kiFQPw5XquG5WCgzikGhyg3nmdIDCkvlZO2rb1KP2OujQ4waPSU46YuMNqYG6BZkfDXfIGjBMRVQkg/HuQP5oubjp0AqTQ3nVrCv3wyamGXx7DduOCSzLVuiS0he09fJVbiU7HJhX95jqFBW9CkQWeLS1X2k53F+1SYTm6oNZvbUCRsYAYvGKL9DOTfDYGkv3S13/1lsIMOfYDDzCNYYDuXfiBA2W8Iu" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=debug msg="completed keyexchange" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=debug msg="completed challenge" Oct 29 01:55:10 volumio go-librespot[29719]: time="2025-10-29T01:55:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:11 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:11 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 500. Oct 29 01:55:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:13 volumio go-librespot[29741]: go-librespot daemon starting... Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="app state loaded" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=info msg="zeroconf server listening on port 45945" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="obtained new client token: AAA1At+9F7UMSiZOtJCABWmkNI8XzsCxzsLxezX8Puu403Mtw50tNddpCUyq3fnRPqgzwW1H2UGypHn32au9ocFKu0YI2AJck6gM2MMNwmqi0bkUV5e7aNzrKfvfnCF7SVKG/rWSGew0Mbr3lrX6Vwn4j6Lu8BwummOAuZpF4LLqJ9TgxstKys0t03FrvnrPOrWaAAp4H85MhF2T4KQuBSAWFkll6d3KD8jEeLqiyU7u9ngxxbVVTAQ=" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="completed keyexchange" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=debug msg="completed challenge" Oct 29 01:55:13 volumio go-librespot[29742]: time="2025-10-29T01:55:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:14 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:14 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 501. Oct 29 01:55:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:17 volumio go-librespot[29751]: go-librespot daemon starting... Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="app state loaded" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=info msg="zeroconf server listening on port 34145" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="obtained new client token: AABoUrQkNX7R0x1AJD6bYfJvMF1GpT5/npDBpyWP6cKpPtbLNgdCc5f+8EpoxzM1y2fPniVUKQTR2qNgYIRZ+D1OFsOmzr5O2wpgIYmQ4O/mbpE/Wt2SBCByOSNgMtkKPXO4R0yqOi7+ehVEHk5cgwXWk2Ezr/gzcP+ONUMcLTmAE5zXdKj3BRVipMWa6h/FDLFaTQR7fua69eF6jEcFB2aeEc3XkDVD6TMktldZCJyhBrDtFW8u" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="completed keyexchange" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=debug msg="completed challenge" Oct 29 01:55:17 volumio go-librespot[29752]: time="2025-10-29T01:55:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:17 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:17 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:20 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:20 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 502. Oct 29 01:55:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:20 volumio go-librespot[29760]: go-librespot daemon starting... Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="app state loaded" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=info msg="zeroconf server listening on port 42199" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="obtained new client token: AABgaIW+N9NLkA9hFgXttD9dJAjd8jchE8z+ton/KV1A/gzYckTSsJ12VhJ9X4mTzv+M7j59vk3RGmf5qnmF74mLA4Qi9mbiWcWK56oKFhbEbmF5MtYXrFKuzHsB/xVxVh2L9Y9lgDFETpCn1tiE1jj2qOJllEW8s+ysNpniD6H5kQkg53SHNJbMz1MyYGc2d5Y3z5CbVQNdbJFvOPITTiSnIX4hYlPmBoHhi9VxtOaVpDu5nbjeVUM=" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="completed keyexchange" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=debug msg="completed challenge" Oct 29 01:55:20 volumio go-librespot[29761]: time="2025-10-29T01:55:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:23 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:23 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 503. Oct 29 01:55:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:24 volumio go-librespot[29784]: go-librespot daemon starting... Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="app state loaded" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=info msg="zeroconf server listening on port 39095" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="obtained new client token: AADppSzvDWabwXM3o3OmLMyHkZFoZ0Sw71xq7vji/qoV3/Kj/gtaYXwbncgC+9lOU/AtQrCgA27zDXwxstbfrS5fxlPqKo8TyvAcUIx6gDxh6RhZ1jnQqbk9bvPWn/wBp5/W5VRSw90S9+e+ODz22aKXyKMQ6I2cMzZvZnMthS7jBiKmLMNB85JqOKquFrdsj+6ple08hQoH+VVM8q5YtdGcs1TZP1Yle4Ke+usOshkJDT0kiiSC" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="completed keyexchange" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=debug msg="completed challenge" Oct 29 01:55:24 volumio go-librespot[29785]: time="2025-10-29T01:55:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:26 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:26 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 504. Oct 29 01:55:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:27 volumio go-librespot[29792]: go-librespot daemon starting... Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="app state loaded" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=info msg="zeroconf server listening on port 40311" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="obtained new client token: AAD3oQ/TNAxMKLOMBEKjw+Nwi/WJRyvZGl1D8OXgNZrZOGWXS3tIli9SKY6H8Q0bWCdZJXRX0JiRy9Gp+6YPBNXjzwFiPKLjSqgRr8X1Ok8wGKleRz7vRNGUU7upaAR2ZBU2aqMkvh73uW/VfNOsORtMIBCOV/9r4V9MvNHrfQuerHx62OaKPXgXdEdpkGsEByc3Ujjah2pSOD+4a/wjqwW3kAmgSL81UJvz7VP/lD9Qh8nf3qK8dR4=" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="completed keyexchange" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=debug msg="completed challenge" Oct 29 01:55:27 volumio go-librespot[29793]: time="2025-10-29T01:55:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:28 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 01:55:29 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:29 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 505. Oct 29 01:55:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:31 volumio go-librespot[29803]: go-librespot daemon starting... Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="app state loaded" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=info msg="zeroconf server listening on port 43439" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="obtained new client token: AABd6xgUn9t8QYWmkXBeDUF453Hb7O1QvuOoLGn5dOy/DHW7SgfZGOyUdF5b/UGpw3KUAWSDbVwjzbWaeStbG1USC7GrUWjcwlCJUswjy86RrBuwVcYwsYk8psKgkvWozy3yI/N3n6yN0ut4niJDAmeJQlTV39/A8bln4VOvUdkmO7KnLGrcx6AaCxxxPeyvY2L9Gnfe2tYZQCFF03ZBJDJkI2bQ5Xwn7a4HchD1G481SPGkn2IY" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="completed keyexchange" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=debug msg="completed challenge" Oct 29 01:55:31 volumio go-librespot[29804]: time="2025-10-29T01:55:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:32 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:32 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 506. Oct 29 01:55:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:34 volumio go-librespot[29825]: go-librespot daemon starting... Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="app state loaded" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=info msg="zeroconf server listening on port 35939" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="obtained new client token: AAA/7S2aWE4czBPFav380IgGm1pR/w7vyGAJXL/qYxJgvv7/78Bd2oX4rWGA/vUDrPxM8LeTbQyHutEKk4JooLXZx8SUdyyadNHwucuwKRJyB9FPsQvJul945XD71CMiU1jfoGTRf3xaklbAAJQYdpB88YHmXwCg+DIeGDfRoDrBmBNAIZn9xmLqW6duQALKdcvsT+JLBXuA9l64giu9GL61EWVBcRhN+hZLr8SGuu4g/5t3Wl9mqCU=" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="completed keyexchange" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=debug msg="completed challenge" Oct 29 01:55:34 volumio go-librespot[29826]: time="2025-10-29T01:55:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:34 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 01:55:35 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:35 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 507. Oct 29 01:55:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:38 volumio go-librespot[29833]: go-librespot daemon starting... Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="app state loaded" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=info msg="zeroconf server listening on port 42061" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="obtained new client token: AADVZE+OSt7QMdaHD1UyVX6X9paajH4AQzns0OHNswoGvLjLgHGiKySOatBcokJebteqcodlaTgKYL2+Rt9Xb6Qa6yRKcYHvnXhpiLsN4grtvQSG/KWFZ2hZ4BXM+JtzYWNkK4Ofb02JTlWkzkp4PSIUDi9OKSRsV7Nz3gxIMpnz5JHyo2dDBwjiYfghaEcWafc7c1ihh26NTvQNAFWubCBcLx8qZJKpmpbUUJRCrOE4mBsuBRAK" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="completed keyexchange" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=debug msg="completed challenge" Oct 29 01:55:38 volumio go-librespot[29834]: time="2025-10-29T01:55:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:38 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:38 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:41 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:41 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 508. Oct 29 01:55:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:41 volumio go-librespot[29843]: go-librespot daemon starting... Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="app state loaded" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=info msg="zeroconf server listening on port 38633" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="obtained new client token: AAAqEvVfiByO/MlL3W5SF4e7/dIDMgqtiqr2ZDv2AU0ws/RdWqKVfCOaUHK76jFS8lbTLkU1UXQJXBtI3+X8BbvYFwHz4CwyLLwFzp3NLFNNCHWbo8YIXtFpUIGeh2QZRQfkPmLhW0aJv/f75vA2//qaY7NyH+26nL83Gmdd830RBsS16G6vksZDndJsLUjNPXX1RPYYqEWDwlNKKEokgkIlhegJYIsR28QF60LzAl9iT7IvpHtJbl0=" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="completed keyexchange" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=debug msg="completed challenge" Oct 29 01:55:41 volumio go-librespot[29844]: time="2025-10-29T01:55:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:44 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:44 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 509. Oct 29 01:55:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:45 volumio go-librespot[29865]: go-librespot daemon starting... Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="app state loaded" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=info msg="zeroconf server listening on port 41087" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="obtained new client token: AABL4wvxNdf1tOC1xQ8iHNwRqZF2fF6r1YXM7ffH6pGmAqdQus+NeQ4term7HR5dDq7y8V7Q2H8eO6KZxf1m5bO98ihFrEKJLyj3Sbds4eoZAJQ3QZhHvuwOwmZS2jW9Zo/UXk4lee9/EHSOMQNQ7Pqbr4duHHtIeEbpHVn940VD72ASo1f05D3ft1cvadLT9DaR7jV5rPAofzhIcd3g5YSLVD+JEIUv4ihi3nkX+TyPpDQa1KJf" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="completed keyexchange" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=debug msg="completed challenge" Oct 29 01:55:45 volumio go-librespot[29866]: time="2025-10-29T01:55:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:46 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 01:55:47 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:47 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 510. Oct 29 01:55:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:48 volumio go-librespot[29876]: go-librespot daemon starting... Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="app state loaded" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=info msg="zeroconf server listening on port 46835" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="obtained new client token: AADtZ7X6hOfKgyTrKNDPaQzU0VVYYjSj9Nv1c68XNu2x49vSoDrN40VKn2iXkPBhZ/0aHN4YNpPl8U1tK7KmA86vFsekT7qi8xrS8u5sMYPM1B1zEPyAndykHqEMFJqZ4nxCE5WmyeDA5485XlMqfDBlGgpzukCYEjZFESOot3gIwbAGFeLGrBCMljFSXoxtNruFZs9proaeVjrVRLx9KlbUqozjWqJgB59Lut88GEplXKLwK3axv0U=" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="completed keyexchange" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=debug msg="completed challenge" Oct 29 01:55:48 volumio go-librespot[29877]: time="2025-10-29T01:55:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:50 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:50 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 511. Oct 29 01:55:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:52 volumio go-librespot[29884]: go-librespot daemon starting... Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="app state loaded" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=info msg="zeroconf server listening on port 44653" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="obtained new client token: AAAZk6YjK9ZRK18ecKYsVB17TLfXoY/XNnSAgI0QdZWVUjGN25OFMsQUUT4VeOoC/zMdfHn/+q/KqXa5tggC/DdAEQ0JvM4HnRaXG20SvuY0opx5GWoNJiowizzdE6u4eXdGE9sj8ktbg2t/pCQRxkwvqyYJWqpuJ/B/kCFvGZPz5lDL9bteuZDf+NHMQDYViVzcb2c4iXkDTY7JOV+RgO2LFuTc/mKX1UZvUr26Q1gPFd8zuc7h" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="completed keyexchange" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=debug msg="completed challenge" Oct 29 01:55:52 volumio go-librespot[29885]: time="2025-10-29T01:55:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:53 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:53 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:54 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 01:55:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 512. Oct 29 01:55:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:55 volumio go-librespot[29906]: go-librespot daemon starting... Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="app state loaded" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=info msg="zeroconf server listening on port 39015" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="obtained new client token: AAD82/rGCzYl/RFxNTpxbTHzkIj90CkI9+qruYnGi1L5NHENPva0HF2BN8hjP0jh7F/UP/O6oieIlGvv2BTuBPB5SZUlAILf+oBgsTTrHaixgKli8TbNENC0DfmGiQX4Ih+ZI/VIpT+JNyGcdavWK1Z60Qc0vWzTfKPEk4avY8X33T1tvOGNtihjxkKyhigKzamsW3GVPzfSEJ1j9w4y9dQWO/ZYaVfQ87B2ZkbOmS5AvRzvtDKYLEs=" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="completed keyexchange" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=debug msg="completed challenge" Oct 29 01:55:55 volumio go-librespot[29907]: time="2025-10-29T01:55:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:56 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:56 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:55:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 513. Oct 29 01:55:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:55:59 volumio go-librespot[29914]: go-librespot daemon starting... Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="app state loaded" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=info msg="zeroconf server listening on port 38685" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="obtained new client token: AABFe4/tvpvVro6IojBopcnBAmceOA8e23ptjOygT4Zdev5mphqx4h1fnzQ6ohpOYvtrjPHUUsa1f5ToIM+ZU3OfWRFdeXBrroJ9XPL/pfVorNclCXGPAGU5NVBhZcbnEsbkn4zk1uaj3I417iWWr8a8ptpw5evcufC7JJlet7mhS8h45TEWcBkaNC1ANNDTAZ02sylP854G8kS0hbxhD49tmXSv2ROcHVwLyALtTHMliBicVP1L" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="completed keyexchange" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=debug msg="completed challenge" Oct 29 01:55:59 volumio go-librespot[29915]: time="2025-10-29T01:55:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:55:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:55:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:55:59 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:55:59 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 514. Oct 29 01:56:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:02 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:56:02 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:02 volumio go-librespot[29923]: go-librespot daemon starting... Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="app state loaded" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=info msg="zeroconf server listening on port 36675" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="obtained new client token: AAABZ4G5tLh+akFBQrhou1i2W2qvILc4p959GG9Fjog+99/8Cd38Y4ILNK9tHRRkKTCPZhWP2gnSKb2ff7BVpVow9+WtJ1JVVbaRN4B/wneixkk4r0jAeXQ34Bixt6SwdpKyTB8bRirnNzWOu+9x4at5flaI5U2JsNcvG11/weoRSqH9p35F2okXt/dZXs0yo4k0Oa35KoDRkIMUkxOgbRA+YHPo921GqHhEN57zvQcEsd03l4SLbUQ=" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="completed keyexchange" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=debug msg="completed challenge" Oct 29 01:56:02 volumio go-librespot[29924]: time="2025-10-29T01:56:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:56:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:56:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:56:03 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 01:56:05 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:56:05 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 515. Oct 29 01:56:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:06 volumio go-librespot[29947]: go-librespot daemon starting... Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="app state loaded" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=info msg="zeroconf server listening on port 45553" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="obtained new client token: AAAHz7aOxIu0XkgmaI3MtydXfN1qhkoBqgBm2/iBrkmxpGFKtdJ2uN8RGBis3gbXxtiXmow03i45GbMG7WilkLaLje+chXsxWkfVDQErhcctTqHowoWTim7MjTUMz/geYTmNxODlToHUCjYBmz4107h0Y5eZ1c0T5orzEEtdNVri62m10Wl64uKYLdOI+MbJekGIRNHz6LIbYmf8FrSvm3msmqFaUbn29BU6+3lOrdAQyfS9LC7W" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="completed keyexchange" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=debug msg="completed challenge" Oct 29 01:56:06 volumio go-librespot[29948]: time="2025-10-29T01:56:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:56:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:56:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:56:08 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:56:08 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 516. Oct 29 01:56:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:09 volumio go-librespot[29958]: go-librespot daemon starting... Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="app state loaded" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=info msg="zeroconf server listening on port 40981" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="obtained new client token: AACVcQVv6u3L4qrDjdm5Vbei5wkC8yg/eceLELq7bhEV1D6obmKa7RQj53SRhy1Uuv/wby1RpT1AZtZLzPK4waz/ANp7xWzC3oylJbAIycaI9alpzXMCOCrVVCPjk1egQB5303in3v1SxxDKnxoZZm5RV5BKItucncC5jmNJ6r0q2JptowUL+odUOJhvymohS1g9CbN3NO6qUGn7glLImaj0iJh1XAD87yKYtxSu0DP3EcaKrCMLXak=" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="completed keyexchange" Oct 29 01:56:09 volumio go-librespot[29959]: time="2025-10-29T01:56:09Z" level=debug msg="completed challenge" Oct 29 01:56:10 volumio go-librespot[29959]: time="2025-10-29T01:56:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:56:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:56:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:56:11 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 01:56:11 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:56:11 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 517. Oct 29 01:56:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:13 volumio go-librespot[29980]: go-librespot daemon starting... Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="app state loaded" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=info msg="zeroconf server listening on port 38699" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="obtained new client token: AAAbYHfVXylpTwsI/BDnEOxgOUtPZldG1BqRgmd0ivmrV7d78p81xB2aRXeYNxUHCyfN+IJkAP+iVcxZa4Hgl6uN5ZqrNoi+FYEo9S01S+JlJhDapNmXl5hC3adFZvH6UpFCoVfi+Tj3HMAHgQ5VMscEnq7yhb+aUMyfVB1m0KmLjbrKRCzaRFuXY93e1f6L2ZnQebTGjvICbkQ0zUOa37bMGVWMOa/zTrbWcSjba/D1gxz0qi+qdt4=" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="completed keyexchange" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=debug msg="completed challenge" Oct 29 01:56:13 volumio go-librespot[29981]: time="2025-10-29T01:56:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:56:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:56:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:56:14 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:56:14 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 518. Oct 29 01:56:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:16 volumio go-librespot[29989]: go-librespot daemon starting... Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="app state loaded" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=info msg="zeroconf server listening on port 46009" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="obtained new client token: AADihwXxaSsV4O7IYmpfVk+/fQTjRXDrrXNRyl6jff2jbsW8vjL/V741jx8GP5HFxWZIXS/gA2x4WF1P01a2TH3UOQiTeGa+KFirMfWes+3UUXe8WmwMXsxqHEZt/l5ALqVQXrNWCli7wkN4OEMTWAwTBYVKjYhcyFy/swrZosruZaKtJHM2wqI18QM8ER1g3+stjWNI0ZjRIwaoYKfoP5+FScn/ROATE2x4xvDCoCX1l3iIPFlGuFY=" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="completed keyexchange" Oct 29 01:56:16 volumio go-librespot[29990]: time="2025-10-29T01:56:16Z" level=debug msg="completed challenge" Oct 29 01:56:17 volumio go-librespot[29990]: time="2025-10-29T01:56:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:56:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:56:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:56:17 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:56:17 volumio volumio[28225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:19 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 29 01:56:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 519. Oct 29 01:56:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 29 01:56:20 volumio go-librespot[29997]: go-librespot daemon starting... Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=info msg="running go-librespot 0.4.0" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="app state loaded" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=info msg="zeroconf server listening on port 40073" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="obtained new client token: AADz1T+FOONTNMGN+K09vkkIall/dvaEaYlmeAe4h6SbeG9WJMEStUQu6ydXQGzlLX/ZPfH0Nubm/nPt6FeBYn3i1m512czfA3YCsJHevDiRzHpKCZPOSBdVXIVjZFwykntd/IzTD6WU5ucjyP3avj/oAgH8uQfqqRVsfHcYfZ1it5QV8f7qfWN3YGs1ID5Qq/P3GMfeH5NdXv6i7vGCHO1n0YrxFki8Hx6n0gBemsmnOVtf3PrN7VA=" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="completed keyexchange" Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="completed challenge" Oct 29 01:56:20 volumio volumio[28225]: info: Initializing connection to go-librespot Websocket Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=debug msg="new websocket client" Oct 29 01:56:20 volumio volumio[28225]: info: Connection to go-librespot Websocket established Oct 29 01:56:20 volumio go-librespot[29998]: time="2025-10-29T01:56:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 29 01:56:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 01:56:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 01:56:20 volumio volumio[28225]: info: Connection to go-librespot Websocket closed Oct 29 01:56:23 volumio volumio[28225]: info: Getting Spotify volume Oct 29 01:56:23 volumio volumio[28225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 01:56:23 volumio volumio[28225]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 01:56:23 volumio volumio[28225]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 29 01:56:23 volumio volumio[28225]: errno: -111, Oct 29 01:56:23 volumio volumio[28225]: code: 'ECONNREFUSED', Oct 29 01:56:23 volumio volumio[28225]: syscall: 'connect', Oct 29 01:56:23 volumio volumio[28225]: address: '127.0.0.1', Oct 29 01:56:23 volumio volumio[28225]: port: 9879, Oct 29 01:56:23 volumio volumio[28225]: response: undefined Oct 29 01:56:23 volumio volumio[28225]: } Oct 29 01:56:23 volumio volumio[28225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 01:56:23 volumio sudo[30034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-29 01:55' Oct 29 01:56:23 volumio sudo[30034]: 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"