Feb 26 02:03:00 volumio go-librespot[1643503]: go-librespot daemon starting... Feb 26 02:03:01 volumio go-librespot[1643504]: time="2026-02-26T02:03:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:03:01 volumio go-librespot[1643504]: time="2026-02-26T02:03:01+07:00" level=debug msg="app state loaded" Feb 26 02:03:01 volumio go-librespot[1643504]: time="2026-02-26T02:03:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:03:03 volumio go-librespot[1643504]: time="2026-02-26T02:03:03+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:03:03 volumio go-librespot[1643504]: time="2026-02-26T02:03:03+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:03:03 volumio go-librespot[1643504]: time="2026-02-26T02:03:03+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:03:03 volumio go-librespot[1643504]: time="2026-02-26T02:03:03+07:00" level=info msg="zeroconf server listening on port 42919" Feb 26 02:03:04 volumio go-librespot[1643504]: time="2026-02-26T02:03:04+07:00" level=debug msg="obtained new client token: AAByURUSTV44q0Pn1zwC2pn35CRx4WdyWJDZoNg2BUkBeNQoKTaA31xVm4AX65u66DocBEbFgXE2LDB8HNL0YivzHnjC2EKyQMN5IiGB7Wdnbqy0q0jZ7LM0/8gbUl5QlbHio/Eflw9zS/km0XosDEqUME0ybtuP7B/O0RjmcxQO5vS9hjQzAQbm4bt0woTQqxzIcq3yQ80eE8fQ9xET1XauGmBBlFcQ93h5IArjMclrj2WAuzeccBF0CKY=" Feb 26 02:03:04 volumio go-librespot[1643504]: time="2026-02-26T02:03:04+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:03:04 volumio go-librespot[1643504]: time="2026-02-26T02:03:04+07:00" level=debug msg="completed keyexchange" Feb 26 02:03:04 volumio go-librespot[1643504]: time="2026-02-26T02:03:04+07:00" level=debug msg="completed challenge" Feb 26 02:03:05 volumio go-librespot[1643504]: time="2026-02-26T02:03:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:03:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:03:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:03:09 volumio volumio[1642466]: info: Updating MyVolumio device info Feb 26 02:03:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 26 02:03:09 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:03:10 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:03:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:11 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:03:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:12 volumio go-librespot[1643533]: go-librespot daemon starting... Feb 26 02:03:12 volumio go-librespot[1643534]: time="2026-02-26T02:03:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:03:13 volumio go-librespot[1643534]: time="2026-02-26T02:03:12+07:00" level=debug msg="app state loaded" Feb 26 02:03:13 volumio go-librespot[1643534]: time="2026-02-26T02:03:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:03:15 volumio go-librespot[1643534]: time="2026-02-26T02:03:15+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:03:15 volumio go-librespot[1643534]: time="2026-02-26T02:03:15+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:03:15 volumio go-librespot[1643534]: time="2026-02-26T02:03:15+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:03:15 volumio go-librespot[1643534]: time="2026-02-26T02:03:15+07:00" level=info msg="zeroconf server listening on port 42011" Feb 26 02:03:15 volumio go-librespot[1643534]: time="2026-02-26T02:03:15+07:00" level=debug msg="obtained new client token: AAC/X5KiTNl4wIN31u0SxNBDJn0ujgWg0oZcJAKn8LxeTDbB8LZuOPHiMbqJ05TQ6ABysWFpYzBHiIM5nwB6sh9Hz8h6u1n5XXxF0deK/q9ct4uVwqQ2JsnnNo6Qc3sqkgWabUPz1T7rLj4l/aBmoyTYdpCa41hCeI7dJIvIWPw/XFTZP4k5gcf9BysWTnRBtNsxFM78botrm40BYl5AbFvoD46srVKlp0SlXAfhYGmBX8lWjnXbsZVCcj4=" Feb 26 02:03:16 volumio go-librespot[1643534]: time="2026-02-26T02:03:16+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:03:16 volumio go-librespot[1643534]: time="2026-02-26T02:03:16+07:00" level=debug msg="completed keyexchange" Feb 26 02:03:16 volumio go-librespot[1643534]: time="2026-02-26T02:03:16+07:00" level=debug msg="completed challenge" Feb 26 02:03:16 volumio go-librespot[1643534]: time="2026-02-26T02:03:16+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:03:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:03:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:03:17 volumio nmbd[979]: [2026/02/26 02:03:17.063564, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 26 02:03:17 volumio nmbd[979]: query_name_response: Multiple (2) responses received for a query on subnet 10.10.9.65 for name WORKGROUP<1d>. Feb 26 02:03:17 volumio nmbd[979]: This response was from IP 10.10.9.5, reporting an IP address of 10.10.9.5. Feb 26 02:03:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 26 02:03:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:22 volumio go-librespot[1643562]: go-librespot daemon starting... Feb 26 02:03:23 volumio go-librespot[1643564]: time="2026-02-26T02:03:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:03:23 volumio go-librespot[1643564]: time="2026-02-26T02:03:23+07:00" level=debug msg="app state loaded" Feb 26 02:03:23 volumio go-librespot[1643564]: time="2026-02-26T02:03:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:03:25 volumio go-librespot[1643564]: time="2026-02-26T02:03:25+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:03:25 volumio go-librespot[1643564]: time="2026-02-26T02:03:25+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:03:25 volumio go-librespot[1643564]: time="2026-02-26T02:03:25+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:03:25 volumio go-librespot[1643564]: time="2026-02-26T02:03:25+07:00" level=info msg="zeroconf server listening on port 34913" Feb 26 02:03:28 volumio go-librespot[1643564]: time="2026-02-26T02:03:26+07:00" level=debug msg="obtained new client token: AABwqB3LhZ9x4sd9v1tyLehyv1ItwU/Qs6O5+NYegb6oqTXMV83T/qmXA8APz09i1fzSPN2HxCTO//Kh26iZNXALjRXss6d9z9oZzpTzyhdgyyr6ZuRH1J+95Tx/j2p5mq3y71afyECmuBUjs0WEygxRt30y70RByIKbaO0CDJ7pIcUmtm08dKrehrtiDyUVSjqw522WQC0m9CiZb6U0w50EOAdLrEM2+vPn3iT89RXhwjN6d3119WuuOas=" Feb 26 02:03:28 volumio go-librespot[1643564]: time="2026-02-26T02:03:27+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:03:28 volumio go-librespot[1643564]: time="2026-02-26T02:03:27+07:00" level=debug msg="completed keyexchange" Feb 26 02:03:28 volumio go-librespot[1643564]: time="2026-02-26T02:03:27+07:00" level=debug msg="completed challenge" Feb 26 02:03:28 volumio go-librespot[1643564]: time="2026-02-26T02:03:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:03:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:03:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:03:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 26 02:03:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:32 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:03:32 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:03:33 volumio go-librespot[1643590]: go-librespot daemon starting... Feb 26 02:03:34 volumio go-librespot[1643592]: time="2026-02-26T02:03:34+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:03:34 volumio go-librespot[1643592]: time="2026-02-26T02:03:34+07:00" level=debug msg="app state loaded" Feb 26 02:03:36 volumio go-librespot[1643592]: time="2026-02-26T02:03:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:03:37 volumio go-librespot[1643592]: time="2026-02-26T02:03:37+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:03:37 volumio go-librespot[1643592]: time="2026-02-26T02:03:37+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:03:37 volumio go-librespot[1643592]: time="2026-02-26T02:03:37+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:03:37 volumio go-librespot[1643592]: time="2026-02-26T02:03:37+07:00" level=info msg="zeroconf server listening on port 43825" Feb 26 02:03:40 volumio go-librespot[1643592]: time="2026-02-26T02:03:38+07:00" level=debug msg="obtained new client token: AACSlPl0byrAvjSHfViDO5BqQRmRph2MdTxLdC8K7NP5THTzsmQxqclG1PMbbM2ZCiw35bY4BE4IjmQxMei48g2jGfX7U8absP2F1Kx7iBcl6TNdQ5DU8FrFOtzdi2kQV5fVivAOaIouSkpmwK7J8pEqWiUuBBREaTfE0r1EQtMkT+hPRZaNTdyQ6MCep1XozwrdUEpUrdeZbKQAIq1zCmLzdlw2vHtrWjEMnhf95Gtfl703h/C+zbnvwUI=" Feb 26 02:03:40 volumio go-librespot[1643592]: time="2026-02-26T02:03:39+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:03:40 volumio go-librespot[1643592]: time="2026-02-26T02:03:39+07:00" level=debug msg="completed keyexchange" Feb 26 02:03:40 volumio go-librespot[1643592]: time="2026-02-26T02:03:39+07:00" level=debug msg="completed challenge" Feb 26 02:03:40 volumio go-librespot[1643592]: time="2026-02-26T02:03:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:03:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:03:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:03:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 26 02:03:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:44 volumio volumio-remote-updater[666]: [2026-02-26 02:03:44] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Feb 26 02:03:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:45 volumio go-librespot[1643619]: go-librespot daemon starting... Feb 26 02:03:45 volumio go-librespot[1643621]: time="2026-02-26T02:03:45+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:03:46 volumio go-librespot[1643621]: time="2026-02-26T02:03:45+07:00" level=debug msg="app state loaded" Feb 26 02:03:46 volumio go-librespot[1643621]: time="2026-02-26T02:03:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:03:48 volumio go-librespot[1643621]: time="2026-02-26T02:03:48+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:03:48 volumio go-librespot[1643621]: time="2026-02-26T02:03:48+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:03:48 volumio go-librespot[1643621]: time="2026-02-26T02:03:48+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:03:48 volumio go-librespot[1643621]: time="2026-02-26T02:03:48+07:00" level=info msg="zeroconf server listening on port 41029" Feb 26 02:03:48 volumio go-librespot[1643621]: time="2026-02-26T02:03:48+07:00" level=debug msg="obtained new client token: AADsjjKFWYnvKoQLHhkH1foj9D6x06i6B7cC4uspuIfWzzQo5tWgW+iTTv3Ib91DHAYPQju46KUDtu9IFYLyV3NWmkfn29h1XbUhSGdNsfCoJoPWhk9TCBvKaB2GDZjwiAsfGLBfkuZpH/sQ4oHjdKpjVQlaa0erO2VgepV+daeq+5ho88EY2Dz1jWN6gk25Hwa3slM2cUvXEdbGT5LxjBtRfLj3OcHx9RFdPX04PRHb3Kb26R5PgOVWZiU=" Feb 26 02:03:48 volumio go-librespot[1643621]: time="2026-02-26T02:03:48+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:03:49 volumio go-librespot[1643621]: time="2026-02-26T02:03:49+07:00" level=debug msg="completed keyexchange" Feb 26 02:03:49 volumio go-librespot[1643621]: time="2026-02-26T02:03:49+07:00" level=debug msg="completed challenge" Feb 26 02:03:49 volumio go-librespot[1643621]: time="2026-02-26T02:03:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:03:49 volumio volumio-remote-updater[666]: [2026-02-26 02:03:49] [connect] Successful connection Feb 26 02:03:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:03:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:03:53 volumio volumio[1642466]: error: [MQTT] MQTT connection error: Error: write EPIPE Feb 26 02:03:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 26 02:03:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:54 volumio volumio[1642466]: info: Initializing connection to go-librespot Websocket Feb 26 02:03:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:03:56 volumio go-librespot[1643648]: go-librespot daemon starting... Feb 26 02:03:56 volumio go-librespot[1643650]: time="2026-02-26T02:03:56+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:03:57 volumio go-librespot[1643650]: time="2026-02-26T02:03:56+07:00" level=debug msg="app state loaded" Feb 26 02:03:57 volumio go-librespot[1643650]: time="2026-02-26T02:03:57+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:03:58 volumio go-librespot[1643650]: time="2026-02-26T02:03:58+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:03:58 volumio go-librespot[1643650]: time="2026-02-26T02:03:58+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:03:58 volumio go-librespot[1643650]: time="2026-02-26T02:03:58+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:03:58 volumio go-librespot[1643650]: time="2026-02-26T02:03:58+07:00" level=info msg="zeroconf server listening on port 44839" Feb 26 02:04:00 volumio go-librespot[1643650]: time="2026-02-26T02:03:59+07:00" level=debug msg="obtained new client token: AADgHstcn0O0BgME/4p8tx1eZnNee8ZoRAY1JUFKDOcR/X/UdI44jCHJMEiESGL3vc3uQajeIvHq/LIPk83wdbIG+NqdhswxM9Xve8IP+AoWAGwymW3E9HB8tDa3wjE6C/wp1CexVe+o2hLgSA3T6uZtWKtn5pEURf1gbWQ2kk8zR3XGiQ2EmnpaWdER0CtKwRwS8gVAvqRxz0Ya4kZylqiM4z7usWK6jgNFKzgZuGD3/mimxMxoJTGlSsU=" Feb 26 02:04:00 volumio go-librespot[1643650]: time="2026-02-26T02:03:59+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:04:00 volumio go-librespot[1643650]: time="2026-02-26T02:04:00+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:00 volumio go-librespot[1643650]: time="2026-02-26T02:04:00+07:00" level=debug msg="completed challenge" Feb 26 02:04:00 volumio go-librespot[1643650]: time="2026-02-26T02:04:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:02 volumio volumio-remote-updater[666]: [2026-02-26 02:04:02] [connect] Successful connection Feb 26 02:04:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 26 02:04:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:05 volumio go-librespot[1643676]: go-librespot daemon starting... Feb 26 02:04:06 volumio go-librespot[1643677]: time="2026-02-26T02:04:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:06 volumio go-librespot[1643677]: time="2026-02-26T02:04:06+07:00" level=debug msg="app state loaded" Feb 26 02:04:06 volumio go-librespot[1643677]: time="2026-02-26T02:04:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:08 volumio go-librespot[1643677]: time="2026-02-26T02:04:08+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:04:08 volumio go-librespot[1643677]: time="2026-02-26T02:04:08+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:04:08 volumio go-librespot[1643677]: time="2026-02-26T02:04:08+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:04:09 volumio go-librespot[1643677]: time="2026-02-26T02:04:08+07:00" level=info msg="zeroconf server listening on port 35575" Feb 26 02:04:09 volumio go-librespot[1643677]: time="2026-02-26T02:04:09+07:00" level=debug msg="obtained new client token: AADFOXrBd1ck9ipDovOyN8w3TQtA7SusS78czUCqTOgc20SgZ1fdmqkud/BldAHzwR1muPtuoeCg8C1mq1stdU8dlzRV6Wj925rXzfX7mXq+7XQkwFqUBF51FCKilaubrT1/POso/4+iWQJUGtpNScug2z+f8357TVMOFFBd6QUSYqwolKE/9R8j9oLEhtwSWqzcDN6NV63P4ksn8wSG5BUDJNE1fEI5c7DJmkwv8CU5XBgfUCT/Gm+G" Feb 26 02:04:09 volumio go-librespot[1643677]: time="2026-02-26T02:04:09+07:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 26 02:04:09 volumio go-librespot[1643677]: time="2026-02-26T02:04:09+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 26 02:04:09 volumio go-librespot[1643677]: time="2026-02-26T02:04:09+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:10 volumio go-librespot[1643677]: time="2026-02-26T02:04:09+07:00" level=debug msg="completed challenge" Feb 26 02:04:10 volumio go-librespot[1643677]: time="2026-02-26T02:04:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 26 02:04:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:15 volumio go-librespot[1643706]: go-librespot daemon starting... Feb 26 02:04:15 volumio go-librespot[1643708]: time="2026-02-26T02:04:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:15 volumio go-librespot[1643708]: time="2026-02-26T02:04:15+07:00" level=debug msg="app state loaded" Feb 26 02:04:15 volumio go-librespot[1643708]: time="2026-02-26T02:04:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=info msg="zeroconf server listening on port 45549" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=debug msg="obtained new client token: AACROY/Y/nZLFaX5xh08tA3H3lX1RISy4s+9NEjoqI8ZtDfdT4R1o7ulnTDzMfBbALKFQPnTUyS2yz+JP68UPM2ucjXUD67V0zX0MqagT0OqFIvxlZXEO9yyx+/wFudB8mr7+TUBLSzQPmxpz9SoU+0lxQHhI7IekDS/OpRuWEOmPYk7T+v9frz3SsHJQ529+BvFq9XKo0tQGF21ZaQKDGkkpt4irZeyYH2NsFMkpUHuolzgSv13X9Kh9Rk=" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=debug msg="completed challenge" Feb 26 02:04:16 volumio go-librespot[1643708]: time="2026-02-26T02:04:16+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:17 volumio volumio[1642466]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 26 02:04:17 volumio volumio-remote-updater[666]: [2026-02-26 02:04:17] [connect] Successful connection Feb 26 02:04:18 volumio volumio[1642466]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 26 02:04:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 26 02:04:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:20 volumio dhcpcd[850]: eth0: failed to renew DHCP, rebinding Feb 26 02:04:20 volumio dhcpcd[850]: eth0: leased 10.10.9.65 for 1800 seconds Feb 26 02:04:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:21 volumio go-librespot[1643719]: go-librespot daemon starting... Feb 26 02:04:21 volumio go-librespot[1643739]: time="2026-02-26T02:04:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:21 volumio go-librespot[1643739]: time="2026-02-26T02:04:21+07:00" level=debug msg="app state loaded" Feb 26 02:04:21 volumio go-librespot[1643739]: time="2026-02-26T02:04:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:21 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 26 02:04:21 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 26 02:04:21 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 26 02:04:21 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 26 02:04:21 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 26 02:04:22 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 26 02:04:22 volumio welcome[1643755]: Resolved ip:[1] 10.10.9.65 Feb 26 02:04:23 volumio go-librespot[1643739]: time="2026-02-26T02:04:23+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:04:23 volumio go-librespot[1643739]: time="2026-02-26T02:04:23+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:04:23 volumio go-librespot[1643739]: time="2026-02-26T02:04:23+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:04:23 volumio go-librespot[1643739]: time="2026-02-26T02:04:23+07:00" level=info msg="zeroconf server listening on port 40639" Feb 26 02:04:23 volumio go-librespot[1643739]: time="2026-02-26T02:04:23+07:00" level=debug msg="obtained new client token: AADBOKdTOrwgj5kN314EaKIG0xKfiSQoVF1DBcvlysaZsaIyjs2mYVSwIHSwMC+JnrcnmeLEFXI5xK5zCOsIX9jMRLyLWkBL2ZeKdUZ7dej3/a9YjasGFMp/oB9dHMz0s0sDy1J2bAwTlItHQ+fuardLVxGzDGkjiLg4nb5gQuJIMYmUq0JvIsSrmA4/yAELbvPGo2va4fbpE0DXsJU+qsNNs44ED/feWjOohBBhs5TixaMdfWUzBJ+Kznw=" Feb 26 02:04:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 26 02:04:24 volumio go-librespot[1643739]: time="2026-02-26T02:04:23+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:04:24 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 26 02:04:24 volumio go-librespot[1643739]: time="2026-02-26T02:04:24+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:24 volumio go-librespot[1643739]: time="2026-02-26T02:04:24+07:00" level=debug msg="completed challenge" Feb 26 02:04:24 volumio go-librespot[1643739]: time="2026-02-26T02:04:24+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:25 volumio volumio[1642466]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 26 02:04:28 volumio volumio[1642466]: info: Error connecting to go-librespot Websocket: Error: socket hang up Feb 26 02:04:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 26 02:04:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:28 volumio volumio[1642466]: info: Received Get System Info Feb 26 02:04:28 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 02:04:28 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 02:04:28 volumio volumio[1642466]: info: Discovery: Getting this device information Feb 26 02:04:28 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:04:28 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:04:28 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 02:04:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:28 volumio go-librespot[1643778]: go-librespot daemon starting... Feb 26 02:04:28 volumio volumio[1642466]: info: camilladsp respawn in 200 ms (attempt 2/10) Feb 26 02:04:28 volumio go-librespot[1643780]: time="2026-02-26T02:04:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:28 volumio go-librespot[1643780]: time="2026-02-26T02:04:28+07:00" level=debug msg="app state loaded" Feb 26 02:04:28 volumio go-librespot[1643780]: time="2026-02-26T02:04:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:28 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:04:28 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:04:29 volumio volumio[1642466]: info: [MQTT] Connected to MQTT broker Feb 26 02:04:29 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:04:29 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:04:29 volumio go-librespot[1643780]: time="2026-02-26T02:04:29+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:04:29 volumio go-librespot[1643780]: time="2026-02-26T02:04:29+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:04:29 volumio go-librespot[1643780]: time="2026-02-26T02:04:29+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:04:29 volumio go-librespot[1643780]: time="2026-02-26T02:04:29+07:00" level=info msg="zeroconf server listening on port 46357" Feb 26 02:04:29 volumio go-librespot[1643780]: time="2026-02-26T02:04:29+07:00" level=debug msg="obtained new client token: AAAH8XwKGaNXJF1Dlu4yH/9624gX6H9zQjuV0c7N6FnJOZVOtbMe/Z4bkobX6XUxn6phzkmd45h1acqijH7F+4aFSmPh0zYJ+6Sx6FCkmzeFUn1wB7Wb33PC+cbMKVVIn5H5dORFPayIIirYXKkowgGNbGV+sXCwFa8FBDjABtgEbeIhApORpyTC7+u1mCjMzQwxS8vKGQPA83+xyZQmLCCcgO6Jk8XsARWC5Kv32SX9racapfxfY7iJCBc=" Feb 26 02:04:29 volumio go-librespot[1643780]: time="2026-02-26T02:04:29+07:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 26 02:04:29 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 02:04:29 volumio volumio[1642466]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 02:04:30 volumio go-librespot[1643780]: time="2026-02-26T02:04:30+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 26 02:04:30 volumio go-librespot[1643780]: time="2026-02-26T02:04:30+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:30 volumio go-librespot[1643780]: time="2026-02-26T02:04:30+07:00" level=debug msg="completed challenge" Feb 26 02:04:30 volumio go-librespot[1643780]: time="2026-02-26T02:04:30+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:32 volumio volumio[1642466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 26 02:04:32 volumio volumio[1642466]: info: Initializing connection to go-librespot Websocket Feb 26 02:04:32 volumio volumio[1642466]: info: Listing playlists Feb 26 02:04:32 volumio volumio[1642466]: info: Listing playlists Feb 26 02:04:32 volumio volumio[1642466]: info: camilladsp respawn in 400 ms (attempt 3/10) Feb 26 02:04:32 volumio volumio[1642466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 26 02:04:32 volumio volumio[1642466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:04:32 volumio volumio-remote-updater[666]: [2026-02-26 02:04:32] [connect] Successful connection Feb 26 02:04:32 volumio volumio[1642466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 26 02:04:33 volumio volumio[1642466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 26 02:04:33 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:04:33 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:04:33 volumio volumio[1642466]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 6 Feb 26 02:04:33 volumio volumio-remote-updater[666]: [2026-02-26 02:04:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1772046272 101 Feb 26 02:04:33 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:04:33 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:04:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 26 02:04:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:34 volumio go-librespot[1643799]: go-librespot daemon starting... Feb 26 02:04:34 volumio go-librespot[1643800]: time="2026-02-26T02:04:34+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:35 volumio go-librespot[1643800]: time="2026-02-26T02:04:34+07:00" level=debug msg="app state loaded" Feb 26 02:04:35 volumio go-librespot[1643800]: time="2026-02-26T02:04:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:35 volumio go-librespot[1643800]: time="2026-02-26T02:04:35+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:04:35 volumio go-librespot[1643800]: time="2026-02-26T02:04:35+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:04:35 volumio go-librespot[1643800]: time="2026-02-26T02:04:35+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:04:35 volumio go-librespot[1643800]: time="2026-02-26T02:04:35+07:00" level=info msg="zeroconf server listening on port 34155" Feb 26 02:04:35 volumio go-librespot[1643800]: time="2026-02-26T02:04:35+07:00" level=debug msg="obtained new client token: AAB/fnBDgrxfrA8XzIV2xW2ccQQDX7I9aS7KrpyB+LI0C+0f7paWToYSF2fReTUFyxXuvId9zPpPZm2QnOok3s70wYlJ/GVi+RnuLb2ZXwpfUCkR3r8D8EKacwn65dvvCd8C3VjcABzFMgjlQgLirOdRAUDV4cS4YMnNCJd4bO/gO3JJsORsVYCCWMDkLlRZPAmnzq/jFhxTBkJKrPHEp6ofDe3UCTVwRK2JchCnBMduXB0gc2gER/2GW0Y=" Feb 26 02:04:36 volumio go-librespot[1643800]: time="2026-02-26T02:04:36+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:04:36 volumio go-librespot[1643800]: time="2026-02-26T02:04:36+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:36 volumio go-librespot[1643800]: time="2026-02-26T02:04:36+07:00" level=debug msg="completed challenge" Feb 26 02:04:36 volumio go-librespot[1643800]: time="2026-02-26T02:04:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:39 volumio volumio[1642466]: info: Initializing connection to go-librespot Websocket Feb 26 02:04:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 26 02:04:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:40 volumio go-librespot[1643831]: go-librespot daemon starting... Feb 26 02:04:40 volumio go-librespot[1643832]: time="2026-02-26T02:04:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:40 volumio go-librespot[1643832]: time="2026-02-26T02:04:40+07:00" level=debug msg="app state loaded" Feb 26 02:04:40 volumio go-librespot[1643832]: time="2026-02-26T02:04:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:41 volumio go-librespot[1643832]: time="2026-02-26T02:04:41+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:04:41 volumio go-librespot[1643832]: time="2026-02-26T02:04:41+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:04:41 volumio go-librespot[1643832]: time="2026-02-26T02:04:41+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:04:41 volumio go-librespot[1643832]: time="2026-02-26T02:04:41+07:00" level=info msg="zeroconf server listening on port 44767" Feb 26 02:04:41 volumio go-librespot[1643832]: time="2026-02-26T02:04:41+07:00" level=debug msg="obtained new client token: AADFJXFCmsH4zHnt8x4O4LHqvrljLQpJMKYJfB+j8nYRfWf7R+RJNT1X9YJwDI2Yd79UHDT0/A/k+hbEoybHPo9StgWJVhL2PE3zYFOb1FC5PalSWDeE8ba7SXx+Zgi9SQCQfm0f+Tf2pKORbT4LL3QM2pXaGsKI6UFZNlH4DkujijrQDUUw/o0jXDSneyHqyDuGFxwni4hAHMFTLDnVM19yUtZ1bpEvvaAI4fJz1qs7e/GTdv9a6JfaHVI=" Feb 26 02:04:42 volumio go-librespot[1643832]: time="2026-02-26T02:04:42+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:04:42 volumio volumio[1642466]: info: camilladsp respawn in 800 ms (attempt 4/10) Feb 26 02:04:42 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:04:42 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:04:42 volumio go-librespot[1643832]: time="2026-02-26T02:04:42+07:00" level=debug msg="new websocket client" Feb 26 02:04:42 volumio go-librespot[1643832]: time="2026-02-26T02:04:42+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:42 volumio go-librespot[1643832]: time="2026-02-26T02:04:42+07:00" level=debug msg="completed challenge" Feb 26 02:04:42 volumio go-librespot[1643832]: time="2026-02-26T02:04:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:43 volumio volumio[1642466]: info: Connection to go-librespot Websocket established Feb 26 02:04:43 volumio volumio[1642466]: info: Connection to go-librespot Websocket closed Feb 26 02:04:43 volumio volumio[1642466]: info: CoreCommandRouter::volumioGetState Feb 26 02:04:43 volumio volumio[1642466]: info: CorePlayQueue::getTrack 0 Feb 26 02:04:44 volumio volumio[1642466]: info: camilladsp respawn in 1600 ms (attempt 5/10) Feb 26 02:04:44 volumio volumio[1642466]: info: Listing playlists Feb 26 02:04:44 volumio volumio[1642466]: info: Listing playlists Feb 26 02:04:45 volumio volumio[1642466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 26 02:04:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 26 02:04:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:46 volumio go-librespot[1643867]: go-librespot daemon starting... Feb 26 02:04:46 volumio go-librespot[1643875]: time="2026-02-26T02:04:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:46 volumio go-librespot[1643875]: time="2026-02-26T02:04:46+07:00" level=debug msg="app state loaded" Feb 26 02:04:46 volumio go-librespot[1643875]: time="2026-02-26T02:04:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:46 volumio go-librespot[1643875]: time="2026-02-26T02:04:46+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:04:46 volumio go-librespot[1643875]: time="2026-02-26T02:04:46+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:04:46 volumio go-librespot[1643875]: time="2026-02-26T02:04:46+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:04:46 volumio go-librespot[1643875]: time="2026-02-26T02:04:46+07:00" level=info msg="zeroconf server listening on port 39331" Feb 26 02:04:47 volumio go-librespot[1643875]: time="2026-02-26T02:04:47+07:00" level=debug msg="obtained new client token: AAA8xznNJ9B17u1yJjqM2NCEEHMd3XKjLj2IRzxUh9MkElApThmsogkNGB91BtgCpIcGIdmrIp66CxY+2rKUj8wvkNSY7XebPoFYDZg5/pJNyIKmMMqYOVktrR7WP3Ywi5xIgRP0S0srqXFW0NJxAzdWRsB4njIeKlDkZ3Lzu1mKraCCalK5L17jAGyfVf1ViFPiFho/ckPVXFIcKBAsLehFlO8Wvf3XHV2GF9JIj7b5rcqp+6eDLa63" Feb 26 02:04:47 volumio go-librespot[1643875]: time="2026-02-26T02:04:47+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:04:47 volumio go-librespot[1643875]: time="2026-02-26T02:04:47+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:47 volumio go-librespot[1643875]: time="2026-02-26T02:04:47+07:00" level=debug msg="completed challenge" Feb 26 02:04:47 volumio volumio[1642466]: info: Getting Spotify volume Feb 26 02:04:47 volumio go-librespot[1643875]: time="2026-02-26T02:04:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:48 volumio volumio[1642466]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:04:48 volumio volumio[1642466]: Error: connect ECONNRESET 127.0.0.1:9879 Feb 26 02:04:48 volumio volumio[1642466]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 26 02:04:48 volumio volumio[1642466]: errno: -104, Feb 26 02:04:48 volumio volumio[1642466]: code: 'ECONNRESET', Feb 26 02:04:48 volumio volumio[1642466]: syscall: 'connect', Feb 26 02:04:48 volumio volumio[1642466]: address: '127.0.0.1', Feb 26 02:04:48 volumio volumio[1642466]: port: 9879, Feb 26 02:04:48 volumio volumio[1642466]: response: undefined Feb 26 02:04:48 volumio volumio[1642466]: } Feb 26 02:04:48 volumio volumio[1642466]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:04:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 26 02:04:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:04:51 volumio go-librespot[1643896]: go-librespot daemon starting... Feb 26 02:04:51 volumio go-librespot[1643898]: time="2026-02-26T02:04:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:51+07:00" level=debug msg="app state loaded" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:52+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:52+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:52+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:52+07:00" level=info msg="zeroconf server listening on port 34803" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:52+07:00" level=debug msg="obtained new client token: AAAIuBrtgtMDTa4eEz83rvHmglXtx0YNz9f5VLbW7UIkqZxTUcNbXduybcB5A2QucTldHCn0YuYhexQ8XpJ5Vsc7jSxU9ywjWxRrmyekjSKFrm8fzRrKSp/x9bXzMA2pYZjU7Q8Xs7V99SkRNOjtxa1hizxTDV5uFUitE9rcZkkMXnCGWxJOryS8ftePl2lLCLpqkdNhZhQZpstHwTuoBRT6/jX6D3wF8sh3oIK7HEVFcPx4PSIGtAgxYxU=" Feb 26 02:04:52 volumio go-librespot[1643898]: time="2026-02-26T02:04:52+07:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 26 02:04:53 volumio go-librespot[1643898]: time="2026-02-26T02:04:53+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 26 02:04:53 volumio go-librespot[1643898]: time="2026-02-26T02:04:53+07:00" level=debug msg="completed keyexchange" Feb 26 02:04:53 volumio go-librespot[1643898]: time="2026-02-26T02:04:53+07:00" level=debug msg="completed challenge" Feb 26 02:04:53 volumio go-librespot[1643898]: time="2026-02-26T02:04:53+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:04:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:04:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:04:54 volumio sudo[1643907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 02:03' Feb 26 02:04:54 volumio sudo[1643907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"