Oct 30 00:15:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Oct 30 00:15:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:01 volumio go-librespot[11304]: go-librespot daemon starting... Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=debug msg="app state loaded" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" 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 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" 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 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=info msg="zeroconf server listening on port 40659" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=debug msg="obtained new client token: AAAUyWYunu/wtiKgIh/aXj9Ccf4JGbhMGT1ElMJB2VuTvdzxT9U1J58X5xylgIkRaSx/9kt6K+VEyys/09IVeg/6QIQGMr3AU+YLe7lqnC3mq4cRXXoeXOcGI3LHea9lH/XrQ/wRenlxm8WvzFx810I61vRO87pRR0AsdiNZ6FtEVRmJgQVcTN5wzwnqZ4rwunFpxse/BHTbcLzLvOWicEPEVMEv076yik5JyEevldn9ywDiMHeiYgY=" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=debug msg="completed keyexchange" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15:01Z" level=debug msg="completed challenge" Oct 30 00:15:01 volumio go-librespot[11305]: time="2025-10-30T00:15: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 30 00:15:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:01 volumio volumio[10958]: info: MYVOLUMIO: Adding device Oct 30 00:15:01 volumio volumio[10958]: info: MYVOLUMIO: Evaluating Server Oct 30 00:15:02 volumio volumio[10958]: info: Setting Geolocation for MyVolumio to eu3 Oct 30 00:15:02 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:02 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:02 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:02 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:02 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:02 volumio volumio[10958]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 30 00:15:03 volumio volumio[10958]: info: Updating MyVolumio device info Oct 30 00:15:03 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:03 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:03 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:04 volumio volumio[10958]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 30 00:15:04 volumio volumio[10958]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 30 00:15:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Oct 30 00:15:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:05 volumio go-librespot[11341]: go-librespot daemon starting... Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=debug msg="app state loaded" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" 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 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" 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 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" 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 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=info msg="zeroconf server listening on port 42711" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=debug msg="obtained new client token: AABGufGsbQQmux6Ri1sIBAK3JGSohkdX0UeBk4NoDWf6tt/x3pN7N94vF4oa7e8I+GTUA4x1Kuq+aaE1SDIFiKJK3P9qvfVwzuJhFXf0mOIItyC2q2ZHV++KZQSsfhuBpol5baHASqOKscu+ZfO+ZCcwjyw4vL/0/LcrG0b8TeLT5X5FRW3DRYnuGY2pIX381mlsN5uA9qiWooknL67opPo89mtR3pMfnLDWW9XZ9eitkTtjlkfX" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=debug msg="completed keyexchange" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=debug msg="completed challenge" Oct 30 00:15:05 volumio go-librespot[11342]: time="2025-10-30T00:15:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 00:15:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:05 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:05 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:08 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:08 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Oct 30 00:15:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:08 volumio go-librespot[11349]: go-librespot daemon starting... Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=debug msg="app state loaded" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" 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 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" 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 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" 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 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=info msg="zeroconf server listening on port 35093" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=debug msg="obtained new client token: AAARZ2SmIXGt8J+VeTkosjnQ8RcpAbyBzOO5ZFOoJl/9zaHR9frQeTZJqloL4nOLXgnT7YW9nNU6jHAr8DtxXMMRfJLna2aAdnCLgrcAi8ZK/6xqPvkqloHg7/GzqlUhCygxyxihjgf9F7R6aP8vP/KgRTSlvwMa9s+uKLGkQu+Q9Sxz+3r4Aw4dbgHtr3vkdE085IQTAHW3nus7qTMWawSDnenO+qQilIt/GykU/6dFMU2FaE1L/yY=" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" 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 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=debug msg="completed keyexchange" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15:08Z" level=debug msg="completed challenge" Oct 30 00:15:08 volumio go-librespot[11350]: time="2025-10-30T00:15: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 30 00:15:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:11 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 00:15:11 volumio volumio[10958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 30 00:15:11 volumio volumio[10958]: info: CoreCommandRouter::volumioGetState Oct 30 00:15:11 volumio volumio[10958]: info: CorePlayQueue::getTrack 0 Oct 30 00:15:11 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:11 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Oct 30 00:15:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:12 volumio go-librespot[11360]: go-librespot daemon starting... Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=debug msg="app state loaded" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" 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 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" 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 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" 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 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=info msg="zeroconf server listening on port 39161" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=debug msg="obtained new client token: AABNpMH1SgVjzybak+95e1ucrmYBvIBFMxRiatIxF4XZYmzj4Ee0HxyFqpRQyFRdQ3VRt46usRW06SZnJL+RejFWYqBiic1aREftTQFbgX5P9zT7fqHInz/CKHDLBWtf7lz9orSbq3H8wNOg8pCmE7q+qINU6s790L9ITf/RmhDmUn3h5Bgxxsr2lMK3HCLf4aErLYfb/bXSZbWDeffUXAzCN3XW+sA/2WxIXdoIGBQS9IYL1gJ2" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=debug msg="completed keyexchange" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15:12Z" level=debug msg="completed challenge" Oct 30 00:15:12 volumio go-librespot[11361]: time="2025-10-30T00:15: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 30 00:15:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:14 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:14 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Oct 30 00:15:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:15 volumio go-librespot[11383]: go-librespot daemon starting... Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=debug msg="app state loaded" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" 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 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" 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 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=info msg="zeroconf server listening on port 43729" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=debug msg="obtained new client token: AACNpalwcX1BtzItHO4Oxcx7KmKunwpK8zwh/WBogNggjtk0qtnvPGwClpYOCGUGrUcR07ufKTY6hEA5l2YBnQOTPqOsCXqzon025Q+hKHsIyOPs1LLC4MDh15FmY/CqsE+TXUsw9wGM42G6Ylqh+XEoZFbagayP4EKd8iHpTYSzMV6y315VHhIoT/IZuCptxwzNoT8le6X+NeYDEomPkuZHiqZ8GwldbI70A9muSPpgIG6ZBK0hess=" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" 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 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=debug msg="completed keyexchange" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15:15Z" level=debug msg="completed challenge" Oct 30 00:15:15 volumio go-librespot[11384]: time="2025-10-30T00:15: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 30 00:15:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 00:15:16 volumio volumio[10958]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 30 00:15:16 volumio volumio[10958]: info: Completed starting MyVolumio Plugin Oct 30 00:15:16 volumio volumio[10958]: [Metrics] CommandRouter: 31s 967.41ms Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::volumiosetStartupVolume Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::Close All Modals sent Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::Close All Modals sent Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 00:15:16 volumio volumio-remote-updater[874]: Test mode disabled Oct 30 00:15:16 volumio volumio-remote-updater[874]: Alpha mode disabled Oct 30 00:15:16 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 30 00:15:16 volumio volumio[10958]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 30 00:15:16 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 30 00:15:17 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 30 00:15:17 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 00:15:17 volumio volumio[10958]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 30 00:15:17 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:17 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Oct 30 00:15:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:19 volumio go-librespot[11400]: go-librespot daemon starting... Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="app state loaded" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=info msg="zeroconf server listening on port 42283" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="obtained new client token: AAC05zCLepEgdiGbveCw7JlAi1Gf5SCtSkLbJNS+b3R6U2X2So5MtqWXCkG/d8hiz7XJp/3doObY7dzvMLpG3UKT5aQ/p6ceuACYutuHXv0VVWMHCUHvj8y7vJ+l/qlnXnUuTh6oUFntngi4UhTKbFIKySPGeUIL2jXyJzPIdyfp/ZI1K5RLAK0KEGMbyBl+GKguJOzZhXQdlcruA8GFYNfLsmw7x+Kr0L00TgLPLAHEgfYGElBX" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="completed keyexchange" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=debug msg="completed challenge" Oct 30 00:15:19 volumio go-librespot[11401]: time="2025-10-30T00:15:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 00:15:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:20 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:20 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Oct 30 00:15:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:22 volumio go-librespot[11408]: go-librespot daemon starting... Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="app state loaded" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=info msg="zeroconf server listening on port 43293" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="obtained new client token: AABx+ebHSCUx5C/8COXXSpQ1l2mqq1lu+Qodqm95SHSKvOSUDwK33RdBKLtxvNtRThZ/o84AsBPE95d889hG9YOBC3rzbds70B78W2vpCSPp32NxJFaVj9azKkKHCAUX9hoXivizayoxZVfbLJKYigy/5sTnY5C+KweAaduCz7iGDF5JUKIyqLHF/QNOvXHwHzOSzci9RxXr5rPB7c4doXjGR82g7Ap2iMvP/WAdEp/wJw2nPgx+Ju8=" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" 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 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="completed keyexchange" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15:22Z" level=debug msg="completed challenge" Oct 30 00:15:22 volumio go-librespot[11409]: time="2025-10-30T00:15: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 30 00:15:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:23 volumio volumio[10958]: info: BOOT COMPLETED Oct 30 00:15:23 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:23 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Oct 30 00:15:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:26 volumio go-librespot[11430]: go-librespot daemon starting... Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="app state loaded" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=info msg="zeroconf server listening on port 33021" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="obtained new client token: AABSPnqP/eErbpW+DRBZrP8KfUmAuNIiVEy7eEAwsvoyA2jBugBcMfGQO8AUutkO3wlaSMEMi8qmPmMc8Zrx1Um1LkpA5lu4nDJh58jtBFWcqEPjxXxVGcAW9DdY97QcVTefXcYQBAXXCRkIdpjNDU+vs2vTLBFPENqM00w/rY4SumavMbER8H6Y22C7yUG3FnEq5awc20b7/IsYLuuqgDlhoE1dYT0j97f9qR11bIm5VStLZ1fZ" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="completed keyexchange" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=debug msg="completed challenge" Oct 30 00:15:26 volumio go-librespot[11431]: time="2025-10-30T00:15:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 00:15:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:26 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:26 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:29 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:29 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Oct 30 00:15:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:29 volumio go-librespot[11438]: go-librespot daemon starting... Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=debug msg="app state loaded" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15: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 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15: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 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15: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 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=info msg="zeroconf server listening on port 41625" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=debug msg="obtained new client token: AAAGC7MOnHNIIi0CtjzyNZIZ5o1o/YeopfVhNeAKJzATS+4ebvLQQVBIDeJwCrzbWv+xEITxO5/EI8B653Fsdgb2YkupfYEO6zpStsBZLIoYRtVx9d7zf8edppO4dfoEOD9QgJdcSLC90MPeEwVYnRrEQqrg7eMw8tGo2ptWaWnOXHubBPUPwwcjRsORxjGrpJ0pjrIEVT/KozWcY0i0/uwydtdlTkgIsellHgq1uHa+36t2WC2c3K4=" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=debug msg="completed keyexchange" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15:29Z" level=debug msg="completed challenge" Oct 30 00:15:29 volumio go-librespot[11439]: time="2025-10-30T00:15: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 30 00:15:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:32 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:32 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Oct 30 00:15:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:33 volumio go-librespot[11450]: go-librespot daemon starting... Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="app state loaded" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=info msg="zeroconf server listening on port 34257" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="obtained new client token: AACoahv7AQvN8i5a8Hou6lFHq6wvPXsisCr2aYr/dj2OqtqtRoNFEGvCrUgivuM3PWwf5Qs6iIhdqCzfy4B7va67rokE1BJSLwxfESVvU9Rvp6m+chO+hZW/p8iVJBSDgFhpVkFt0OACXVUIVTswC/Zm2VQHuzMDAHrlUVUcZRKHX/m+C5SjO6Kf6vcOZ65y2lQyJybcn4ZKiZlL1mL2bUAOLPxhL8sPP8VCVfxs0QH8Iiqhm47V" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="completed keyexchange" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=debug msg="completed challenge" Oct 30 00:15:33 volumio go-librespot[11451]: time="2025-10-30T00:15:33Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 00:15:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:35 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:35 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Oct 30 00:15:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:36 volumio go-librespot[11472]: go-librespot daemon starting... Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=debug msg="app state loaded" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" 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 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" 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 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" 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 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=info msg="zeroconf server listening on port 37931" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=debug msg="obtained new client token: AABnowl0WPD4aiGFiLYff1g2ePzeXtGFcrYKlMAd49sGCABvRGyfuGc0dOoTQEVjxAYLyfG9tX5CZ5xX2zY+Z/oTq6inPU5kgf7DzVg+pnCXZ2Bu3miJD0cxdtswf/9Ca8aN4Lbie8N6eK+sOMHbJ1qYIx9iVsB0W/02KvcIkePYep8Z4VQ13wfiEFG/wf9ZhI1WkOVz8O6qFYsWtlxD638PV97diNQNh2lMKY1PeuCi59B2efjYhgc=" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=debug msg="completed keyexchange" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15:36Z" level=debug msg="completed challenge" Oct 30 00:15:36 volumio go-librespot[11473]: time="2025-10-30T00:15: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 30 00:15:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:38 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:38 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Oct 30 00:15:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:40 volumio go-librespot[11482]: go-librespot daemon starting... Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="app state loaded" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=info msg="zeroconf server listening on port 45115" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="obtained new client token: AACOCubF9NKocOrZI+ZiH05dDlC943bLjOctmF/wqY074ERD2nUs6kR3uutkd4gYOIkGqMtOagHepoT7HM9x6VTmZGd4gbQIpxyX4lK9eUKGJRZsBw8RyRzlHR4nhWoQaS5AwXx2VBt/pzkvavIPkfiaRUEcxW61WmAUJNyMoD5Qq7xXyfIWqXpUHdN2L6NYd2Hl6ab6fZhaLqkqkRaBasD0x+z8aydJXXEC04DG7XOWM/2lHmZN" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="completed keyexchange" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=debug msg="completed challenge" Oct 30 00:15:40 volumio go-librespot[11483]: time="2025-10-30T00:15:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 00:15:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:41 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:41 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Oct 30 00:15:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:43 volumio go-librespot[11490]: go-librespot daemon starting... Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="app state loaded" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=info msg="zeroconf server listening on port 43905" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="obtained new client token: AAASkHSxZ8THY4257yT3Patmewv9F65fAWzh5sHMoTuvM9weRpv1u9aCJhH2CTdgVsy9k3mUuM/01jbBcRuDWRiuvks3eiBS4qNQn5oFrxtml55CpYRInOZiUtnxEwwJ4xs51rn93Qjdkx4Kipx4dInknhvVEdZGFr186CYwWOFBu9s23pE6o4y0lMlNDpYobZS+wKRBFBa2HEloOaVTKswXxGyCm9GEd24nLcnXBQlPugM3Hxw5v9g=" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="completed keyexchange" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15:43Z" level=debug msg="completed challenge" Oct 30 00:15:43 volumio go-librespot[11491]: time="2025-10-30T00:15: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 30 00:15:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:44 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:44 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Oct 30 00:15:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:47 volumio go-librespot[11512]: go-librespot daemon starting... Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=debug msg="app state loaded" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" 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 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" 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 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=info msg="zeroconf server listening on port 38601" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=debug msg="obtained new client token: AAAUlylmaqKPAu4KaNN0m6xNqjIYUSJw+zBn/t0dB0x80p8xfX5sSM0Ty6w4ZnfiGaErVTfPpV9KC06NljFRSAISlePlLfdXhirlNQqnEzY224MudYNZ6T65OBSxDIXqGAJmsYr8LRcZ1RnQJpYBGhtirmgnRGbhGfcQ3ENxAaGqgDapAi7Ckq3Fyx/aNWzmxiD0xLzp2bItKqk7VwQolgcdUHdDrsq8JJbWN8MR6ljYZQZ8pscF" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=debug msg="completed keyexchange" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=debug msg="completed challenge" Oct 30 00:15:47 volumio go-librespot[11513]: time="2025-10-30T00:15:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 00:15:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:47 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:47 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:50 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:50 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Oct 30 00:15:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:50 volumio go-librespot[11526]: go-librespot daemon starting... Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=debug msg="app state loaded" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" 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 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" 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 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" 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 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=info msg="zeroconf server listening on port 40541" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=debug msg="obtained new client token: AADd/CoV19TgvO+if0lJMWKrLzI+/ZZmK6FsF7Go2vv4yaEfvkn56GF2uTGSgyJaPyVtJiw6/ErDAHkfIFC9JALR7Pe5wtLoC5yYBrGdtRkl4dLOHRJHMXl03HhnTUF7qWQ8GJ9CU8MeM+3Up+A/eDdmcSfBH31t4WvNlMZx/yZ0lpXVU2twoehckq6ztOrVPfal6uX9aSP4M5pL9yfNU6Vl2h1TqTmhnFHEdHFd/leqCqtThPNPNY0=" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=debug msg="completed keyexchange" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15:50Z" level=debug msg="completed challenge" Oct 30 00:15:50 volumio go-librespot[11527]: time="2025-10-30T00:15: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 30 00:15:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:53 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:53 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Oct 30 00:15:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:54 volumio go-librespot[11535]: go-librespot daemon starting... Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=debug msg="app state loaded" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" 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 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" 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 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" 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 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=info msg="zeroconf server listening on port 38181" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=debug msg="obtained new client token: AAB0iQfQ68TSUUY4u17L5lWJDrFGK29dT51GAwqxLd/op5en26CtiZPV8mlVM1S22oYI1vG1UpPUHVjqeqnTiokfV5MjLgd0fgHpFCsp7xZIF9N/gVwbEcw0eT4rQ4ZMPnp/kJlqXEsbXNf9HgFBcdpIp7vYkg1psmnOUc3k0FXHxQSQIO6kRQJZj1/oJ8B3KfxYqDgEE1KQsEWhM/wujOKVwZrXtHl0mf+R9m574ZXLFHMyCEEX" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=debug msg="completed keyexchange" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=debug msg="completed challenge" Oct 30 00:15:54 volumio go-librespot[11536]: time="2025-10-30T00:15:54Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 30 00:15:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:56 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:56 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:15:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Oct 30 00:15:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:15:57 volumio go-librespot[11557]: go-librespot daemon starting... Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=debug msg="app state loaded" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" 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 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" 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 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=info msg="zeroconf server listening on port 34239" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=debug msg="obtained new client token: AABK8pMedvJB2NC7QSm5eyHFH8qIkKitBysa/7nb9bpJbKOLbCbqZof/dgtYoznO3kLSfT90uONCSLfcIVcjh44eW5eOd8uYwEnZrtBT/zZoko8OvKyTXUXIjHPqXAjWAbIO6H+6YNjUfknNDP8/D+VYHTOH2enpjw2L3DaSErN+26ih58b16Slsi5WNK4+klLMeRAA2ZawnXy/ML0bo2g25sUTWyYEUzSz2Ck8kVPk9FVSEyjk3aEE=" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=debug msg="completed keyexchange" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15:57Z" level=debug msg="completed challenge" Oct 30 00:15:57 volumio go-librespot[11558]: time="2025-10-30T00:15: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 30 00:15:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:15:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:15:59 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:15:59 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:16:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Oct 30 00:16:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:00 volumio go-librespot[11565]: go-librespot daemon starting... Oct 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16:00Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16:00Z" level=debug msg="app state loaded" Oct 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16:00Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16: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 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16: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 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16: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 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16:00Z" level=info msg="zeroconf server listening on port 36383" Oct 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16:00Z" level=debug msg="obtained new client token: AADwOr9+k4wneb5J837SGjVOjIchrTDUwj66yH3z5NMry/j2dcSbvuaCgPcXE6kmbURsA8AOCDl9xdIpyarsVpm3Pm4/saajzFXeZcXp5SDkTqgCyT845nLNI1WTDB/7lJ06vQGHuKcypfJWFFIvyztGJyc/GMpvAbo3R8qVPspaArAgjFa6drljMw+yk2JCDT98aEGEn0mkb8SCbvPmA5evaxIRuA+P5EYaUmb0lDdFPcKYOfud7Fs=" Oct 30 00:16:00 volumio go-librespot[11566]: time="2025-10-30T00:16:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:16:01 volumio go-librespot[11566]: time="2025-10-30T00:16:01Z" level=debug msg="completed keyexchange" Oct 30 00:16:01 volumio go-librespot[11566]: time="2025-10-30T00:16:01Z" level=debug msg="completed challenge" Oct 30 00:16:01 volumio go-librespot[11566]: time="2025-10-30T00:16: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 30 00:16:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:16:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:16:02 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:16:02 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:16:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Oct 30 00:16:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:04 volumio go-librespot[11573]: go-librespot daemon starting... Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=debug msg="app state loaded" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" 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 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" 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 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=info msg="zeroconf server listening on port 33145" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=debug msg="obtained new client token: AADEK7ubOnsKK6oqeGuzSP53nh8XvvWZKUQ5G/W8RZ5vZAjlE8Mu9tJBw2nZ3hgA+bOac5Ulvu20eTfGssTQDihewdkiMvLha/A8zhgMhrrcd+jr8pVDBFzUBZ0oeSoLIXydfQ1Yh8lDyEUzZe9USW1RvJIAIMHnrzGskfKA1hKL6TMj0Qb7BFKWHzO/s7uNo5HkkaYy9Wov4UvadczfsUiyRB7eGCjpCXKymrK13O2EoXTb+lv+JCk=" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=debug msg="completed keyexchange" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16:04Z" level=debug msg="completed challenge" Oct 30 00:16:04 volumio go-librespot[11574]: time="2025-10-30T00:16: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 30 00:16:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:16:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:16:05 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:16:05 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:16:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Oct 30 00:16:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:07 volumio go-librespot[11595]: go-librespot daemon starting... Oct 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16:07Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16:07Z" level=debug msg="app state loaded" Oct 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16: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 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16: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 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16: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 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16:07Z" level=info msg="zeroconf server listening on port 43571" Oct 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16:07Z" level=debug msg="obtained new client token: AADqrb1ZRQI0/Xi7FeExANLsDY99aVbBuj3BthRkoDCMfD2Fnv1INlJ85Mrt91PejqJsXGNgeF14WNZgKcHOXWiSY5+c5TQm2DVaeyGU1Zo0CIqkb2uDnxyO5YcAU8nltI3Sczlrx7vi2vk7hVxrtEMYLuBG2ljktTY5MhFmAyIlWF7z7Zd+Hgho42PmibHxKmxeXK4Db1YFCaSM1N3bTNs16/SLNEJSHK8SzhASjolElZCstwue9a0=" Oct 30 00:16:07 volumio go-librespot[11596]: time="2025-10-30T00:16:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 30 00:16:08 volumio go-librespot[11596]: time="2025-10-30T00:16:08Z" level=debug msg="completed keyexchange" Oct 30 00:16:08 volumio go-librespot[11596]: time="2025-10-30T00:16:08Z" level=debug msg="completed challenge" Oct 30 00:16:08 volumio go-librespot[11596]: time="2025-10-30T00:16: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 30 00:16:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:16:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:16:08 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:16:08 volumio volumio[10958]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:16:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Oct 30 00:16:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 30 00:16:11 volumio go-librespot[11606]: go-librespot daemon starting... Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=info msg="running go-librespot 0.4.0" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=debug msg="app state loaded" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16: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 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16: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 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16: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 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=info msg="zeroconf server listening on port 38765" Oct 30 00:16:11 volumio volumio[10958]: info: Initializing connection to go-librespot Websocket Oct 30 00:16:11 volumio volumio[10958]: info: Connection to go-librespot Websocket established Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=debug msg="new websocket client" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=debug msg="obtained new client token: AADHeRAhXV8piKRxXWeVlz0vJb1wc78txaMWVW4LnCR/1Dt+mG9bKcX1FFlc8zf96T9kLJOY8pqIGYrOroyHndZAvTgGwR1gSion8JQ7IK0+z4c9jr0HQmwXHtiIw++4cnKfqCd//kCpIHpAgDJRihn08wAs9lhkoL3mSZ67OBuTAdQ+M61MtS/w7Yo7dyQD5WqA//tfx0TVkw/up5omMCiB1o+CR28ZPVT2wiF3l//aonFUhONQjPk=" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16: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 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=debug msg="completed keyexchange" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16:11Z" level=debug msg="completed challenge" Oct 30 00:16:11 volumio go-librespot[11607]: time="2025-10-30T00:16: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 30 00:16:11 volumio volumio[10958]: info: Connection to go-librespot Websocket closed Oct 30 00:16:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 30 00:16:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 00:16:14 volumio volumio[10958]: info: Getting Spotify volume Oct 30 00:16:14 volumio volumio[10958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 00:16:14 volumio volumio[10958]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 00:16:14 volumio volumio[10958]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 30 00:16:14 volumio volumio[10958]: errno: -111, Oct 30 00:16:14 volumio volumio[10958]: code: 'ECONNREFUSED', Oct 30 00:16:14 volumio volumio[10958]: syscall: 'connect', Oct 30 00:16:14 volumio volumio[10958]: address: '127.0.0.1', Oct 30 00:16:14 volumio volumio[10958]: port: 9879, Oct 30 00:16:14 volumio volumio[10958]: response: undefined Oct 30 00:16:14 volumio volumio[10958]: } Oct 30 00:16:14 volumio volumio[10958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 00:16:14 volumio sudo[11628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-30 00:15' Oct 30 00:16:14 volumio sudo[11628]: 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"