Oct 28 22:47:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 148. Oct 28 22:47:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:00 volumio go-librespot[11219]: go-librespot daemon starting... Oct 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" level=debug msg="app state loaded" Oct 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" 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 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" 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 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" 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 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" level=info msg="zeroconf server listening on port 42321" Oct 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" level=debug msg="obtained new client token: AADsL3+ipPrj0kTOVI9+s59JHyhusRRhSRdkCO8e+gkyna2sIr6gual7v98+WNHSbtwXxmfImlWIy85+7QSBMjyRulB7aHXU/OFYMIx5fKZ0gpRotvDOTKzgUnLBXHJTkEE4iWhZEqoqTLhOzj0h7Psuana0+pDYDDjiYcY3wyoAtslfyLEykfmoHE5h8WWDNLsHPoRGzxSDTeAgavgXw/WoEznlhS9LZXEA9n/UdQ6p56pAQZTbxec=" Oct 28 22:47:00 volumio go-librespot[11220]: time="2025-10-28T22:47:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:01 volumio go-librespot[11220]: time="2025-10-28T22:47:01Z" level=debug msg="completed keyexchange" Oct 28 22:47:01 volumio go-librespot[11220]: time="2025-10-28T22:47:01Z" level=debug msg="completed challenge" Oct 28 22:47:01 volumio go-librespot[11220]: time="2025-10-28T22:47:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:02 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:02 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 149. Oct 28 22:47:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:04 volumio go-librespot[11241]: go-librespot daemon starting... Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=debug msg="app state loaded" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" 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 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" 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 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" 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 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=info msg="zeroconf server listening on port 38527" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=debug msg="obtained new client token: AAArzc36U5TE8/j4WeV1chHrJy2zjrCZAQ8I6mEhPQ4EeCKeQRavS4/+jVFRXJJ6qaIvEDGBOhvHEKvX7M+ew9lcrSurn3dXBM5i5fEcBjLw2C1MDkW2GkjdqjEaRgJ0h924g+e1A+/8PrId6LV/8iqxoElqOggKOSWCPp9rAF6FPTljyAlRzwS3Wh8gbRlFytguwI+eIUh79IypKjqAqNHzXLEF9DeP+RIgUy8k2D3IIGL7N8sMZLU=" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=debug msg="completed keyexchange" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=debug msg="completed challenge" Oct 28 22:47:04 volumio go-librespot[11242]: time="2025-10-28T22:47:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:05 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:05 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 150. Oct 28 22:47:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:07 volumio go-librespot[11253]: go-librespot daemon starting... Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=debug msg="app state loaded" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=info msg="zeroconf server listening on port 38281" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=debug msg="obtained new client token: AACA+n0WBB0odPu+2lQIShNJsXx8HWZOwOUlrJy64fa3tv9kOkLpp5VA5PwfnJl6GEVhlRG1CY9mG70f6YRI36MFEtpmFiVEX+erSSQtfG3bnKsauV3m5L6ROL0rxyZzd9J9w5C1n75AmQbKxVTx4C1jOnX+KwRPdU9ccZcArLKleIJUuk9tVnuVX68gSgRMH8+uDlXuY+RJFJgmV20CIccnKBsCEi218OQHLV34KWldQ7CKAWmEsXw=" Oct 28 22:47:07 volumio go-librespot[11254]: time="2025-10-28T22:47:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:08 volumio go-librespot[11254]: time="2025-10-28T22:47:08Z" level=debug msg="completed keyexchange" Oct 28 22:47:08 volumio go-librespot[11254]: time="2025-10-28T22:47:08Z" level=debug msg="completed challenge" Oct 28 22:47:08 volumio go-librespot[11254]: time="2025-10-28T22:47:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:08 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:08 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:11 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:11 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 151. Oct 28 22:47:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:11 volumio go-librespot[11262]: go-librespot daemon starting... Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="app state loaded" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=info msg="zeroconf server listening on port 45935" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="obtained new client token: AACJ30vwGeuDJkLkVO/CCt16aGnho4/oM4+T+JWmnb6DgHr1cmYFceo5ibHZ3KB4J8EDQjmqdcyvwz2ZuITdepjLQa0uj0pV7BAyGwn2BN2xHi9hhWkLruFp7bDCfkA1ZpIjYxRGCjOd4J+RoXjkE+j/XLLU+OYa7xrBkV2/3szcFZLL4ZwO+Y9JBWftVmA5SW1G5Zz+LlpRMeeMgCG+tAKzck6rKkDjZE6ehR0aWBEzpfWNIgUYNoo=" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="completed keyexchange" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=debug msg="completed challenge" Oct 28 22:47:11 volumio go-librespot[11263]: time="2025-10-28T22:47:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:14 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:14 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 152. Oct 28 22:47:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:14 volumio go-librespot[11285]: go-librespot daemon starting... Oct 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" level=debug msg="app state loaded" Oct 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" 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 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" 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 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" 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 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" level=info msg="zeroconf server listening on port 33959" Oct 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" level=debug msg="obtained new client token: AACR8PAKhyXEsHsjS3FKAOCBVqZfELzCHP/WzDnI2WOnrTopRGud6BCPnWZV3g4FmM6KGbJsZRk8grssMP+YCdFwe+3n8ziTyGogq/Pfn0yTvhi3e/bb+0o1KESHunrBu3wggixTY/xl14phmKZyF7TozuFwv8vdr73Ra6H4lp8rmvBzGEptRM1eHJ6OJTQjJGLNHS3+D4IF3hROiH4ub9V0BXD52FFIGDeEE/8atvZUZWDyj2GfOH4=" Oct 28 22:47:14 volumio go-librespot[11286]: time="2025-10-28T22:47:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:15 volumio go-librespot[11286]: time="2025-10-28T22:47:15Z" level=debug msg="completed keyexchange" Oct 28 22:47:15 volumio go-librespot[11286]: time="2025-10-28T22:47:15Z" level=debug msg="completed challenge" Oct 28 22:47:15 volumio go-librespot[11286]: time="2025-10-28T22:47:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:17 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:17 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 153. Oct 28 22:47:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:18 volumio go-librespot[11293]: go-librespot daemon starting... Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=debug msg="app state loaded" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" 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 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" 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 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=info msg="zeroconf server listening on port 36661" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=debug msg="obtained new client token: AACvUM3ThKd4/TvwK+9L9nNmggfOHM7KY7oG8jmn3Ra048GP7loTwR9d33X+f9tuK+AZXFuMfOi5qLMS1dopynJzGlQFlpLC5Fidt3ScLw5VK6wKxwVeon/lBrfu5f2DOlTEnVt8WssJ7vVcSMtMpiHhkoRMBMY0oYhagr2JDnOtKl0GtRQDiqdBhjwZxSz8VR1kS0rZyVAreuqXSJl5HSWUvHcLTceLyEx9Dy51gotF0Qf0cChkUGo=" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=debug msg="completed keyexchange" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=debug msg="completed challenge" Oct 28 22:47:18 volumio go-librespot[11294]: time="2025-10-28T22:47:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:20 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:20 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154. Oct 28 22:47:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:21 volumio go-librespot[11315]: go-librespot daemon starting... Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=debug msg="app state loaded" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=info msg="zeroconf server listening on port 37153" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=debug msg="obtained new client token: AADSuu7tUvE+JeL6pHUHNHODVYYsiG/qGxVwDalYXghnmZvkHmvc0kRJ7KMfLdxyLegBAPKTeSVgl46Z4+Uwq9apuALKf/MLHpBmW/bDYlvcEmKKMzRBMM0C1fQH/KPtDbH0QHnvVHTQpVJbUQAn4pSvC8tDzn+U4Z/V18Mzwa4uThW2vv/3K3goMgVkGTixG4hZDjVbzk7KaC8DsOU4bchjYv1h4JY0cp0KUm0NFfwSASejfcSlHFc=" Oct 28 22:47:21 volumio go-librespot[11316]: time="2025-10-28T22:47:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:22 volumio go-librespot[11316]: time="2025-10-28T22:47:22Z" level=debug msg="completed keyexchange" Oct 28 22:47:22 volumio go-librespot[11316]: time="2025-10-28T22:47:22Z" level=debug msg="completed challenge" Oct 28 22:47:22 volumio go-librespot[11316]: time="2025-10-28T22:47:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:23 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:23 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 155. Oct 28 22:47:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:25 volumio go-librespot[11325]: go-librespot daemon starting... Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=debug msg="app state loaded" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" 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 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" 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 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" 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 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=info msg="zeroconf server listening on port 39223" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=debug msg="obtained new client token: AACklHxp6eaBMfWbiTAU8igNxA/5eaPTFX91g6a4UmVL4oiqP0e7ujI0oGomiQZ4b8CSpO5o0NaHE3gJHykLi9Klv+BUL2kCCwWjOnO7nP/r5PoWIXw32jqX8/G2F4hQbU0NtU376LfS1wRPukmUuIbPsuVF9o09pY8RoQ9SukIzYAHPIxcgQEZ/ds/vF9/K00IwryaANwy36ZyfxcJaq4oDKEkTXuq4G0fu/38sfZB8k+j+0PpSiDQ=" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=debug msg="completed keyexchange" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=debug msg="completed challenge" Oct 28 22:47:25 volumio go-librespot[11326]: time="2025-10-28T22:47:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:26 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:26 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 156. Oct 28 22:47:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:28 volumio go-librespot[11337]: go-librespot daemon starting... Oct 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" level=debug msg="app state loaded" Oct 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" 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 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" 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 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" level=info msg="zeroconf server listening on port 38697" Oct 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" level=debug msg="obtained new client token: AAB8TWjQMI0nSgNF55YNxVBh9lwO5Yeu9h9T7biP5jfR9XARefk+F+KHIWVqNVtn/QjmXYqWc5IFIuzwX5nKjsNvzQUBAI9X+2k8puclI52IfOxzhMtNzAvXOb97L62G5KK20QxiU8wzYnPprrhh7q7WQb+jZDKrfssWipOJdL2Pt8qQuo++zrU5DcZvRtnIjXBXdd3aE9cf8mVbComepM8Guv459Ac+Ni3TZ0c1gQTxAbJPEYDuua4=" Oct 28 22:47:28 volumio go-librespot[11338]: time="2025-10-28T22:47:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:29 volumio go-librespot[11338]: time="2025-10-28T22:47:29Z" level=debug msg="completed keyexchange" Oct 28 22:47:29 volumio go-librespot[11338]: time="2025-10-28T22:47:29Z" level=debug msg="completed challenge" Oct 28 22:47:29 volumio go-librespot[11338]: time="2025-10-28T22:47:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:29 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:29 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:32 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:32 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 157. Oct 28 22:47:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:32 volumio go-librespot[11359]: go-librespot daemon starting... Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=debug msg="app state loaded" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" 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 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" 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 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=info msg="zeroconf server listening on port 37909" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=debug msg="obtained new client token: AACwJJ+lIZhgRX/TmN5YzdunZ6GdivFFbTmc5a1BRqt3FoPrpd1DH67iez9I9aj3OVJkp0kaomnR9Sdw46NXJrcWM1SUH0raqzqKhWXyMFn7OmOBipyFK9nz8ggbmj3lF2CMaWH8FCCLgZmzh0a4WZGOiBEXamfTx1yqFxrTOtxFCkvs96hBJbl/d1mONGLfo9TIyBUVuIrp9dpcj7pO3N0EEYnz2sitTnLxYyvw8R8n/O+BhRmUsCM=" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=debug msg="completed keyexchange" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=debug msg="completed challenge" Oct 28 22:47:32 volumio go-librespot[11360]: time="2025-10-28T22:47:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:35 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:35 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 158. Oct 28 22:47:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:35 volumio go-librespot[11367]: go-librespot daemon starting... Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=debug msg="app state loaded" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=info msg="zeroconf server listening on port 41557" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=debug msg="obtained new client token: AAAuIhk5R+KYvmsBhdQOThE65xeSgC/arz1wjp86T4EW1epnUbMvoU36s44tvlsKhSRd52mnqABQrnAOa6kxjutM15kW5eF5tZtReajphdQvVmuNC+qR1YJFieM3bYnCp5DvHFmVwC15ZiRoJ708wZEFtT3kcdfTe4krfIcfaIQQW9+9cVnfM0T/qPc4CxYmuG0PdKrUaaCKTKNwYgVUkdCXlQH90gmv1KfaLDQRvevZEwScRvO5e7o=" Oct 28 22:47:35 volumio go-librespot[11368]: time="2025-10-28T22:47:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:36 volumio go-librespot[11368]: time="2025-10-28T22:47:36Z" level=debug msg="completed keyexchange" Oct 28 22:47:36 volumio go-librespot[11368]: time="2025-10-28T22:47:36Z" level=debug msg="completed challenge" Oct 28 22:47:36 volumio go-librespot[11368]: time="2025-10-28T22:47:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:38 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:38 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 159. Oct 28 22:47:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:39 volumio go-librespot[11375]: go-librespot daemon starting... Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=debug msg="app state loaded" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" 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 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" 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 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" 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 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=info msg="zeroconf server listening on port 44139" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=debug msg="obtained new client token: AAB7KYZAxxbWgeUZyG43LRDU2mNmVtytZDnFkl8uLmtA1CJzxR9KBOJv52nkCvYeEtj+GplauoZr03UqdAquLJMRRi51BaZwJZp0JK/Y44t+uxJbLsjd+qOPUVhJbx6iHVv4EbO3uAnoQvZDzU1f5GThc2d2HJS1q31G2SQS1ZiXacM/9of4iND2usa10Xyva7fR2IvKesIyC/HMrhmpXszXwgpHlAMOADDI/xUyxLRv2ejUXroSJFo=" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=debug msg="completed keyexchange" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=debug msg="completed challenge" Oct 28 22:47:39 volumio go-librespot[11376]: time="2025-10-28T22:47:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:41 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:41 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 160. Oct 28 22:47:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:42 volumio go-librespot[11398]: go-librespot daemon starting... Oct 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" level=debug msg="app state loaded" Oct 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" 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 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" 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 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" 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 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" level=info msg="zeroconf server listening on port 35403" Oct 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" level=debug msg="obtained new client token: AABidoiFtBd0CxNo5NB0s/qkgHx1IVbPRqJJ2o/TqGDhyWuBAg24TuoxvjPIh3knA/76mGB08Gzjrp76ergWit2/fd1DrvNIYF13aONugL2zusPIqZiS61vSYNbURtwi7x/bD6NlZ4TD2C+hhK1Uf8avaLVWOzss1auR6w3zBVKeMvrIWLnOzaNBAOJWtAQzIU4DtJMrmsq+MnrOkHM0NGHncBrt/AACaQ7ToeyXIM+kB544aogiG7o=" Oct 28 22:47:42 volumio go-librespot[11399]: time="2025-10-28T22:47:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:43 volumio go-librespot[11399]: time="2025-10-28T22:47:43Z" level=debug msg="completed keyexchange" Oct 28 22:47:43 volumio go-librespot[11399]: time="2025-10-28T22:47:43Z" level=debug msg="completed challenge" Oct 28 22:47:43 volumio go-librespot[11399]: time="2025-10-28T22:47:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:44 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:44 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 161. Oct 28 22:47:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:46 volumio go-librespot[11407]: go-librespot daemon starting... Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="app state loaded" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=info msg="zeroconf server listening on port 39529" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="obtained new client token: AAB92BCa2iX2m+GDs6In2Bnb0e/s4dBYS7LMg5Gi5i4ZdSN/HrkcpKzcwHWvXpIjVJmaQFOH12ClMGDTEizwT+CJB9UoyuaGzbJCwybW0kvdHCbnY2HVHgqWNeZFCAYecMvuU9VIXbghJXp9h+zmcLAzO2ha/FxMLHJL9CmxA5AW/gPjYzHp6ZdNQvcKfnGenOSd7KYNcNTupJB4uagoQbRiqKpAdfSNb9nLD1j3irRGT95b2gxsdT0=" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="completed keyexchange" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=debug msg="completed challenge" Oct 28 22:47:46 volumio go-librespot[11408]: time="2025-10-28T22:47:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:47 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:47 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 162. Oct 28 22:47:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:49 volumio go-librespot[11418]: go-librespot daemon starting... Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=debug msg="app state loaded" Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=info msg="zeroconf server listening on port 45583" Oct 28 22:47:49 volumio go-librespot[11419]: time="2025-10-28T22:47:49Z" level=debug msg="obtained new client token: AABjECeGvfqkWrek8mH9snDB5EidQbL9ITtZe3LiVlLbGBiMO0UXWqBOiSgF3fIByxjglvTiDYAPfR/WXUeQPvJHU0C1p0yCU9eO84OXWBhr2nG4ZCqnwZFcXsx1lglEUHHA5Wl8D0pEuhz01S8NZ0qfZl9CI5ZiB2v2K3f8qxtgAdvnE39iGThk4/gC8ErkIsDeCmaMzGI1E+rCQ2RPLBY63yBOITNhqp+UKi79QnUN5e2Kt8IThCI=" Oct 28 22:47:50 volumio go-librespot[11419]: time="2025-10-28T22:47:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:50 volumio go-librespot[11419]: time="2025-10-28T22:47:50Z" level=debug msg="completed keyexchange" Oct 28 22:47:50 volumio go-librespot[11419]: time="2025-10-28T22:47:50Z" level=debug msg="completed challenge" Oct 28 22:47:50 volumio go-librespot[11419]: time="2025-10-28T22:47:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:50 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:50 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:53 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:53 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 163. Oct 28 22:47:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:53 volumio go-librespot[11441]: go-librespot daemon starting... Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=debug msg="app state loaded" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" 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 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" 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 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" 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 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=info msg="zeroconf server listening on port 40181" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=debug msg="obtained new client token: AACuUUeVYaj8Q2yiv1XjfyaiZwYrHHq2eimutCuD/iN5f6WarrhLh0nOva0oHNWJjTl7aPPH+pQuG5MMs/nVkYXRY6nuZXHjEFQq/3pBwJL9U8WGk8iV12iamSgOBF8wO+Jd7qsh62HxBrWZSgobD9KMBSYJNS6fxSFEZIsOoWIqGjdx9yZyLq//VqBCjzrlf7JtVU5vIO3JcZ0gyi+ynSH7S6vHfn3ZBJBITs0Tm+YqFLGvud1DiCQ=" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=debug msg="completed keyexchange" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=debug msg="completed challenge" Oct 28 22:47:53 volumio go-librespot[11442]: time="2025-10-28T22:47:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:56 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:56 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:47:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 164. Oct 28 22:47:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:47:56 volumio go-librespot[11449]: go-librespot daemon starting... Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=debug msg="app state loaded" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=info msg="zeroconf server listening on port 45507" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=debug msg="obtained new client token: AADUXr7l1eAjg8KpOBpcKx+70/Va7g14xr+a6tEG9MWQLskk3yrdO6nlluvGy5B0e7t9IR7+VNoFtOBjRDjIsT3HWWuh3+Df1WxK5nG/Wa0W32Zs6XWykNT6Zml/RZ/QKHgo1cfZo9tM0IgzDBMYYBAFiYlJDcGA2Jk1PXqv86Hx6tbhhtor6XGqdAEJTIAvgXjPar+/jw9CSqBB1JE8FHajv5bQOxh5Pubg9jq+1Muw44A758Um2dw=" Oct 28 22:47:56 volumio go-librespot[11450]: time="2025-10-28T22:47:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:47:57 volumio go-librespot[11450]: time="2025-10-28T22:47:57Z" level=debug msg="completed keyexchange" Oct 28 22:47:57 volumio go-librespot[11450]: time="2025-10-28T22:47:57Z" level=debug msg="completed challenge" Oct 28 22:47:57 volumio go-librespot[11450]: time="2025-10-28T22:47:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:47:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:47:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:47:59 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:47:59 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 165. Oct 28 22:48:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:00 volumio go-librespot[11457]: go-librespot daemon starting... Oct 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" level=debug msg="app state loaded" Oct 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" 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 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" 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 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" level=info msg="zeroconf server listening on port 33737" Oct 28 22:48:00 volumio go-librespot[11458]: time="2025-10-28T22:48:00Z" level=debug msg="obtained new client token: AAA8rCth8LYu0sE4E34Z9mMVUM0PrXjeqis2zLZFRt48hZNlVDUQS32LJVkx22eXca6ko346sF5MSpY5ZBMN51qYi0cG01rBAV3Sgxa+xJVEoSYXUkeaSSWDOHja9mGqz3VstuzF9yuUZzOW7dSmTy3Z2KCyc8UVyK6sBSMfAUhk7ATl6M1IJmhTUAbDyftBJJuwiLEExRNRFkGesmEMx0HuhQ5eoTx+BD6Qy73vEcobXE8oSw3NQ6w=" Oct 28 22:48:01 volumio go-librespot[11458]: time="2025-10-28T22:48:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:48:01 volumio go-librespot[11458]: time="2025-10-28T22:48:01Z" level=debug msg="completed keyexchange" Oct 28 22:48:01 volumio go-librespot[11458]: time="2025-10-28T22:48:01Z" level=debug msg="completed challenge" Oct 28 22:48:01 volumio go-librespot[11458]: time="2025-10-28T22:48:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:02 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:02 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 166. Oct 28 22:48:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:04 volumio go-librespot[11479]: go-librespot daemon starting... Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=debug msg="app state loaded" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" 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 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" 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 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" 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 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=info msg="zeroconf server listening on port 42959" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=debug msg="obtained new client token: AAA+qdj2n2S93cK6XVRxWhzNM3h7avGUv5kAJrqqwzad04LAeMWDpJITNrdVIWfDeL+EvqhoGSbqNrpJtSB8ObGXY2JjOburgk6bD+XhJXinOylQsHWE3MZmrhdB85Ev32ulyHr0T+ECOD+XZKKS8FSUUjLScYy+2xpFZw8h0RzlYTeXz4YWrVORIyjfdCk3jVPcXiWKtwMvHxFJbkWw+oY9JmbNbrq3pvUDZAr54NV7ffE5lPxWvuc=" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=debug msg="completed keyexchange" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=debug msg="completed challenge" Oct 28 22:48:04 volumio go-librespot[11480]: time="2025-10-28T22:48:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:05 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:05 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 167. Oct 28 22:48:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:07 volumio go-librespot[11490]: go-librespot daemon starting... Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="app state loaded" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=info msg="zeroconf server listening on port 44441" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="obtained new client token: AACaKOj/9H46LVnbtP0PmObWYcnPWUFzt2GE6u8MPrCjx0vLvoMZ2eGan0m3c8RDoVwyHu/3az8V5M92kLdmFP7sMX/7HVn9Z6YUmoUCP3O/H9o6b26QfjFFrwysKhdpXs9mrQEDAR6d7pVHQfGHfgKw+umQja/kzW/OOOBJuBvznqao6WAehefAci9fop+gQAdgShEsjmpB/GK2Eja//80F+wo/D/YOcLpakUTGnXmW+pV4PIy0P2o=" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="completed keyexchange" Oct 28 22:48:07 volumio go-librespot[11491]: time="2025-10-28T22:48:07Z" level=debug msg="completed challenge" Oct 28 22:48:08 volumio go-librespot[11491]: time="2025-10-28T22:48:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:08 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:08 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:11 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:11 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 168. Oct 28 22:48:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:11 volumio go-librespot[11499]: go-librespot daemon starting... Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=debug msg="app state loaded" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" 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 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" 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 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=info msg="zeroconf server listening on port 32915" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=debug msg="obtained new client token: AACu9TTFjzGiCEHSjk+hOjQcFfH3ti+nhAEMsC+nvX5Ps9CYMFFq3YhGKHI/QG8Mrraw6eq2mbu4RtJgeF7xHwAwI1CNmHL0Wr9d/HsopzJlhgtAqOMrGuYVXpcZS4QtGTJfxlKxpF0Ucp70tELu8eJXmYQgIHJnOsd9r44iYbjhYV+iche4/GDGQVzRBaQ2ij1KoNpCu5hLLbHPAjFMAp6KeJe+FxeVWZQo/1qAHJQ0YfDBNXxq2W0=" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=debug msg="completed keyexchange" Oct 28 22:48:11 volumio go-librespot[11500]: time="2025-10-28T22:48:11Z" level=debug msg="completed challenge" Oct 28 22:48:12 volumio go-librespot[11500]: time="2025-10-28T22:48:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:14 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:14 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 169. Oct 28 22:48:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:15 volumio go-librespot[11521]: go-librespot daemon starting... Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=debug msg="app state loaded" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" 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 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" 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 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" 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 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=info msg="zeroconf server listening on port 39763" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=debug msg="obtained new client token: AADlk1U+y2wkJOynQMhvvKHs0fxOuwnKn2DZCWjCLghBTvFmAO35gHUl+bbja+ZBhx08ZlNI8OtNE5qTJkg8EziVs3QOqrcdvWcS9V8GBwxosFDWvpAwAOL1MjonbgHJ0mumT2fKYDw60waBhyW/n/V9BPU/qEzxMRF5NeTD72Xsmzf0MfsOf9kJX6XI0htlLdZfywU3cyHrHsQMcvWCcmKpElh3dCOicEOAEfI1qHsKNl5IqRbl" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=debug msg="completed keyexchange" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=debug msg="completed challenge" Oct 28 22:48:15 volumio go-librespot[11522]: time="2025-10-28T22:48:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:17 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:17 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 170. Oct 28 22:48:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:18 volumio go-librespot[11530]: go-librespot daemon starting... Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="app state loaded" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=info msg="zeroconf server listening on port 33521" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="obtained new client token: AACT8iNwgEUDjHI7o90rLiq8F40XhB5W+HwKUwPrGhEf4Up34s/h617ZcrNlJMndmHrTe8zhIzH8eJiCgwqXWbzTkvFymcC7lipYD08y4g7KQwbQ9d2IWFiMxa+svrYgzLpn48dwXtr2qGCCtSil0181I3BIVTRMGUAfI/TCyzSlLKHeRvvv33yOuyZ8MAcdcg46Z7cWO+wXlnJ563wnbTzhgDzCsudn8nzO7MaYQNq4pMB3HAXAEYg=" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="completed keyexchange" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=debug msg="completed challenge" Oct 28 22:48:18 volumio go-librespot[11531]: time="2025-10-28T22:48:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:20 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:20 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 171. Oct 28 22:48:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:22 volumio go-librespot[11552]: go-librespot daemon starting... Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=debug msg="app state loaded" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" 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 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" 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 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" 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 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=info msg="zeroconf server listening on port 44041" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=debug msg="obtained new client token: AAD8/YUoMrMZvwDosp9QCnVaQ2h3A5qQFGT5Ty89dHbt1+JBm9YVeeT2JU+pVEC9JeHrHv5GBsInR33WP6/zOy/fhhmWC+y/SeaYFL+moTGjaSgsUF8AxrU5mVkUFOxatFET8bZ4v1qp0ScFbFITorZqi3wta43K0gZAW1yFhuW2mdZzFpPYdefF1w2/Am6qP/ieoJ5mX6lVWHUt1xNYlz+LLqTfVuXKkEBN6GkAhvuG+GDtp1bV" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=debug msg="completed keyexchange" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=debug msg="completed challenge" Oct 28 22:48:22 volumio go-librespot[11553]: time="2025-10-28T22:48:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:23 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:23 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 172. Oct 28 22:48:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:25 volumio go-librespot[11561]: go-librespot daemon starting... Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=debug msg="app state loaded" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" 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 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" 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 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" 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 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=info msg="zeroconf server listening on port 40987" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=debug msg="obtained new client token: AAAacdWauD99DD9QR31jeVyg7dTG9kT59e7JaIZb0K/6NZSlvZs5oprbJ0zeAkPodmcKGHEnEX5xYp9tEkQgyI8nBSDTOlXQ4am722O0fs8Bz3d2KrA3cSH8x5/o9TOidC1hw2R16tO6bBx8HTfma86fCaii2y2zHadCfqvoKs7+pcM3xUCGQM0TUzAFabgX9sgXXgiA0/KQjkRyApv58qen6I0vsXimhiBN+0QwcPmGD9h7GRvFNqs=" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=debug msg="completed keyexchange" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=debug msg="completed challenge" Oct 28 22:48:25 volumio go-librespot[11562]: time="2025-10-28T22:48:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:26 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:26 volumio volumio[10615]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 173. Oct 28 22:48:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 22:48:29 volumio go-librespot[11572]: go-librespot daemon starting... Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=info msg="running go-librespot 0.4.0" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=debug msg="app state loaded" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" 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 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" 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 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" 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 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=info msg="zeroconf server listening on port 41485" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=debug msg="obtained new client token: AAC6sqn9wlWsQjpbMVCiq44EGf58eaUwZ5ys4/s0j7CnCxl5HbBaLoTvL5hsAiNxmMzctMb3dkoEp+Pgg7wNi9EewyBGyUQ6oeT4ZlvecIJsDMB9xgfv3pcpSCmMZvm+z9yhdl00Rp7zAr+wcgIHID7mS3RFCAO7Fg59a/xmn53VomV+kkiW2v8A2llpBSb0PTVQgBdlgosJ4SlntNRhK6quzjzcxrOGmjQJ2K2/2sZ0xtWJ7+L8" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 22:48:29 volumio volumio[10615]: info: Initializing connection to go-librespot Websocket Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=debug msg="new websocket client" Oct 28 22:48:29 volumio volumio[10615]: info: Connection to go-librespot Websocket established Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=debug msg="completed keyexchange" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=debug msg="completed challenge" Oct 28 22:48:29 volumio go-librespot[11573]: time="2025-10-28T22:48:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 22:48:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 22:48:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 22:48:29 volumio volumio[10615]: info: Connection to go-librespot Websocket closed Oct 28 22:48:32 volumio volumio[10615]: info: Getting Spotify volume Oct 28 22:48:32 volumio volumio[10615]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 22:48:32 volumio volumio[10615]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 22:48:32 volumio volumio[10615]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 28 22:48:32 volumio volumio[10615]: errno: -111, Oct 28 22:48:32 volumio volumio[10615]: code: 'ECONNREFUSED', Oct 28 22:48:32 volumio volumio[10615]: syscall: 'connect', Oct 28 22:48:32 volumio volumio[10615]: address: '127.0.0.1', Oct 28 22:48:32 volumio volumio[10615]: port: 9879, Oct 28 22:48:32 volumio volumio[10615]: response: undefined Oct 28 22:48:32 volumio volumio[10615]: } Oct 28 22:48:32 volumio volumio[10615]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 22:48:32 volumio sudo[11609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 22:47' Oct 28 22:48:32 volumio sudo[11609]: 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"