Nov 05 02:05:00 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:05:00 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:05:00 volumio volumio[18704]: info: Listing playlists Nov 05 02:05:00 volumio volumio[18704]: info: Listing playlists Nov 05 02:05:01 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:01 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Nov 05 02:05:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:02 volumio go-librespot[19111]: go-librespot daemon starting... Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="app state loaded" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=info msg="zeroconf server listening on port 37459" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="obtained new client token: AAD08BJUcfpYMG1EuVwM6r7H7l8HNb3HdPdtBSLbIL0CBZsXxSZIHTcp/DZpAJzz+7penFe60OLJXSwzye8359ofHumH0aNfuAonTdDm1fsRhpUc3DVel01Qg2EPRd/XbOzTUyN+Gl9JypJgjVcKqTHE3lHqneEI1z9bFGNFcB9A5Q2DtuFheFb/N9LcrIJ0i/xWKKdRi94QyNBtAy/x58zvow4rWnetf2lMzeI6td1EQOU2mRpUPBg=" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="completed keyexchange" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=debug msg="completed challenge" Nov 05 02:05:02 volumio go-librespot[19112]: time="2025-11-05T02:05:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::volumioGetBrowseSources Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 05 02:05:03 volumio volumio[18704]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 05 02:05:03 volumio volumio[18704]: info: Completed starting MyVolumio Plugin Nov 05 02:05:03 volumio volumio[18704]: [Metrics] CommandRouter: 32s 91.27ms Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::volumiosetStartupVolume Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::Close All Modals sent Nov 05 02:05:03 volumio volumio[18704]: info: CoreCommandRouter::Close All Modals sent Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 05 02:05:04 volumio volumio-remote-updater[874]: Test mode disabled Nov 05 02:05:04 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 05 02:05:04 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 05 02:05:04 volumio volumio[18704]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 05 02:05:04 volumio volumio[18704]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 05 02:05:04 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:04 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Nov 05 02:05:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:05 volumio go-librespot[19129]: go-librespot daemon starting... Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=debug msg="app state loaded" Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=info msg="zeroconf server listening on port 37905" Nov 05 02:05:05 volumio go-librespot[19130]: time="2025-11-05T02:05:05Z" level=debug msg="obtained new client token: AACMcByTp5X+CYJMSDLtzZhgUK4d8NGW7ANs7T6zVS8WbS17cpqPgyo8A1sb9cJJ7lPoWzIwn1WMBlfqmzTxo/4B9ZFeM4R9pgoF2iQ56/Ae9RFUwPgg0Rf5d3bHGGfYAR74rKlRPbcfU8xjVdUbjzzbwEACs1VMEN+TVyMy6s/HTPQEOpQYi0ORHEKqoRPI1v0x37b8XydQlIsqESy+1/YyZuXthle6kUkkrkR/+13AEc3P/IYtmYw=" Nov 05 02:05:06 volumio go-librespot[19130]: time="2025-11-05T02:05:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:06 volumio go-librespot[19130]: time="2025-11-05T02:05:06Z" level=debug msg="completed keyexchange" Nov 05 02:05:06 volumio go-librespot[19130]: time="2025-11-05T02:05:06Z" level=debug msg="completed challenge" Nov 05 02:05:06 volumio go-librespot[19130]: time="2025-11-05T02:05:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:07 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:07 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:08 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 02:05:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Nov 05 02:05:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:09 volumio go-librespot[19137]: go-librespot daemon starting... Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="app state loaded" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=info msg="zeroconf server listening on port 33103" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="obtained new client token: AAA/TqUNnGvE/nwbnRsVOfJ7/fYVy6bP3E7H9ZbkiF7asXCBpswPP2nhYhI2erzcbufI1KqCS1am4s147tfjtAD5bSf3hXSePIKZJGWxRGp0QY7OVB+WeWHqHYiD+MWz6aUVByFNnSkvikh+aOjt9T7IwXb4/k05vWxu9OUTmPMXd4/yVhB7IcsfgkP7+youF+CUkCg8yUzryLVjn1y0JJR4jWIBfaWr+4Lt8tbeZZS76GEWbyE7cAA=" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="completed keyexchange" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=debug msg="completed challenge" Nov 05 02:05:09 volumio go-librespot[19138]: time="2025-11-05T02:05:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:10 volumio volumio[18704]: info: BOOT COMPLETED Nov 05 02:05:10 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:10 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:10 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:05:10 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:05:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Nov 05 02:05:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:12 volumio go-librespot[19160]: go-librespot daemon starting... Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=debug msg="app state loaded" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=info msg="zeroconf server listening on port 37421" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=debug msg="obtained new client token: AACpVqIWtw+eVShktFrQvDVvwlCzKeZJjRHp4Gd2/vtlqEV7Op1a5vL2KwFkQ7Knw89F/PoUbpTidCmyhdZvNwpBWoJi/4hYDMnbKeA4KzC21ha/QskWpnIgtljnmXcZDr1ndWzh1L7DQ7egi5CSV1yu8b/N46/Jc/O+j2Xwm1zXFusY8N5Hib0K/3bnbu57GHP+U45cxvIPVLbQxg97vtlwMm8lDw26srcSYA4eXT9v0h+O9VnpIRY=" Nov 05 02:05:12 volumio go-librespot[19161]: time="2025-11-05T02:05:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:13 volumio go-librespot[19161]: time="2025-11-05T02:05:13Z" level=debug msg="completed keyexchange" Nov 05 02:05:13 volumio go-librespot[19161]: time="2025-11-05T02:05:13Z" level=debug msg="completed challenge" Nov 05 02:05:13 volumio go-librespot[19161]: time="2025-11-05T02:05:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:13 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:13 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:16 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 02:05:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Nov 05 02:05:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:16 volumio go-librespot[19170]: go-librespot daemon starting... Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="app state loaded" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=info msg="zeroconf server listening on port 34405" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="obtained new client token: AACEaD9y7uweWDv7SExtQdtL2wsnLTAMvFC3V42j7QaFnZdjO0bT6pyrPsnxhEAyEkgwmo5RCUhVjvb0l7bwSjeOTDLYoX1Xj/RlCPy/5OR9YpU0O51Tp0Nl8ZF8nSdHgzS8B5sjGCL9lWXHkreW4eBBqFKbfUot8lYnW1opiOV4SfYXMkDvhBnMwoiHvagjs8gYMY7JiAuw0Wxhcwpj6nIFqo//BJ3dBb837Gs3FGeuJ0BAkpUnMtM=" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="completed keyexchange" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=debug msg="completed challenge" Nov 05 02:05:16 volumio go-librespot[19171]: time="2025-11-05T02:05:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:16 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:16 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Nov 05 02:05:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:19 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:19 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:19 volumio go-librespot[19181]: go-librespot daemon starting... Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=debug msg="app state loaded" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05: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]" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05: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]" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05: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]" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=info msg="zeroconf server listening on port 40869" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=debug msg="obtained new client token: AAAH2NscI9uOMSrMwpMBnc7HOV3GC1EKSd/CsWkQna+91Tp2s2hOLeIzlHqKuDlM2ehNdttO8XTDnZaKe2sFaqVxNBbxwyFqkr6nvlfTHCpfXxVfIoOuKP/YvwAgrrpl83bigixuqGMSL3vK65YPVCjNcEdpfYTfOBNo4tdRRZd101j5nh6MajcWidQ4wcfnl+sXgzfk60PBL41zcyYnFfXIKWAHJq4Q0nwfgJJt17eLIrRPiW4d2L0=" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=debug msg="completed keyexchange" Nov 05 02:05:19 volumio go-librespot[19182]: time="2025-11-05T02:05:19Z" level=debug msg="completed challenge" Nov 05 02:05:20 volumio go-librespot[19182]: time="2025-11-05T02:05:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:20 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:05:20 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:05:20 volumio volumio[18704]: info: Listing playlists Nov 05 02:05:20 volumio volumio[18704]: info: Listing playlists Nov 05 02:05:22 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:22 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Nov 05 02:05:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:23 volumio go-librespot[19203]: go-librespot daemon starting... Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="app state loaded" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=info msg="zeroconf server listening on port 39089" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="obtained new client token: AABHHM4tdrgWE5YWQg44Vt6B484mcIBqEwNv9mdMH+twdZ8+vBw0Q4M8IrAym7i/Ynf/+Flx2GQ1z2HflEWeufTN8CmFYBKKDVYcQhm8fVs0mtkIH+Gc/8Pw6co3XZv6GjHhPmWSpq3TaYV0P/9hVNBtWGPFARDND+YOSEpdS1Zpo0SE/BKorswAZrcwH5ghKgs0TZEb/2YMPHoPOvtNkYi0B6vmBoGgo2rEnNzvZ/zY76rVgTuh5CM=" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="completed keyexchange" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=debug msg="completed challenge" Nov 05 02:05:23 volumio go-librespot[19204]: time="2025-11-05T02:05:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:24 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 02:05:25 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:25 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Nov 05 02:05:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:26 volumio go-librespot[19212]: go-librespot daemon starting... Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=debug msg="app state loaded" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=info msg="zeroconf server listening on port 39489" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=debug msg="obtained new client token: AACJzT6GDsnkwhQfV+RN7xZAQZpJfvRW0ktqxVSJtMR6utiRNbtkyinlzi5gvIIDCiCAxtvUusK5yQYynYq6wTtQ5RhnDH9ti8ogPhVu2zwBgussKSpVTtSqcVOL0M/ShACKKxQF7Lcg0+XgGHcqicvR4li3ZaChoozD7yu5np4eL+ICGHI5h5uugwA8oA8ZzwKTwZM9RT/CDiLB8wQ5LP97tOdP1wCPeGCNQR8X/D9o7Dw65aKcSXg=" Nov 05 02:05:26 volumio go-librespot[19213]: time="2025-11-05T02:05:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:27 volumio go-librespot[19213]: time="2025-11-05T02:05:27Z" level=debug msg="completed keyexchange" Nov 05 02:05:27 volumio go-librespot[19213]: time="2025-11-05T02:05:27Z" level=debug msg="completed challenge" Nov 05 02:05:27 volumio go-librespot[19213]: time="2025-11-05T02:05:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:28 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:28 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Nov 05 02:05:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:30 volumio go-librespot[19220]: go-librespot daemon starting... Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="app state loaded" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=info msg="zeroconf server listening on port 38891" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="obtained new client token: AADm5nQRQERUuAfM8OGiXPtIFlyvbxLL6iH1+UGJLXXlJpnA4R7cV6AZiI+HDYfMVUj7daUgprCINRU3RXXyn+1L7nEEqA7ctSinW3J8pgn6i+MpL4Eeu0S1jbUDbHy1A6lsU1hYZm+I20NM0rlU3hh9l3KJYMlhPAE9KmHM/9QYMeAuB7SDcIYLyuiLXqsqmkA08NtU/IFPgUwLUyHwXZe+c7cwlDEV5GnlMy6uT+E1A+zH4jU5gL0=" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="completed keyexchange" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=debug msg="completed challenge" Nov 05 02:05:30 volumio go-librespot[19221]: time="2025-11-05T02:05:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:30 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:05:30 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:05:31 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 02:05:31 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:31 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Nov 05 02:05:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:33 volumio go-librespot[19242]: go-librespot daemon starting... Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=debug msg="app state loaded" Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=info msg="zeroconf server listening on port 38035" Nov 05 02:05:33 volumio go-librespot[19243]: time="2025-11-05T02:05:33Z" level=debug msg="obtained new client token: AABwh48N1yfZzUeYFacNbyXOQkD5LIEMyMdLYNW0wIWESMEHj/G9QBXuzN5cf3et6lEjZ96nQkpfoeBuAAl44BgH37q00Qqpsoh9ILLHb+MX1gFEVPSymA/H8Ya4utcwwbdBKxEN7KW0NCPvHNIdeHyR3mHpQ4ctQCuk1s7tc1D7qU+YKvaWdO2nRAKOfB6IllZGRa96Q4rCyB2PsVojR2u5j+S7ovDEdCF/SOQXOVBk/nlYuKD4gYE=" Nov 05 02:05:34 volumio go-librespot[19243]: time="2025-11-05T02:05:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:34 volumio go-librespot[19243]: time="2025-11-05T02:05:34Z" level=debug msg="completed keyexchange" Nov 05 02:05:34 volumio go-librespot[19243]: time="2025-11-05T02:05:34Z" level=debug msg="completed challenge" Nov 05 02:05:34 volumio go-librespot[19243]: time="2025-11-05T02:05:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:34 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:34 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Nov 05 02:05:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:37 volumio go-librespot[19250]: go-librespot daemon starting... Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="app state loaded" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=info msg="zeroconf server listening on port 35861" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="obtained new client token: AAADJMhL/Zu/yykfstnspvIooZ+7pYHSWVJzwSqezY2cfanajXMP6xXC7lzHJ8u/dGJ7L4MXH18UHkrlMM+y9F2I+/AKW/2R54N5qXlWMPDU67wXiv8iZ8jCj09HqHvGeNajAO/SmYrVONP8rugaN5rWbN857TaWwUf9t5UuVIMJk49OzVWTVP70j10eQu0IFvQc9thWyWPUNY7paiLJ7mDEE0Ob8YaTU1mXergHCvSMLayzICuPpT0=" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="completed keyexchange" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=debug msg="completed challenge" Nov 05 02:05:37 volumio go-librespot[19251]: time="2025-11-05T02:05:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:37 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:37 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Nov 05 02:05:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:40 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:40 volumio go-librespot[19261]: go-librespot daemon starting... Nov 05 02:05:40 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=debug msg="app state loaded" Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=info msg="zeroconf server listening on port 40501" Nov 05 02:05:40 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:05:40 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=debug msg="obtained new client token: AACk92QCPAkgOdlgFSvKWo8yMTTMSiK3MneASUL/RPjujvsJLzmBwG6E9XoSa2M//ik+QyR0DxGIZAe6RXwadsAToWsFKaSNCQVUKOA+wT2w5Cj8kwosRUharPn5sVZawIE0rN+sezwWHleuQUSbBYd2q9aaR7cBkdDHYtok68ZjlE8TRAaK6z+qoORGX95PsDSjLMBWvOWCoLoQVSyrbyIL3C7P3BqJ0gtfCZISIvQTgp43mKTcdNo=" Nov 05 02:05:40 volumio volumio[18704]: info: Listing playlists Nov 05 02:05:40 volumio volumio[18704]: info: Listing playlists Nov 05 02:05:40 volumio go-librespot[19262]: time="2025-11-05T02:05:40Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:41 volumio go-librespot[19262]: time="2025-11-05T02:05:41Z" level=debug msg="completed keyexchange" Nov 05 02:05:41 volumio go-librespot[19262]: time="2025-11-05T02:05:41Z" level=debug msg="completed challenge" Nov 05 02:05:41 volumio go-librespot[19262]: time="2025-11-05T02:05:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:41 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 02:05:43 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:43 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Nov 05 02:05:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:44 volumio go-librespot[19283]: go-librespot daemon starting... Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="app state loaded" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=info msg="zeroconf server listening on port 41107" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="obtained new client token: AACYLR4/4+Lv7ULsq0/ZYH0N9Jjx1k1oH2aXGu7lKiOq8Rl+ZdFto7h6CsV8fzX0k8t33w/NWwvAY5/llaB9mUUdKrAY9LR10QktY3lI3i9E1VXzioxuiijSGKHA+eremM0aRAMkbfwomVHm9YNmSW1PNbIU6BSi9e3+C8eGSj3/MNm0ZYDWrc+oIhCUMslAnuBkg/A7v+Ig3kzRoP1W1L9SUtbYZL4J0/8PVG7BFKDJJpo70vxAU5k=" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="completed keyexchange" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=debug msg="completed challenge" Nov 05 02:05:44 volumio go-librespot[19284]: time="2025-11-05T02:05:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:46 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:46 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Nov 05 02:05:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:47 volumio go-librespot[19293]: go-librespot daemon starting... Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05:47Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05:47Z" level=debug msg="app state loaded" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05:47Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05: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]" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05: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]" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05: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]" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05:47Z" level=info msg="zeroconf server listening on port 41411" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05:47Z" level=debug msg="obtained new client token: AADfHJgTd5gf26GB7NeUrz2w825zj8RJWlbH2/Va0emn4DgYzhzklhbONATMKD2Uyv9+a9K+JrDzyqooKPcDVH/tYKkPpG64R5Gvgxxo45qFyZlMrluKtujdSSSE3R9yScv2yV+0YuxO1GrWwn/C10JVcyH76d8pw8Bqbf93ZeptXzSE9f9FVIwPgERYAFYgRPrENpn86Pg6yr5Ke//7LT7eBn9i0JiQNGfnyA55fqzQSS0z+6AJ3Rw=" Nov 05 02:05:47 volumio go-librespot[19294]: time="2025-11-05T02:05:47Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:48 volumio go-librespot[19294]: time="2025-11-05T02:05:48Z" level=debug msg="completed keyexchange" Nov 05 02:05:48 volumio go-librespot[19294]: time="2025-11-05T02:05:48Z" level=debug msg="completed challenge" Nov 05 02:05:48 volumio go-librespot[19294]: time="2025-11-05T02:05:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:49 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:49 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:50 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:05:50 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:05:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Nov 05 02:05:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:51 volumio go-librespot[19301]: go-librespot daemon starting... Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="app state loaded" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=info msg="zeroconf server listening on port 35329" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="obtained new client token: AACfgXt0Xdtt0yGDQxO9SqqSRNVAj/dKMMAx9teiReuTogz5uflMRCJkOFqk6ijNbIRvzA1p8AKJEwLReL3aa090RxZBxfH8HT6/tAgx/xlTtslxhHRz6mn0Uj40Ap+jlEmgIUD6X+WKyJIpOo2KNxB5CyStFahZw8+NzHEdVHEDziIdH8uSzKYyveK+EAbUbrd5gE8cl2OolXa9vvVUpi3zqD1ZoTT4KQXOJ4otskrPCa3ucp3A9kA=" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="completed keyexchange" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=debug msg="completed challenge" Nov 05 02:05:51 volumio go-librespot[19302]: time="2025-11-05T02:05:51Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:52 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:52 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:53 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 02:05:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Nov 05 02:05:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:54 volumio go-librespot[19323]: go-librespot daemon starting... Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="app state loaded" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=info msg="zeroconf server listening on port 44775" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="obtained new client token: AADhLgOQ6QUoLaqFXkTPzXrF5v87Dk8rcAWWXDb0AoDusmrTPLvHsogrdLoibwceI3IpqQXnCZI9o1W54iDaJfE2ZUwmDgRbctKman9DYJjYKqkjpfbb9Ap528QDm+gbKyBrfL4e7vRSS54K5u9eeEdzRaQxzmzskMexR3BpWYgrrZ4sfdLq0oYB2w4XRE0JLhuxhfMt4XlQzsW15VHDyayH9x3sEgPnjwD2MCPJRlDq/ZjSdCBg/Vc=" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="completed keyexchange" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=debug msg="completed challenge" Nov 05 02:05:54 volumio go-librespot[19324]: time="2025-11-05T02:05:54Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:55 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:55 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:05:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Nov 05 02:05:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:05:58 volumio go-librespot[19335]: go-librespot daemon starting... Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="app state loaded" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=info msg="zeroconf server listening on port 46279" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="obtained new client token: AAAZh3chl/Md4phLgbblAQqadt7hNR17GZ2jVzNlLMsA27Lg6WtjqIWMYE7gziMB0QEYg6bnNbVvBGupBVW4r5h/RIZKyXarP7mpacEJB6sEyyds9LQ3K2kielpn/A6OV74UNmg4LliZkoBVOsB6rV7qqrHhFbDRJFmEq0kuAZGNeeC3WH5iT2L600rHWqnvKbUbuWtbYu8erqOaZMQIVLaD4OTQQy9OAi2PcgTPIaGltlRfHOHP" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="completed keyexchange" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=debug msg="completed challenge" Nov 05 02:05:58 volumio go-librespot[19336]: time="2025-11-05T02:05:58Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:05:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:05:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:05:58 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:05:58 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:00 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:06:00 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:06:00 volumio volumio[18704]: info: Listing playlists Nov 05 02:06:00 volumio volumio[18704]: info: Listing playlists Nov 05 02:06:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Nov 05 02:06:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:01 volumio go-librespot[19344]: go-librespot daemon starting... Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="app state loaded" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=info msg="zeroconf server listening on port 36803" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="obtained new client token: AAAo+HQzTyiZt3Jq6BoWSlPBbv/7E7A1HPBidMlK7uxWqbr1VrcLglEjEYl8o+xoaU2D2Qe68XKPumn7ElmzWS+zKhcIQPhaZm9AwwH6BIcav+SEbCnxGbGqwjj3JwgfStyTZyxBiKAZkcEchLdP1ydHfdydqu7XYYzzBW+MvLZ6EjFzQpGKgW96SDgi2lCgLrvpCpAGokzCp3JAQDplPKMC/LHTQET1g0cj6XPAtjrnNMI/mMtf5nY=" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="completed keyexchange" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=debug msg="completed challenge" Nov 05 02:06:01 volumio go-librespot[19345]: time="2025-11-05T02:06:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:06:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:06:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:06:01 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:01 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:04 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 05 02:06:04 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:04 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Nov 05 02:06:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:05 volumio go-librespot[19366]: go-librespot daemon starting... Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="app state loaded" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=info msg="zeroconf server listening on port 34909" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="obtained new client token: AADWTBJF03RNXYZWcezK+xdr0HA3TyOPEh6KN3Oz54YSESCsbVClO8Hio8DiuY6GVUL/+Qbp36xIAR0yIqNwyVniLtmS8B2N9leLBfIWIylg3/nzLkT5Y69CjwLVH4B6rr4n8bFHhsrChLexMX9xJo8xWeM+KXhVI3RshqMC9SU7HoGLyr2PRg2gRZmExfPzzPqRshQJXaygwDbXBO7UyuxHK9T/3ATv9e7+Yw7itox3rziLSP1K" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="completed keyexchange" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=debug msg="completed challenge" Nov 05 02:06:05 volumio go-librespot[19368]: time="2025-11-05T02:06:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:06:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:06:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:06:07 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:07 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Nov 05 02:06:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:08 volumio go-librespot[19375]: go-librespot daemon starting... Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=debug msg="app state loaded" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=info msg="zeroconf server listening on port 43019" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=debug msg="obtained new client token: AACG73a0zvDkKoPOQQdyFdCYAM2ckpNuw0pGx980Q7v3TNXAJiUkbexLmrfvkVEg2iUmRAF+iQ+6mozoAhilioqTfiRcwH3P43CtRoY+wUHsqKv9feBME5DcIN1xTtQcLpRhh+FUwgJQQemsF2ZjvfJBG3FL54QYctQ13L0MndskyPg7A+xrAy85R78ZewHiOvJUcgDEtIvBhTLhi2WFMtsLUSiDy3hZiUrzvEB+7vDVMlr21U43xIY=" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=debug msg="completed keyexchange" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=debug msg="completed challenge" Nov 05 02:06:08 volumio go-librespot[19376]: time="2025-11-05T02:06:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:06:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:06:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:06:10 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:10 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:10 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:06:10 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:06:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Nov 05 02:06:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:12 volumio go-librespot[19383]: go-librespot daemon starting... Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="app state loaded" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=info msg="zeroconf server listening on port 32995" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="obtained new client token: AABDhw8/E9UvLmxo+uEiWAsQv/ftrs5zZSRxcOD8tMcdRbyA4UmUE5HaRkKP50p9nCsYdM9HidG9rBLZBs3Ag8sxbS5L+RUjPYljxeupuIzKmvQ+CbSyeOXHRgg29z1RTQV3LInoJHrHGJhexwVvZwHOzc+EbGv5DU5eMYA/Id7LiEY4tBQoFEfz7e4FZmYNZCYI3cF/ver3MZUaa+/pZ4n1Rp7r/GfkpxLevWHZOdQoWfcPLkdn" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="completed keyexchange" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=debug msg="completed challenge" Nov 05 02:06:12 volumio go-librespot[19384]: time="2025-11-05T02:06:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:06:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:06:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:06:13 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:13 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Nov 05 02:06:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:15 volumio go-librespot[19406]: go-librespot daemon starting... Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=debug msg="app state loaded" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06: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]" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06: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]" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06: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]" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=info msg="zeroconf server listening on port 46829" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=debug msg="obtained new client token: AACHNS11uCCRpzO16G3q9tQ+k0xeaH+k/UQZm2biTnfzSbXWSdA0/hK+dbV8MCMzjMCy6Nq+qNHdOs8YpGXwATXGo0UQnzpfcjStUpUybm0Os7w0+UqV5dXhPdZqOp1IrKbGA5ndJJb7iJ1pSF7x9viM27my8yZRCdkZnHJYtpWE5+ialhkJBkG5ioqc6QKzONi7B2O/Jv9uA9yFWFfDJm1K58UyDg6KkD3v+vhlZMYQ/P2Sny832oY=" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=debug msg="completed keyexchange" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=debug msg="completed challenge" Nov 05 02:06:15 volumio go-librespot[19407]: time="2025-11-05T02:06:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:06:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:06:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:06:16 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:16 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Nov 05 02:06:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:19 volumio go-librespot[19417]: go-librespot daemon starting... Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=debug msg="app state loaded" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=info msg="zeroconf server listening on port 35585" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=debug msg="obtained new client token: AAAzP84DCGTNnyERkj7ODPQjFDZuREBi1fRV1FOzeq+5KFzy8OS8tSVN9c2WiS6of+VKBl86R+rwpEeG5Y6Zc7aWK0I7dBlupq5H+eSpu/i/hI91/IrNsIlD30wOcQw8FNetyVkmnHC8cuFQTp2LosLpdPex5d3WJ32hApdur9UVBO3gnAr24DhDkcM8iFcI0apAxCBkCEuimIq1aeNNL2koCuEHxufoQ3FNEKLoOmgzQQ6bjHuk" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=debug msg="completed keyexchange" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=debug msg="completed challenge" Nov 05 02:06:19 volumio go-librespot[19418]: time="2025-11-05T02:06:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:06:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:06:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:06:19 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:19 volumio volumio[18704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:20 volumio volumio[18704]: info: CoreCommandRouter::volumioGetState Nov 05 02:06:20 volumio volumio[18704]: info: CorePlayQueue::getTrack 0 Nov 05 02:06:20 volumio volumio[18704]: info: Listing playlists Nov 05 02:06:20 volumio volumio[18704]: info: Listing playlists Nov 05 02:06:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Nov 05 02:06:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 02:06:22 volumio go-librespot[19439]: go-librespot daemon starting... Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=info msg="running go-librespot 0.4.0" Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=debug msg="app state loaded" Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 02:06:22 volumio volumio[18704]: info: Initializing connection to go-librespot Websocket Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=debug msg="new websocket client" Nov 05 02:06:22 volumio volumio[18704]: info: Connection to go-librespot Websocket established Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=info msg="zeroconf server listening on port 34287" Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=debug msg="obtained new client token: AABI/WTqVM5TG0dillBZIyQdNsux+nPPrbjWgVBcnQVe0DhIjv0WIcNBFlCzR5MPRWaQzTnf0SpFkjLE1VX46028EhVbjt0r6OQPn9fGCNDnYyoB0LyyZntmF/UWXfe76BaMM6Gg7MuSUZ1Orr9hgKaff7Tt0QqaGJsneufgSoex34AYtIGPvwvHwCIimvF0+8QVeeEf8Xmmy4EtYK7gGTf0nunLEX30XAtz92L3+UM2a4R27ZQZwdY=" Nov 05 02:06:22 volumio go-librespot[19440]: time="2025-11-05T02:06:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 02:06:23 volumio go-librespot[19440]: time="2025-11-05T02:06:23Z" level=debug msg="completed keyexchange" Nov 05 02:06:23 volumio go-librespot[19440]: time="2025-11-05T02:06:23Z" level=debug msg="completed challenge" Nov 05 02:06:23 volumio go-librespot[19440]: time="2025-11-05T02:06:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 02:06:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 02:06:23 volumio volumio[18704]: info: Connection to go-librespot Websocket closed Nov 05 02:06:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 02:06:25 volumio volumio[18704]: info: Getting Spotify volume Nov 05 02:06:25 volumio volumio[18704]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 02:06:25 volumio volumio[18704]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 02:06:25 volumio volumio[18704]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 05 02:06:25 volumio volumio[18704]: errno: -111, Nov 05 02:06:25 volumio volumio[18704]: code: 'ECONNREFUSED', Nov 05 02:06:25 volumio volumio[18704]: syscall: 'connect', Nov 05 02:06:25 volumio volumio[18704]: address: '127.0.0.1', Nov 05 02:06:25 volumio volumio[18704]: port: 9879, Nov 05 02:06:25 volumio volumio[18704]: response: undefined Nov 05 02:06:25 volumio volumio[18704]: } Nov 05 02:06:25 volumio volumio[18704]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 02:06:25 volumio sudo[19461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-05 02:05' Nov 05 02:06:25 volumio sudo[19461]: 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"