Jan 13 21:30:00 marty volumio[1207]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 21:30:01 marty volumio[1207]: info: Shairport-Sync Started Jan 13 21:30:01 marty volumio[1207]: Error adding Membership: Error: addMembership EINVAL Jan 13 21:30:01 marty volumio[1207]: info: go-librespot daemon successfully initialized Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 13 21:30:01 marty volumio[1207]: info: Adding plugin bluetooth to MyMusic Plugins Jan 13 21:30:01 marty volumio[1207]: info: Adding plugin multiroom to MyMusic Plugins Jan 13 21:30:01 marty volumio[1207]: info: Adding plugin metavolumio to MyMusic Plugins Jan 13 21:30:01 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 13 21:30:01 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:01 marty volumio[1207]: info: Adding plugin cd_controller to MyMusic Plugins Jan 13 21:30:01 marty volumio[1207]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 13 21:30:01 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:01 marty go-librespot[1600]: go-librespot daemon starting... Jan 13 21:30:01 marty go-librespot[1605]: time="2026-01-13T21:30:01+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:01 marty go-librespot[1605]: time="2026-01-13T21:30:01+01:00" level=debug msg="app state loaded" Jan 13 21:30:01 marty go-librespot[1605]: time="2026-01-13T21:30:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:01 marty volumio[1207]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 13 21:30:01 marty volumio[1207]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 13 21:30:01 marty volumio[1207]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 13 21:30:02 marty go-librespot[1605]: time="2026-01-13T21:30:02+01: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]" Jan 13 21:30:02 marty go-librespot[1605]: time="2026-01-13T21:30:02+01: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]" Jan 13 21:30:02 marty go-librespot[1605]: time="2026-01-13T21:30:02+01: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]" Jan 13 21:30:02 marty go-librespot[1605]: time="2026-01-13T21:30:02+01:00" level=info msg="zeroconf server listening on port 37599" Jan 13 21:30:02 marty go-librespot[1605]: time="2026-01-13T21:30:02+01:00" level=debug msg="obtained new client token: AAD35qeaVDh0pM/ukbi5UjkOV+rGqZl4gt+Tm407l772in3KZat7KV64BOQaSoAEzEfJdaaHpoNxGCWvw2aNfg+aQZEWhm8N083WRBrtNYpNsQSoAxZmWTcnVhhBfUBcxEA4TzN6h7NAv/rvhAPfk7c7I2nTMp8YsWFw2hMEsoAOuUUm2/J9MtgVxIuOehFvH1M0bzd1fn7trj/uWyPZMwMiZHeRBsuTj+MqBCZ2gE1LS0S8jfFJJhk=" Jan 13 21:30:02 marty go-librespot[1605]: time="2026-01-13T21:30:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:02 marty go-librespot[1605]: time="2026-01-13T21:30:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:02 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:02 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:03 marty volumio-remote-updater[707]: [2026-01-13 21:30:03] [connect] Successful connection Jan 13 21:30:05 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 13 21:30:05 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:05 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:05 marty go-librespot[1623]: go-librespot daemon starting... Jan 13 21:30:05 marty go-librespot[1624]: time="2026-01-13T21:30:05+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:05 marty go-librespot[1624]: time="2026-01-13T21:30:05+01:00" level=debug msg="app state loaded" Jan 13 21:30:05 marty go-librespot[1624]: time="2026-01-13T21:30:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:06 marty go-librespot[1624]: time="2026-01-13T21:30:06+01: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]" Jan 13 21:30:06 marty go-librespot[1624]: time="2026-01-13T21:30:06+01: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]" Jan 13 21:30:06 marty go-librespot[1624]: time="2026-01-13T21:30:06+01: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]" Jan 13 21:30:06 marty go-librespot[1624]: time="2026-01-13T21:30:06+01:00" level=info msg="zeroconf server listening on port 45601" Jan 13 21:30:06 marty go-librespot[1624]: time="2026-01-13T21:30:06+01:00" level=debug msg="obtained new client token: AABiM2s1kXIQUDHRjs7hhUfyvqkFbkbQW5+Zl+rVRsAUDLKHnrSL3cSF31jB4WH5KUI6UYI8tnKEVCshxxckQr74ytSJwlZsTpWJDX96/ou2ClaUTmLDTm33GJACk09Rt7Bg3fFkdvdXWf+rUOezelaAzU4gMCZ8SEY5UxUCDkKYY44/H13zIosyu2AC9j1YgzOzn7p+cutk85wETk7LBSuvLadQyIYJnCsPcGFtkkT06c013Lc9ZFs=" Jan 13 21:30:07 marty go-librespot[1624]: time="2026-01-13T21:30:07+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:07 marty go-librespot[1624]: time="2026-01-13T21:30:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:07 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:07 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:10 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 13 21:30:10 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:10 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:10 marty go-librespot[1631]: go-librespot daemon starting... Jan 13 21:30:10 marty go-librespot[1632]: time="2026-01-13T21:30:10+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:10 marty go-librespot[1632]: time="2026-01-13T21:30:10+01:00" level=debug msg="app state loaded" Jan 13 21:30:10 marty go-librespot[1632]: time="2026-01-13T21:30:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:11 marty go-librespot[1632]: time="2026-01-13T21:30:11+01: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]" Jan 13 21:30:11 marty go-librespot[1632]: time="2026-01-13T21:30:11+01: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]" Jan 13 21:30:11 marty go-librespot[1632]: time="2026-01-13T21:30:11+01: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]" Jan 13 21:30:11 marty go-librespot[1632]: time="2026-01-13T21:30:11+01:00" level=info msg="zeroconf server listening on port 36153" Jan 13 21:30:11 marty go-librespot[1632]: time="2026-01-13T21:30:11+01:00" level=debug msg="obtained new client token: AAAfxFt7bjsTybmhXtXVcZ/UuVYFdImXkrJFHMFMOszUBsFV0Wtzov9lmYLrteQo2RLm/u7HGBciSvLb9Ebd5R7Ca3hZQKh9qFlVhBQJHaO8dHm84xoMnoEgAoect9fU6HxFb9QqyVsA2TKFV/01E+X4nzCgyHy5JOGe9Pkx6cyKkR7xT3lkU18CGXyd/TkIq5CL95qUPlTkSn7pzs27PpKtf4Yt3QRG7bfC7p6RvS5E89Y8rGnxetg=" Jan 13 21:30:11 marty go-librespot[1632]: time="2026-01-13T21:30:11+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:13 marty volumio[1207]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 13 21:30:13 marty volumio[1207]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 13 21:30:13 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:13 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:13 marty volumio[1207]: info: Starting MyVolumio Remote Streaming Endpoints Jan 13 21:30:13 marty volumio[1207]: info: MyVolumio login type: Token Jan 13 21:30:14 marty go-librespot[1632]: time="2026-01-13T21:30:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.2.122:45952->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:30:14 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:14 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:14 marty volumio[1207]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 13 21:30:14 marty volumio[1207]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 13 21:30:17 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jan 13 21:30:17 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:17 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:17 marty go-librespot[1656]: go-librespot daemon starting... Jan 13 21:30:17 marty go-librespot[1657]: time="2026-01-13T21:30:17+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:17 marty go-librespot[1657]: time="2026-01-13T21:30:17+01:00" level=debug msg="app state loaded" Jan 13 21:30:17 marty go-librespot[1657]: time="2026-01-13T21:30:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:17 marty go-librespot[1657]: time="2026-01-13T21:30:17+01: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]" Jan 13 21:30:17 marty go-librespot[1657]: time="2026-01-13T21:30:17+01: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]" Jan 13 21:30:17 marty go-librespot[1657]: time="2026-01-13T21:30:17+01: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]" Jan 13 21:30:17 marty go-librespot[1657]: time="2026-01-13T21:30:17+01:00" level=info msg="zeroconf server listening on port 37877" Jan 13 21:30:18 marty go-librespot[1657]: time="2026-01-13T21:30:18+01:00" level=debug msg="obtained new client token: AAD8dTKI5HoRlWpRmu5/UAK8TK5/NK/tYeKvrshTqLQC5gU7d1tQ4vcSqlwnFxbEYSBB4T4k9OaTUW65Bxpqun9dymaBn+QiytYNVgGKNPkbLfz80Jm5vhCpINxklMI61IxBddTCuJEWHC/oxyuLTUrXmStYjQIsuQ+SRWWdTC+LBZO662MuCBYz6eWWLJyY3ABAj7NqJFKOKf9/F/82es97XGJYn+0d8luDmD/WGTf8oRXXm9OA" Jan 13 21:30:18 marty volumio-remote-updater[707]: [2026-01-13 21:30:18] [connect] Successful connection Jan 13 21:30:18 marty go-librespot[1657]: time="2026-01-13T21:30:18+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:18 marty go-librespot[1657]: time="2026-01-13T21:30:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:18 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:18 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:21 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jan 13 21:30:21 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:21 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:21 marty go-librespot[1664]: go-librespot daemon starting... Jan 13 21:30:21 marty go-librespot[1665]: time="2026-01-13T21:30:21+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:21 marty go-librespot[1665]: time="2026-01-13T21:30:21+01:00" level=debug msg="app state loaded" Jan 13 21:30:21 marty go-librespot[1665]: time="2026-01-13T21:30:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:21 marty go-librespot[1665]: time="2026-01-13T21:30:21+01: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]" Jan 13 21:30:21 marty go-librespot[1665]: time="2026-01-13T21:30:21+01: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]" Jan 13 21:30:21 marty go-librespot[1665]: time="2026-01-13T21:30:21+01: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]" Jan 13 21:30:21 marty go-librespot[1665]: time="2026-01-13T21:30:21+01:00" level=info msg="zeroconf server listening on port 34357" Jan 13 21:30:22 marty go-librespot[1665]: time="2026-01-13T21:30:22+01:00" level=debug msg="obtained new client token: AAAZXBcChJh5MXhCWGhs2w1kPACt2VnFxSfKzKycvCKJvJrE5CAmUkCvDCOIiNt68yVkIMMtGW8m+DfEoyMDumNHZxaTSlPDcgxVIYbI5NiLAmrHwSoI2IYv38rhyUJqGyNoIAeXcSp+RV7d+Zx0bJIzyBxXwcfjenOTJg0FKkVLrgwXvtFe++ARCcCHY7i89DlGy6ECkZnfD3AZuejJp/la7Qph0Rhx76XJQ6hf/f8v2m8jIeJe" Jan 13 21:30:22 marty go-librespot[1665]: time="2026-01-13T21:30:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:22 marty go-librespot[1665]: time="2026-01-13T21:30:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:22 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:22 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:25 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jan 13 21:30:25 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:25 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:25 marty go-librespot[1686]: go-librespot daemon starting... Jan 13 21:30:25 marty go-librespot[1687]: time="2026-01-13T21:30:25+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:25 marty go-librespot[1687]: time="2026-01-13T21:30:25+01:00" level=debug msg="app state loaded" Jan 13 21:30:25 marty go-librespot[1687]: time="2026-01-13T21:30:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:25 marty go-librespot[1687]: time="2026-01-13T21:30:25+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:30:25 marty go-librespot[1687]: time="2026-01-13T21:30:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:30:25 marty go-librespot[1687]: time="2026-01-13T21:30:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:30:25 marty go-librespot[1687]: time="2026-01-13T21:30:25+01:00" level=info msg="zeroconf server listening on port 42609" Jan 13 21:30:26 marty go-librespot[1687]: time="2026-01-13T21:30:26+01:00" level=debug msg="obtained new client token: AABcOCVMj6AIK9W0JwYTMRo2nCBulw0w+0EpS2R0/kwwD5EL9x+zdCv9zAgGcJH+NYYo8aTHUngwyWW+xvi/kY+3+ccGjXaDg5VlTeoNh+QMDpaev1zGwfVJ2UTaQ0SH9oeBc2T3+6e568oRG8fXj+mkZAg9cfJceX5GjDXZG7+j387Yl3wml0RJNNt44omnESbLripT9DKjAM2xc0vNnA/I44tD/f6zFWjEC8j9Nz7U0HQnsS2a" Jan 13 21:30:26 marty go-librespot[1687]: time="2026-01-13T21:30:26+01: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" Jan 13 21:30:26 marty go-librespot[1687]: time="2026-01-13T21:30:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 21:30:26 marty go-librespot[1687]: time="2026-01-13T21:30:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:26 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:26 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:26 marty volumio[1207]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 13 21:30:26 marty volumio[1207]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 13 21:30:26 marty volumio[1207]: info: Streaming services startup Jan 13 21:30:26 marty volumio[1207]: info: Starting Streaming Daemon Jan 13 21:30:26 marty volumio[1207]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 13 21:30:26 marty sudo[1695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 21:30:26 marty sudo[1695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 21:30:27 marty sudo[1695]: pam_unix(sudo:session): session closed for user root Jan 13 21:30:27 marty volumio[1207]: info: Shairport-Sync Started Jan 13 21:30:27 marty volumio[1207]: info: Shairport-Sync Started Jan 13 21:30:27 marty volumio[1207]: info: Initializing connection to go-librespot Websocket Jan 13 21:30:27 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 21:30:27 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:27 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 21:30:28 marty volumio[1207]: error: Cannot start Volumio Streaming Daemon Jan 13 21:30:28 marty volumio[1207]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 21:30:28 marty volumio[1207]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 21:30:28 marty volumio[1207]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:30:28 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 21:30:28 marty volumio[1207]: error: MyVolumio Custom Token format not valid, refreshing it Jan 13 21:30:29 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jan 13 21:30:29 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:29 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:29 marty go-librespot[1704]: go-librespot daemon starting... Jan 13 21:30:29 marty go-librespot[1705]: time="2026-01-13T21:30:29+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:29 marty go-librespot[1705]: time="2026-01-13T21:30:29+01:00" level=debug msg="app state loaded" Jan 13 21:30:29 marty go-librespot[1705]: time="2026-01-13T21:30:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:29 marty volumio[1207]: info: CoreCommandRouter::volumioGetState Jan 13 21:30:29 marty volumio[1207]: info: CorePlayQueue::getTrack 0 Jan 13 21:30:30 marty go-librespot[1705]: time="2026-01-13T21:30:30+01: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]" Jan 13 21:30:30 marty go-librespot[1705]: time="2026-01-13T21:30:30+01: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]" Jan 13 21:30:30 marty go-librespot[1705]: time="2026-01-13T21:30:30+01: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]" Jan 13 21:30:30 marty go-librespot[1705]: time="2026-01-13T21:30:30+01:00" level=info msg="zeroconf server listening on port 38367" Jan 13 21:30:30 marty go-librespot[1705]: time="2026-01-13T21:30:30+01:00" level=debug msg="obtained new client token: AAByNAew+NNgZOKcTD4XhDZJwlAdEVUeKHikehfrbyenMiOjP+if7eORD2Yrxy8cd22HDmazJETPThVVDQocK+d6pESDoPZMkfoKRlS+MFe93lVVEOoVyYHAfetHT7/WBZtbysF1k8wVYC1125cN3M0YdcZRFOE9T41jPBtkd+rWnTHBckgF/iTUv00TOSsvfMJAE6b0zT2C0sE5NxQyXa13cKtSfi8hXXTf6ACFp/F8uWh8fknR" Jan 13 21:30:30 marty go-librespot[1705]: time="2026-01-13T21:30:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:30 marty go-librespot[1705]: time="2026-01-13T21:30:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:30 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:30 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:30 marty volumio[1207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 21:30:31 marty volumio[1207]: info: Initializing connection to go-librespot Websocket Jan 13 21:30:32 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 13 21:30:32 marty volumio[1207]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:30:33 marty volumio-remote-updater[707]: [2026-01-13 21:30:33] [connect] Successful connection Jan 13 21:30:33 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 13 21:30:33 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:33 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:33 marty go-librespot[1726]: go-librespot daemon starting... Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::volumioGetBrowseSources Jan 13 21:30:33 marty go-librespot[1727]: time="2026-01-13T21:30:33+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:33 marty go-librespot[1727]: time="2026-01-13T21:30:33+01:00" level=debug msg="app state loaded" Jan 13 21:30:33 marty go-librespot[1727]: time="2026-01-13T21:30:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:33 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 21:30:34 marty volumio-remote-updater[707]: [2026-01-13 21:30:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768336233 101 Jan 13 21:30:34 marty go-librespot[1727]: time="2026-01-13T21:30:34+01: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]" Jan 13 21:30:34 marty go-librespot[1727]: time="2026-01-13T21:30:34+01: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]" Jan 13 21:30:34 marty go-librespot[1727]: time="2026-01-13T21:30:34+01: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]" Jan 13 21:30:34 marty go-librespot[1727]: time="2026-01-13T21:30:34+01:00" level=info msg="zeroconf server listening on port 35843" Jan 13 21:30:34 marty volumio[1207]: 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: 3 Jan 13 21:30:34 marty go-librespot[1727]: time="2026-01-13T21:30:34+01:00" level=debug msg="obtained new client token: AAD32Brf13qPF1C9kLay/LpX8h101Ld+T0gy1eEP44Tbb5XtQEaKD/Dkx6DDRuxvAwqoYN09mBrYskYqH51I050UeG8TFe8SJUBLqMssp4+lO0qJQN5QA98mGtRi/Me1XdiUK2USyyCz4rdLi0RD4u1CtmaWYNHtR6PIqiilhiBehm2Soxu7czbsQhZ5zanoXpY8c8MsssKAyV+iVnIq9oxMeDZoq6tw5pIsdmLuP1AfCM/f++Bfd3U=" Jan 13 21:30:34 marty go-librespot[1727]: time="2026-01-13T21:30:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:34 marty go-librespot[1727]: time="2026-01-13T21:30:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:34 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:34 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:34 marty volumio[1207]: info: MyVolumio login type: Token Jan 13 21:30:34 marty volumio[1207]: info: CoreCommandRouter::volumioGetState Jan 13 21:30:34 marty volumio[1207]: info: CorePlayQueue::getTrack 0 Jan 13 21:30:35 marty volumio[1207]: info: Initializing connection to go-librespot Websocket Jan 13 21:30:35 marty volumio[1207]: SPOTIFY: User informations: {"country":"NL","display_name":"martinvandiemen-nl","email":"martinvandiemen@telfort.nl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/martinvandiemen-nl"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/martinvandiemen-nl","id":"martinvandiemen-nl","images":[],"product":"premium","type":"user","uri":"spotify:user:martinvandiemen-nl"} Jan 13 21:30:35 marty volumio[1207]: info: Spotify Successfully logged in Jan 13 21:30:35 marty volumio[1207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 21:30:35 marty volumio[1207]: info: [1768336235552] CoreMusicLibrary::Adding element Spotify Jan 13 21:30:35 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 21:30:35 marty volumio[1207]: Cannot find translation for source Spotify Jan 13 21:30:35 marty volumio[1207]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:30:37 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 13 21:30:37 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:37 marty volumio[1207]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 13 21:30:37 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:37 marty go-librespot[1738]: go-librespot daemon starting... Jan 13 21:30:37 marty go-librespot[1739]: time="2026-01-13T21:30:37+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:37 marty go-librespot[1739]: time="2026-01-13T21:30:37+01:00" level=debug msg="app state loaded" Jan 13 21:30:37 marty go-librespot[1739]: time="2026-01-13T21:30:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:38 marty go-librespot[1739]: time="2026-01-13T21:30:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:30:38 marty go-librespot[1739]: time="2026-01-13T21:30:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:30:38 marty go-librespot[1739]: time="2026-01-13T21:30:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:30:38 marty go-librespot[1739]: time="2026-01-13T21:30:38+01:00" level=info msg="zeroconf server listening on port 41703" Jan 13 21:30:38 marty go-librespot[1739]: time="2026-01-13T21:30:38+01:00" level=debug msg="obtained new client token: AACp7kgUJJpRW0W8Oyh+iTMEH54fhL22hxVcque9jTiNav88a8cicg+R1a6RLMbrhuk4wtJuH5uaiKaeogom2rrFVb44zoWPaZyjycpg6SIigKA5o/Bq1TIQ1o4Y4qdrz5rOu1copbRp/aoj09+yR8Cgp/O++flr2PEviOuLEanAnF0AuOonGP9iQ0qlOoBlRcKy9lo8c8JFjWaRPcImrTHuwRw3V0XQQXB0CYlTfrPH99aduhcNATM=" Jan 13 21:30:38 marty go-librespot[1739]: time="2026-01-13T21:30:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:38 marty go-librespot[1739]: time="2026-01-13T21:30:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:38 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:38 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:38 marty volumio[1207]: info: MyVolumio token set successfully Jan 13 21:30:38 marty volumio[1207]: info: MYVOLUMIO: Adding device Jan 13 21:30:38 marty volumio[1207]: info: MYVOLUMIO: Evaluating Server Jan 13 21:30:40 marty volumio[1207]: info: Initializing connection to go-librespot Websocket Jan 13 21:30:40 marty volumio[1207]: info: MyVolumio status changed Jan 13 21:30:40 marty volumio[1207]: info: Streaming services startup Jan 13 21:30:40 marty volumio[1207]: info: Starting Streaming Daemon Jan 13 21:30:40 marty volumio[1207]: info: Removing browser output: myVolumio user plan is not superstar Jan 13 21:30:40 marty volumio[1207]: info: Removing audio output: Jan 13 21:30:40 marty volumio[1207]: info: Stoppping Tunnel 1 Jan 13 21:30:40 marty sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 21:30:40 marty sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 21:30:40 marty sudo[1769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 13 21:30:40 marty sudo[1769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 21:30:40 marty sudo[1767]: pam_unix(sudo:session): session closed for user root Jan 13 21:30:40 marty volumio[1207]: info: Setting Geolocation for MyVolumio to eu2 Jan 13 21:30:40 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:40 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:40 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 21:30:40 marty volumio[1207]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:30:41 marty sudo[1769]: pam_unix(sudo:session): session closed for user root Jan 13 21:30:41 marty volumio[1207]: info: Remote SSH Stopped Jan 13 21:30:41 marty volumio[1207]: error: Cannot start Volumio Streaming Daemon Jan 13 21:30:41 marty volumio[1207]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 21:30:41 marty volumio[1207]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 21:30:41 marty volumio[1207]: info: Successfully Added MyVolumio device Jan 13 21:30:41 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jan 13 21:30:41 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:41 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:41 marty go-librespot[1772]: go-librespot daemon starting... Jan 13 21:30:41 marty go-librespot[1773]: time="2026-01-13T21:30:41+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:41 marty go-librespot[1773]: time="2026-01-13T21:30:41+01:00" level=debug msg="app state loaded" Jan 13 21:30:41 marty go-librespot[1773]: time="2026-01-13T21:30:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:41 marty volumio[1207]: info: Updating MyVolumio device info Jan 13 21:30:41 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:41 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:41 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:42 marty go-librespot[1773]: time="2026-01-13T21:30:42+01: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]" Jan 13 21:30:42 marty go-librespot[1773]: time="2026-01-13T21:30:42+01: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]" Jan 13 21:30:42 marty go-librespot[1773]: time="2026-01-13T21:30:42+01: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]" Jan 13 21:30:42 marty go-librespot[1773]: time="2026-01-13T21:30:42+01:00" level=info msg="zeroconf server listening on port 37749" Jan 13 21:30:42 marty volumio[1207]: info: Successfully Updated MyVolumio device Jan 13 21:30:42 marty go-librespot[1773]: time="2026-01-13T21:30:42+01:00" level=debug msg="obtained new client token: AAAbju5sZeCwxtYMD2NGh7XoqZ+kgQun4DexlUbJJDeJUSZTtftgg1zBMundIcMqjtNe+Qe6mOnL+sbSMst2fu8sg2o+rJvtmBduNjX2exsqOmGeJ8dG0vpuY4KikmP2pxk20Ov1qTARV5Y3XV/DdBdVPXXKzQxKZU0OCDX5970pvE6I9cMfWyzblgDbA2hV4XfIvk6OZydvUT1UPP/HIEhioYpNZoHD2a+Y+2dzuNW0LEWJIV0yGJY=" Jan 13 21:30:42 marty go-librespot[1773]: time="2026-01-13T21:30:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:42 marty go-librespot[1773]: time="2026-01-13T21:30:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:42 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:42 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:44 marty volumio[1207]: info: Initializing connection to go-librespot Websocket Jan 13 21:30:44 marty volumio[1207]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:30:45 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jan 13 21:30:45 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:45 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:45 marty go-librespot[1794]: go-librespot daemon starting... Jan 13 21:30:45 marty go-librespot[1795]: time="2026-01-13T21:30:45+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:45 marty go-librespot[1795]: time="2026-01-13T21:30:45+01:00" level=debug msg="app state loaded" Jan 13 21:30:45 marty go-librespot[1795]: time="2026-01-13T21:30:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:46 marty go-librespot[1795]: time="2026-01-13T21:30:46+01: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]" Jan 13 21:30:46 marty go-librespot[1795]: time="2026-01-13T21:30:46+01: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]" Jan 13 21:30:46 marty go-librespot[1795]: time="2026-01-13T21:30:46+01: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]" Jan 13 21:30:46 marty go-librespot[1795]: time="2026-01-13T21:30:46+01:00" level=info msg="zeroconf server listening on port 44469" Jan 13 21:30:46 marty go-librespot[1795]: time="2026-01-13T21:30:46+01:00" level=debug msg="obtained new client token: AACCgJf7EbMZKZpC9tlBCa6snXZl/h8tOBVgMwtimu+zmiabpINNX4x+x5mfcKtvw1DAkygtnlOrXlfi11mQRnYc2FpNOwOX5BG6OY6ZG5X5NO7IM/cUWeMNRUFi3xomVnif2DXxrTOPZDp4fbkhTaMvrQqbK0g69ifKSTZ4cPdRzLsaoA6tOO9K9ZYzjBhRNfuqHGmxgGvyn8eq00jsN8qKYcyUVxQDJPmT40WiB0sXezyJ9Kos" Jan 13 21:30:46 marty go-librespot[1795]: time="2026-01-13T21:30:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:46 marty go-librespot[1795]: time="2026-01-13T21:30:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:46 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:46 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:46 marty volumio[1207]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 13 21:30:46 marty volumio[1207]: info: Completed starting MyVolumio Plugin Jan 13 21:30:46 marty volumio[1207]: [Metrics] CommandRouter: 104s 775.09ms Jan 13 21:30:46 marty volumio[1207]: info: CoreCommandRouter::volumiosetStartupVolume Jan 13 21:30:46 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:30:46 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:30:46 marty volumio[1207]: info: CoreCommandRouter::Close All Modals sent Jan 13 21:30:46 marty volumio[1207]: info: CoreCommandRouter::Close All Modals sent Jan 13 21:30:47 marty volumio[1207]: info: Initializing connection to go-librespot Websocket Jan 13 21:30:47 marty volumio[1207]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:30:47 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 13 21:30:47 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 13 21:30:47 marty volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 13 21:30:49 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jan 13 21:30:49 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:49 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:49 marty go-librespot[1804]: go-librespot daemon starting... Jan 13 21:30:49 marty go-librespot[1805]: time="2026-01-13T21:30:49+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:49 marty go-librespot[1805]: time="2026-01-13T21:30:49+01:00" level=debug msg="app state loaded" Jan 13 21:30:49 marty go-librespot[1805]: time="2026-01-13T21:30:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:50 marty volumio[1207]: info: Initializing connection to go-librespot Websocket Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=debug msg="new websocket client" Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:30:50 marty volumio[1207]: info: Connection to go-librespot Websocket established Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=info msg="zeroconf server listening on port 34163" Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=debug msg="obtained new client token: AACI9Yuuz+Kx8WmhuGkxg0515gKVVobAFxfFQMEPjpmYh00WB3MbGctBno8EiMUlEfv5Zy5ZzfAhXoEWJrEjMT/tzglLozwd3+mGIGiO39ntGts2FjWP4hv+6bu8mJOoxKu2M42s7Wt30ykUnU5fxqzJqtXnWj2hWvDv7TKnfXEGLP59Xnirqxu87SWm8bNj2koan6PStHjIRZtVztJkGwg2Zw3Ab8wo0Zgavcj0VhpAQOx26S8bzPA=" Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:50 marty go-librespot[1805]: time="2026-01-13T21:30:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:50 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:50 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:50 marty volumio[1207]: info: Connection to go-librespot Websocket closed Jan 13 21:30:53 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jan 13 21:30:53 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:53 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:53 marty go-librespot[1817]: go-librespot daemon starting... Jan 13 21:30:53 marty volumio[1207]: info: Getting Spotify volume Jan 13 21:30:53 marty go-librespot[1820]: time="2026-01-13T21:30:53+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:53 marty go-librespot[1820]: time="2026-01-13T21:30:53+01:00" level=debug msg="app state loaded" Jan 13 21:30:53 marty go-librespot[1820]: time="2026-01-13T21:30:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:53 marty volumio[1207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:30:54 marty volumio[1207]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:30:54 marty volumio[1207]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 13 21:30:54 marty volumio[1207]: errno: -111, Jan 13 21:30:54 marty volumio[1207]: code: 'ECONNREFUSED', Jan 13 21:30:54 marty volumio[1207]: syscall: 'connect', Jan 13 21:30:54 marty volumio[1207]: address: '127.0.0.1', Jan 13 21:30:54 marty volumio[1207]: port: 9879, Jan 13 21:30:54 marty volumio[1207]: response: undefined Jan 13 21:30:54 marty volumio[1207]: } Jan 13 21:30:54 marty volumio[1207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:30:54 marty go-librespot[1820]: time="2026-01-13T21:30:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:30:54 marty go-librespot[1820]: time="2026-01-13T21:30:54+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:30:54 marty go-librespot[1820]: time="2026-01-13T21:30:54+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:30:54 marty go-librespot[1820]: time="2026-01-13T21:30:54+01:00" level=info msg="zeroconf server listening on port 42037" Jan 13 21:30:54 marty go-librespot[1820]: time="2026-01-13T21:30:54+01:00" level=debug msg="obtained new client token: AACm5KfaPqn6PgHeLbIRwmttXU5iqFqSkEJM+iuwDBorcz/xG8gx1qHB1TMWi8yML3d6JA+8vo1f65+0cx6f2QfgqBB6o+Rgl9RRaP2n4XNV9ylCIeEEQcQuzyrfZomLrykV81FA2hoBa+4MwP14X1Ba7W0i81DE/a4UJCWMvH3oXB9vUM+yrHy17WkVqowX9t6Hfy8qYO7XBpAf5VreQ/9Srg+wsxk4RRZPhpeHiAwRsoXROaX4NcM=" Jan 13 21:30:54 marty go-librespot[1820]: time="2026-01-13T21:30:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:30:54 marty go-librespot[1820]: time="2026-01-13T21:30:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:54 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:54 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:30:57 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Jan 13 21:30:57 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:57 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:30:57 marty go-librespot[1850]: go-librespot daemon starting... Jan 13 21:30:57 marty go-librespot[1851]: time="2026-01-13T21:30:57+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:30:57 marty go-librespot[1851]: time="2026-01-13T21:30:57+01:00" level=debug msg="app state loaded" Jan 13 21:30:57 marty go-librespot[1851]: time="2026-01-13T21:30:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01: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]" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01: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]" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01: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]" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01:00" level=info msg="zeroconf server listening on port 34241" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01:00" level=debug msg="obtained new client token: AADpKvIE/MW4SipCJiA4k4gjtRDqWdRd4YJ8zekLN84txiOaHFe7V6VBs8cMwUs1kaaWXObfqOF1iUshmDzm87oX6G6B0PpnDtIuzffNlKXsNdUJl4Bnpg3XgSCNKzQvlOBhnq++XZOJUsNStosacmAWZ2D4DIwu+8MFxX2XoDWGsTnQznpeyxuCUHLDuSk1cQdgKbw/2/NFbPd+2w1qFmWKlUyoNvoBycuDmH7GL/LsMsk3Lx1VTj0=" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01: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" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 21:30:58 marty go-librespot[1851]: time="2026-01-13T21:30:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:30:58 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:30:58 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:31:01 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jan 13 21:31:01 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:31:01 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:31:01 marty go-librespot[1861]: go-librespot daemon starting... Jan 13 21:31:01 marty go-librespot[1862]: time="2026-01-13T21:31:01+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:31:01 marty go-librespot[1862]: time="2026-01-13T21:31:01+01:00" level=debug msg="app state loaded" Jan 13 21:31:01 marty go-librespot[1862]: time="2026-01-13T21:31:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01: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]" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01: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]" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01: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]" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01:00" level=info msg="zeroconf server listening on port 41935" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01:00" level=debug msg="obtained new client token: AACDw8lwojmTIMm5uFOxoooMRQNwPKRigerDzF3+q11FIFdBsuLuaf/snU9iH4J9OmjsiRIRguuwI0Tru871206ehtqdJC6HnZG94EGRBUbRlr9BqielOMRACAi7/LlXe5IMbb59nSnAczr3ksDd2iMT5wL8U8pmoVMxJNkU+FpJsCIPyLsVR2/z4WDH/63LBJW8iCuj+TweCpnCAxovCVMpB3r38u3jkKrCseMD3Eu2dOcEcxskpso=" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01: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" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 21:31:02 marty go-librespot[1862]: time="2026-01-13T21:31:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:31:02 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:31:02 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:31:05 marty systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Jan 13 21:31:05 marty systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:31:05 marty systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:31:05 marty go-librespot[1884]: go-librespot daemon starting... Jan 13 21:31:05 marty go-librespot[1887]: time="2026-01-13T21:31:05+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:31:05 marty go-librespot[1887]: time="2026-01-13T21:31:05+01:00" level=debug msg="app state loaded" Jan 13 21:31:05 marty go-librespot[1887]: time="2026-01-13T21:31:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:31:06 marty sudo[1891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 21:30' Jan 13 21:31:06 marty sudo[1891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01: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]" Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01: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]" Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01: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]" Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01:00" level=info msg="zeroconf server listening on port 38633" Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01:00" level=debug msg="obtained new client token: AADTRKIE+iUTTLN8oUPyKLTaQMC5f0oUc6AyA2Ea7btCzukYIyh9aKMQD0qR0ypOIPgC6HKW6+SINFkWu1j4Qt0metm8tcIrbdWskHfw58F5Exf/cRfMxcevzcTDg5b/hxH5NieC6hDg+pt6O1e4Y+nqJvet0mGAe9Q0o8nd1hvdpMP9YZAhnpUHUGbRJ8dsKeqjbFu3y7d79Ix23BJlRfxHBsLNmQUCPKbnehXAQG0zBtEJkMCD7eA=" Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01: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" Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 21:31:06 marty go-librespot[1887]: time="2026-01-13T21:31:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:31:06 marty systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:31:06 marty systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"