Oct 12 04:04:00 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3870. Oct 12 04:04:00 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:00 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:00 bcik go-librespot[1644]: go-librespot daemon starting... Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=debug msg="app state loaded" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02: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]" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02: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]" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02: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]" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=info msg="zeroconf server listening on port 43727" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=debug msg="obtained new client token: AAHLBsKhUJC3ZDb9WB8CMPD5jFaysfqVX9GqRcsJmolfVD225m7dR8RdLUtqhF3kYwl6OEZdWSunuaHNGmyXq6PBbnXcl8SoU6d8/kauuAiyUFlyWGIxqtUrMPBp/C74l+qFYRoOdDOmXzogqSZyNapqsGd28UUe48JW94jv358yMVJUT8qeeklpeTDvoLAda/32BGR8cKVP/DhR7fCJ4IL3uCfjb4vLbmGi1e/bpCGljYO4qTqK" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02: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" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=debug msg="completed challenge" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:00 bcik go-librespot[1645]: time="2026-10-12T04:04:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:00 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:00 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:01 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:01 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:03 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3871. Oct 12 04:04:03 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:03 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:03 bcik go-librespot[1654]: go-librespot daemon starting... Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=debug msg="app state loaded" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02: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]" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02: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]" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02: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]" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=info msg="zeroconf server listening on port 46001" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=debug msg="obtained new client token: AAFmhOtqAdpjE1J61y4/n+v4MlqgIAOjD3maNoaHs970qGseFyK4wZBKbU7fOxhKCVOzJ/ph5HjlVcwObGFd/CNlt/PhEeYe1+VFpbklwsLsDsUYQlP4yoMLAOES/H6MfebVG1jbdG4ulz+2eL3HTRRCCFTHff45dxn6+92kim1jOjGwmB5UdDkYgm2aKSRAbHJnw3G5VihApzi2D0rbKPvOMydR5hwj6/UnJsoG8wJm1PcqyHxyUWE=" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=debug msg="completed challenge" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:03 bcik go-librespot[1655]: time="2026-10-12T04:04:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:03 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:03 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:04 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:04 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:07 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3872. Oct 12 04:04:07 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:07 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:07 bcik go-librespot[1678]: go-librespot daemon starting... Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=debug msg="app state loaded" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02: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]" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02: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]" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02: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]" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=info msg="zeroconf server listening on port 44737" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=debug msg="obtained new client token: AAG/pmvtmzMTpQcbuXnnk5yOuzTs3vwBV7gZ8GSSI6qhLJ6HrrG7oBy6hsbdMBdi4UwmtcjS/S13Ct4IsL4tKeQQD5PdQl6yo+WxxZcooa9ThzCWNttGVhNdlkmEp3e8LzRRTpbe7oMLp82W6TmbrarRkJFMxNAD8aYzbDkMrLGDdjYafXOmEBSRZRFF5bpI8PQ3SIQlsZUOHkQO4+4l8Sh6CrmCuQ+JxZlGaY1uaPJcCfzpn/ba" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=debug msg="completed challenge" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:07 bcik go-librespot[1679]: time="2026-10-12T04:04:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:07 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:07 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:07 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:07 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:10 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3873. Oct 12 04:04:10 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:10 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:10 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:10 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:10 bcik go-librespot[1688]: go-librespot daemon starting... Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=debug msg="app state loaded" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02: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]" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02: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]" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02: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]" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=info msg="zeroconf server listening on port 38191" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=debug msg="obtained new client token: AAEjl5NVrFJDI1C40SU1cpNQ33oZzKBESrw49Iu1DIPN+QimO0kNKUBrsSiUJKFwRkp7Qb62CQFjau1uyvEJ63Hxiq8gf2pG6Pj8QBpZnYbA2Y0obF+v7qAFmox8dBtxlzfS7Suj23s2Yaow56R1+SsJWtwfmvLVCDYaT41Ll2JnGgo8aufSyV87btimEqSdvzXPX5nNQwhMHE+jt+1jn2MOL5aAZX/9/ZqoTWijbV7EoeBpkN7s6+I=" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=debug msg="completed challenge" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:10 bcik go-librespot[1689]: time="2026-10-12T04:04:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:10 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:10 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:13 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:13 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:14 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3874. Oct 12 04:04:14 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:14 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:14 bcik go-librespot[1697]: go-librespot daemon starting... Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=debug msg="app state loaded" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02: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]" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02: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]" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02: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]" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=info msg="zeroconf server listening on port 40087" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=debug msg="obtained new client token: AAHTwnvSMkjEHa1K/mXt3gD+TJ82L/pxsxHZeInILX9PpvXOqa06gWi2821cpMsDP3VHDzvt+BgbBzKU2MHI3c837dgvCHFPGnFBPerop2lYC7huiWDwiPftAfNcJwoGky7hZLDy+mX1onM3OUwiJz7Sr27SI3wcUnH8jDtTfbJBxTMoWkOQVEh6KKJ6a0M0gFz2DCl3RtATGuTpRJsiAKw8I3xYgqQJCkLz0f15i/P6puAI6l0G" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=debug msg="completed challenge" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:14 bcik go-librespot[1698]: time="2026-10-12T04:04:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:14 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:14 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:16 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:16 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:17 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3875. Oct 12 04:04:17 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:17 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:17 bcik go-librespot[1724]: go-librespot daemon starting... Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=debug msg="app state loaded" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02: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]" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02: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]" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02: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]" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=info msg="zeroconf server listening on port 44817" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=debug msg="obtained new client token: AAFj2ZxbwYK3gK7yEkw92BgSbu0h+mG2GOT3D01a0XMnk21fzfs+vS+v/gSx3YSfIh+Q5eiwO2Y/wXxwqpUtw5vrJSnIwyCJlgKRkiTm+lC6hMIr5L+QcKkFg4966Z5hgNfQvX8Nbw6yQGC6CsSgT1cdbUww60FcdXhKzYI9P5f/yWCOZw5IftPQZujVC4zOcgWOrupJtwzKupJKQsqABtXS9z1Z7BDvi+dYhQ16SDHBuFyoe5FI2s0=" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=debug msg="completed challenge" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:17 bcik go-librespot[1725]: time="2026-10-12T04:04:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:17 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:17 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:19 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:19 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:21 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3876. Oct 12 04:04:21 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:21 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:21 bcik go-librespot[1735]: go-librespot daemon starting... Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=debug msg="app state loaded" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02: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]" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02: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]" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02: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]" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=info msg="zeroconf server listening on port 42845" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=debug msg="obtained new client token: AAElFQVcOmK6HW0jZ6uf9m9JVhSHlZmVWQ7NwBraxCmI2qXKTVPIKKamQ7yotwtIHrBaTIhVFduBpd8jNZyT4dP656a3gi4rGW+oywn224Vq597W9Zn93gSKsaBuydO636qMHr27pO0/7u5KSM+okhfmHMFqj9NlugFHHIhlvGWjFEZmHy6HiKDTkz2WHAIDspY2G1kBSli2P8fsO2tYiKVEBVYEDctc26bQS8QFyUsDjGvDwYlG" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=debug msg="completed challenge" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:21 bcik go-librespot[1736]: time="2026-10-12T04:04:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:21 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:21 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:22 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:22 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:24 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3877. Oct 12 04:04:24 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:24 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:24 bcik go-librespot[1745]: go-librespot daemon starting... Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=debug msg="app state loaded" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02: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]" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02: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]" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02: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]" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=info msg="zeroconf server listening on port 38395" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=debug msg="obtained new client token: AAGsDwT/oDKyaYnxeLMep7RavV2rgmP2eLI+KNh4asN63GaNp14z94gv8EuQlfazi+YCSUjL1DYnLz/QKttyJYp7T5tPla0g2fkbuec2w37QnUXxJaBVSfYUkR/6j/d+8++pxYPIIlC4I+mI/NtRYoG+pzigZqVQEyVwCce2NjKYC55HTMTrZPpSWywQeq3yvlr0vzyfnBhviTiqkxWO4qcu0KCD+KZFdZF7zRIS4OOObzkdaIZd+tA=" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=debug msg="completed challenge" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:24 bcik go-librespot[1746]: time="2026-10-12T04:04:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:24 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:24 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:25 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:25 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:28 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3878. Oct 12 04:04:28 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:28 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:28 bcik go-librespot[1771]: go-librespot daemon starting... Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=debug msg="app state loaded" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02: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]" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02: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]" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02: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]" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=info msg="zeroconf server listening on port 41529" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=debug msg="obtained new client token: AAHu5a+J/7XDTEhlLfKge/VxN96svSmY44fYK7zBqdBblwx0AJHR5y0ktyZliMAsVsPIMhbyiKtwdeTo8RHa8VwFHzjwfF027LUpWpl9jNz54yFsczNOoIrLUKOKiOh0+r/jt9YlmS2JVvOllgOu5vAjxtzAAw2ecLeY85SAyi50ZoBIttRkWkWTjkFDg89TtPsQY8Tbk1pImBcOWSaYRLsrQyhCakDGE/gPhPhQXB3B71RAcN85" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=debug msg="completed challenge" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:28 bcik go-librespot[1772]: time="2026-10-12T04:04:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:28 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:28 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:28 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:28 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:31 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3879. Oct 12 04:04:31 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:31 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:31 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:31 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:31 bcik go-librespot[1782]: go-librespot daemon starting... Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=debug msg="app state loaded" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02: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]" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02: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]" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02: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]" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=info msg="zeroconf server listening on port 45913" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=debug msg="obtained new client token: AAG93Lx6mo/Xc9XglHlzxuEnk+22JvnL2Q9GnJVWJdGSWxLdzuWykN65CSILphVXl1lwFJvcrljquMk4eqOwh9g1lGfyFef3BJW2ViSRN7fkYrCPy9XCrwLaBwX2JB/M8UAYozFLMdk8joJgW1Vy0c3uMahDEyk0cc4YdIRwlSoq45k7BkLFRt7PxI5P/Wi4K90XbpefCwTsy39Pp7IGtrz7MqfKX3r+CAQ23mayCOudgqLp/4PacCg=" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=debug msg="completed challenge" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:31 bcik go-librespot[1783]: time="2026-10-12T04:04:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:31 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:31 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:34 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:34 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:35 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3880. Oct 12 04:04:35 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:35 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:35 bcik go-librespot[1792]: go-librespot daemon starting... Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=debug msg="app state loaded" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02: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]" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02: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]" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02: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]" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=info msg="zeroconf server listening on port 33609" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=debug msg="obtained new client token: AAFmqlyRePw//mnwcwuW/a5gszxGQHXAtvf/3YpK1vxOy7XbnAT1ripf2wSsPNsUmAteaFpKfC2meL100Ao0t4DaijqHSmGt7Ra9QyPVfhri3F80oyfETGGyjh+YHuBUSJFQusH7ZiDRH78eLNgZRshaKikjbPIiNy7JkccVg40wmj/I7IssUZCLzF7Uh7fsqgKLw1zI4saslceWKFc5d2r4AE2RiqrQBCcQ0Xko1ZFAnFL0M+se" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=debug msg="completed challenge" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:35 bcik go-librespot[1793]: time="2026-10-12T04:04:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:35 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:35 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:37 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:37 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:38 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3881. Oct 12 04:04:38 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:38 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:38 bcik go-librespot[1824]: go-librespot daemon starting... Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=debug msg="app state loaded" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02: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]" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02: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]" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02: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]" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=info msg="zeroconf server listening on port 35975" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=debug msg="obtained new client token: AAGfPanFhfjxnFiwdJu0d+fZMRZFdrWfuXK8y7H1D+ovpRNLF+OoLw2ZrQ8QGi5siqSjmho7pNKBIQrVLIGJMwPXBtQ8HosKmAwZAjZDVHym8YV5jx/VTrbbOB9/JEJPadgxyxvzfrm6rh+fS+gNRBIm+aRbJPZwhnusZgL5GwZr+L1yv4nbCgelTZF3CwfzF8vpLMx7N6M4COXGoeilhV8aY9ZDXjC2gPhjV37gMeSdlXQB1rR6XFk=" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=debug msg="completed challenge" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:38 bcik go-librespot[1825]: time="2026-10-12T04:04:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:38 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:38 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:40 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:40 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:42 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3882. Oct 12 04:04:42 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:42 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:42 bcik go-librespot[1834]: go-librespot daemon starting... Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=debug msg="app state loaded" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02: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]" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02: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]" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02: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]" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=info msg="zeroconf server listening on port 46443" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=debug msg="obtained new client token: AAFyMF8XrblQPnTyW4H+BApZX4ZzNdKBDcQEmULIO+YPgYiYVMkUMx1hbVqGWgB5jvU0JRDQs4Mt0GXR5iOUIGc0Ow/dHLpH6hfyJS5sFzCfFhNnVIPH1ce/ZvlKc5hP03ppKYFIVFCv8SdnaEpglwaPugoSikSTQSHKbu6heHMqCrxyf5tIsjywPouLHREU+muKK+8+k6/zyA6mmw9VOyrBL8GfuhUNK0jSi+NTxEIfhYysP9Fu" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=debug msg="completed challenge" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:42 bcik go-librespot[1835]: time="2026-10-12T04:04:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:42 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:42 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:43 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:43 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:45 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3883. Oct 12 04:04:45 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:45 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:45 bcik go-librespot[1844]: go-librespot daemon starting... Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=debug msg="app state loaded" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02: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]" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02: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]" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02: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]" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=info msg="zeroconf server listening on port 43603" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=debug msg="obtained new client token: AAGlTH35CgS9TYcE+MCIl08Cw1QiyL5s57n5Qtx0cgz1/Jq1pi6uYMA+1Ckd8uY3dtzCkpDLVKLF91p+n7f3DiGLXklXpiZpW1z/QMFy6K+XM3qapFgFBiefZXWU6RYFxyDwDVzbbXpLy5wz5DGyGwFPUzFEiFP03c3g/tQAMBrnXxPxCs7fGgWKs52jkRkQMN6kwAUomfPzEGgFz9EfhnobD5BYglEun2MkcFGhSChnlnWzcorCZ9o=" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=debug msg="completed challenge" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:45 bcik go-librespot[1845]: time="2026-10-12T04:04:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:45 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:45 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:46 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:46 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:49 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3884. Oct 12 04:04:49 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:49 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:49 bcik go-librespot[1870]: go-librespot daemon starting... Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=debug msg="app state loaded" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02: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]" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02: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]" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02: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]" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=info msg="zeroconf server listening on port 46323" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=debug msg="obtained new client token: AAElfWuYkfT7RXDaRkoCh5eMcimkggfmTzgoQMmEufzNFV65rdvN0DTj9McSJUI2NjJoNhner9ZRnTfRu5xH7SDnLa0ITZGU0JTewss0tkyJkhf1BXXQLGgZumYk49utdK6W2UehkTBW1hm5wYfgBXQ2S54nUJOMLEyS+NJSspLfTIDovuSxExDjGVWaUXBVAh0tuBJkn3VJME3FJQ1MmFpnf1bU2tAyM5kpUE9URMeS4FeTHBb7" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=debug msg="completed challenge" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:49 bcik go-librespot[1871]: time="2026-10-12T04:04:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:49 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:49 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:49 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:49 bcik volumio[1099]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:52 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3885. Oct 12 04:04:52 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:52 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:52 bcik go-librespot[1880]: go-librespot daemon starting... Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=debug msg="app state loaded" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:52 bcik volumio[1099]: info: Initializing connection to go-librespot Websocket Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=debug msg="new websocket client" Oct 12 04:04:52 bcik volumio[1099]: info: Connection to go-librespot Websocket established Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02: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]" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02: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]" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02: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]" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=info msg="zeroconf server listening on port 40079" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=debug msg="obtained new client token: AAHHr1DpdSA/UdnvLsSMUC6jkuB11aKt0IP4vJEgbip84wro+fei0mcRhruNNsLSkF7Lo0eqtApHguEOcJOPALAHoPpLIMu9MxOFrxAX7VJyminHkwAefMOM+gBhWow0cSvpeVszmvzAwvsijlq3PrCvaiFEW3NLjibfvFsmMvHwg9TAYXUFXZulBCljOTfZ7GI2BdkSC7ltYsGq2gn878XRU1LIsQlyhP+IXUFnFJXY5FctNi7htKc=" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=debug msg="completed challenge" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:52 bcik go-librespot[1881]: time="2026-10-12T04:04:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:52 bcik volumio[1099]: info: Connection to go-librespot Websocket closed Oct 12 04:04:52 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:52 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:55 bcik volumio[1099]: info: Getting Spotify volume Oct 12 04:04:55 bcik volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 04:04:55 bcik volumio[1099]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:04:55 bcik volumio[1099]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 12 04:04:55 bcik volumio[1099]: errno: -111, Oct 12 04:04:55 bcik volumio[1099]: code: 'ECONNREFUSED', Oct 12 04:04:55 bcik volumio[1099]: syscall: 'connect', Oct 12 04:04:55 bcik volumio[1099]: address: '127.0.0.1', Oct 12 04:04:55 bcik volumio[1099]: port: 9879, Oct 12 04:04:55 bcik volumio[1099]: response: undefined Oct 12 04:04:55 bcik volumio[1099]: } Oct 12 04:04:55 bcik volumio[1099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 04:04:55 bcik sudo[1904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 04:03' Oct 12 04:04:55 bcik sudo[1904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:55 bcik sudo[1904]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:55 bcik volumio-remote-updater[9031]: [2026-10-12 04:04:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 12 04:04:55 bcik volumio-remote-updater[9031]: [2026-10-12 04:04:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 12 04:04:55 bcik volumio5-onboarding[21847]: time=2026-10-12T04:04:55.849+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 12 04:04:55 bcik volumio5-onboarding[21847]: time=2026-10-12T04:04:55.850+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 12 04:04:55 bcik systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:55 bcik systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 12 04:04:55 bcik systemd[1]: volumio.service: Consumed 6.799s CPU time. Oct 12 04:04:55 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 12 04:04:55 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 12 04:04:56 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3886. Oct 12 04:04:56 bcik systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21757. Oct 12 04:04:56 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 12 04:04:56 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:56 bcik systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 12 04:04:56 bcik systemd[1]: volumio.service: Consumed 6.799s CPU time. Oct 12 04:04:56 bcik systemd[1]: Started volumio.service - Volumio Backend Module. Oct 12 04:04:56 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:56 bcik go-librespot[1924]: go-librespot daemon starting... Oct 12 04:04:56 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=debug msg="app state loaded" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02: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]" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02: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]" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02: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]" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=info msg="zeroconf server listening on port 38115" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=debug msg="obtained new client token: AAFmkKhS9h3fRzoPE/+ywO2RUQvivTKYr7jKiX7DtkJlT74q1ZY4Hs+knWlBMuF+FNT9UA4qKViQ0OrNYq489jdOpaDK5rkz9SsTt6zP6t93OmafPCWRgUs285IDxnyvMTk6q3GqRD7v3lrY6A8hK73DxV3my3RjDLxWWeGKAyGUgdO7i/B0ocP2mYlWXgh4n/jBlC3tv0tltf0ZvMjZyZh8ASMAfgFyl1B1jX/19UnSQsz7L3A7" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=debug msg="completed challenge" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:56 bcik go-librespot[1927]: time="2026-10-12T04:04:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:56 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:56 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:56 bcik volumio[1920]: info: ------------------------------------------- Oct 12 04:04:56 bcik volumio[1920]: info: ----- Volumio3 ---- Oct 12 04:04:56 bcik volumio[1920]: info: ------------------------------------------- Oct 12 04:04:56 bcik volumio[1920]: info: ----- System startup ---- Oct 12 04:04:56 bcik volumio[1920]: info: ------------------------------------------- Oct 12 04:04:56 bcik volumio[1920]: info: MYVOLUMIO Environment detected Oct 12 04:04:56 bcik volumio[1920]: info: Plugin folders cleanup Oct 12 04:04:56 bcik volumio[1920]: info: Scanning into folder /volumio/app/plugins/ Oct 12 04:04:56 bcik volumio[1920]: info: Scanning category audio_interface Oct 12 04:04:56 bcik volumio[1920]: info: Scanning category miscellanea Oct 12 04:04:56 bcik volumio[1920]: info: Scanning category music_service Oct 12 04:04:56 bcik volumio[1920]: info: Scanning category plugins.json Oct 12 04:04:56 bcik volumio[1920]: info: Scanning category system_controller Oct 12 04:04:56 bcik volumio[1920]: info: Scanning category user_interface Oct 12 04:04:56 bcik volumio[1920]: info: Scanning into folder /data/plugins/ Oct 12 04:04:56 bcik volumio[1920]: info: Scanning category music_service Oct 12 04:04:56 bcik volumio[1920]: info: Plugin folders cleanup completed Oct 12 04:04:56 bcik volumio[1920]: info: ------------------------------------------- Oct 12 04:04:56 bcik volumio[1920]: info: ----- Core plugins startup ---- Oct 12 04:04:56 bcik volumio[1920]: info: ------------------------------------------- Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugins from folder /volumio/app/plugins/ Oct 12 04:04:56 bcik volumio[1920]: info: Adding plugin upnp to MyMusic Plugins Oct 12 04:04:56 bcik volumio[1920]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 12 04:04:56 bcik volumio[1920]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugins from folder /data/plugins/ Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "system"... Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "appearance"... Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "network"... Oct 12 04:04:56 bcik volumio[1920]: info: Refreshing Cached IP Addresses Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "services"... Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "volumio5onboarding"... Oct 12 04:04:56 bcik sudo[1961]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 04:04:56 bcik sudo[1961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:56 bcik sudo[1959]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 04:04:56 bcik sudo[1959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "alsa_controller"... Oct 12 04:04:56 bcik sudo[1961]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:56 bcik sudo[1959]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:56 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "wizard"... Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "networkfs"... Oct 12 04:04:56 bcik volumio[1920]: info: Starting Udev Watcher for removable devices Oct 12 04:04:56 bcik volumio[1920]: info: Ignoring mount for partition: boot Oct 12 04:04:56 bcik volumio[1920]: info: Ignoring mount for partition: volumio Oct 12 04:04:56 bcik volumio[1920]: info: Ignoring mount for partition: volumio_data Oct 12 04:04:56 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "volumio_command_line_client"... Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "upnp"... Oct 12 04:04:56 bcik volumio[1920]: info: [1791770696867] Starting Upmpd Daemon Oct 12 04:04:56 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "my_music"... Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "mpd"... Oct 12 04:04:56 bcik volumio[1920]: info: Loading plugin "upnp_browser"... Oct 12 04:04:57 bcik volumio[1920]: info: Starting UPNP Browser Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "alarm-clock"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "airplay_emulation"... Oct 12 04:04:57 bcik volumio[1920]: info: Starting Shairport Sync Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "last_100"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "webradio"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "i2s_dacs"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "volumiodiscovery"... Oct 12 04:04:57 bcik volumio[1920]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 04:04:57 bcik volumio[1920]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:04:57 bcik volumio[1920]: *** WARNING *** For more information see Oct 12 04:04:57 bcik volumio[1920]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 04:04:57 bcik volumio[1920]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:04:57 bcik volumio[1920]: *** WARNING *** For more information see Oct 12 04:04:57 bcik node[1920]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 04:04:57 bcik node[1920]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:04:57 bcik node[1920]: *** WARNING *** For more information see Oct 12 04:04:57 bcik node[1920]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 04:04:57 bcik node[1920]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:04:57 bcik node[1920]: *** WARNING *** For more information see Oct 12 04:04:57 bcik volumio[1920]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 12 04:04:57 bcik volumio[1920]: info: Discovery: Started advertising with name: Bącik Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "spop"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "outputs"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "albumart"... Oct 12 04:04:57 bcik volumio[1920]: info: Plugin example_plugin is not enabled Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "inputs"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "updater_comm"... Oct 12 04:04:57 bcik volumio[1920]: info: Plugin mpdemulation is not enabled Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "rest_api"... Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "websocket"... Oct 12 04:04:57 bcik volumio[1920]: info: Starting Socket.io Server version 1.7.4 Oct 12 04:04:57 bcik volumio[1920]: info: Loading plugin "radio_browser"... Oct 12 04:04:57 bcik volumio[1992]: Forking 3 albumart workers Oct 12 04:04:57 bcik volumio[1920]: info: Loading i18n strings for locale pl Oct 12 04:04:57 bcik volumio[1920]: Updating browse sources language Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::initPlayerControls Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: Express server listening on port 3000 Oct 12 04:04:57 bcik volumio[1920]: [Metrics] WebUI: 1s 277.11ms Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::resetVolumioState Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::getcurrentVolume Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 04:04:57 bcik volumio[1920]: info: Volumio Network Manager: Network status updated: 1 Oct 12 04:04:57 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:04:57 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: Reloading queue from file Oct 12 04:04:57 bcik volumio[1920]: info: VolumeController:: Volume=41 Mute =false Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::pushState Oct 12 04:04:57 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioPushState Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::updateTrackBlock Oct 12 04:04:57 bcik volumio[1920]: info: CorePlayQueue::getTrackBlock Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::setRepeat false single undefined Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::pushState Oct 12 04:04:57 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioPushState Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::setRandom false Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::pushState Oct 12 04:04:57 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioPushState Oct 12 04:04:57 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:04:57 bcik volumio[1920]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 12 04:04:57 bcik volumio[1920]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04171 Oct 12 04:04:57 bcik volumio[1920]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 12 04:04:57 bcik volumio[1920]: info: Setting Device type: Raspberry PI Oct 12 04:04:57 bcik volumio[1920]: info: Completed loading Core Plugins Oct 12 04:04:57 bcik volumio[1920]: info: Preparing to generate the ALSA configuration file Oct 12 04:04:57 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:04:57 bcik volumio[1920]: info: VolumeController:: Volume=41 Mute =false Oct 12 04:04:57 bcik volumio[1920]: info: CoreStateMachine::pushState Oct 12 04:04:57 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioPushState Oct 12 04:04:57 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:04:57 bcik volumio[1920]: info: Asound.conf file unchanged, so no further update is needed Oct 12 04:04:57 bcik volumio[1920]: info: Output device has changed, restarting MPD Oct 12 04:04:57 bcik volumio[1920]: info: Output device has changed, restarting Shairport Sync Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:04:57 bcik sudo[2048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 04:04:57 bcik sudo[2048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:57 bcik sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 04:04:57 bcik sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:57 bcik sudo[2048]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:57 bcik volumio[1920]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 04:04:57 bcik volumio[1920]: info: ___________ START PLUGINS ___________ Oct 12 04:04:57 bcik volumio[1920]: info: ControllerMpd::onStart: Initializing MPD Oct 12 04:04:57 bcik systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 04:04:57 bcik volumio[1920]: info: Creating MPD Configuration file Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:04:57 bcik volumio[1920]: info: [1791770697794] CoreMusicLibrary::Adding element Serwery Mediów Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:04:57 bcik sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 12 04:04:57 bcik sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:57 bcik volumio[1920]: info: UPNP Browser: Client initialized successfully Oct 12 04:04:57 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 12 04:04:57 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:04:57 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 12 04:04:57 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 04:04:57 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 04:04:57 bcik sudo[2060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 04:04:57 bcik sudo[2060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:57 bcik sudo[2061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 04:04:57 bcik sudo[2061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:57 bcik sudo[2060]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:57 bcik volumio[1920]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:04:57 bcik volumio[1920]: info: [1791770697832] CoreMusicLibrary::Adding element Last_100 Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:04:57 bcik volumio[1920]: info: [1791770697833] CoreMusicLibrary::Adding element Webradio Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:04:57 bcik volumio[1920]: info: Initializing BBC Radios Oct 12 04:04:57 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 04:04:57 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 04:04:57 bcik sudo[2057]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:57 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 12 04:04:57 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 04:04:57 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 12 04:04:57 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 04:04:57 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 04:04:57 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 04:04:57 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:04:57 bcik volumio[1920]: info: Creating Spotify config file Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:57 bcik sudo[2089]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 04:04:57 bcik sudo[2089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 04:04:57 bcik sudo[2089]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:57 bcik volumio[2004]: Starting albumart workers Oct 12 04:04:57 bcik volumio[2002]: Starting albumart workers Oct 12 04:04:57 bcik volumio[2003]: Starting albumart workers Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:04:57 bcik volumio[1920]: info: [1791770697980] CoreMusicLibrary::Adding element Radio Browser Oct 12 04:04:57 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:04:57 bcik volumio[1920]: Cannot find translation for source Radio Browser Oct 12 04:04:57 bcik volumio[1920]: info: Volumio Calling Home Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:04:58 bcik volumio[1920]: info: MPD Permissions set Oct 12 04:04:58 bcik volumio[1920]: info: MPD Permissions set Oct 12 04:04:58 bcik volumio[1920]: info: Spotify config file written Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik sudo[2096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 12 04:04:58 bcik sudo[2096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:04:58 bcik volumio[1920]: info: No need to fix Spotify hosts Oct 12 04:04:58 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:04:58 bcik go-librespot[2105]: go-librespot daemon starting... Oct 12 04:04:58 bcik sudo[2096]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=debug msg="app state loaded" Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: info: Volumio called home Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: info: Starting Shairport Sync Oct 12 04:04:58 bcik volumio[1920]: info: Starting Shairport Sync Oct 12 04:04:58 bcik volumio[1920]: info: Starting Shairport Sync Oct 12 04:04:58 bcik sudo[2124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 04:04:58 bcik sudo[2124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:58 bcik sudo[2128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 04:04:58 bcik sudo[2128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:58 bcik sudo[2125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 04:04:58 bcik sudo[2125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:04:58 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 04:04:58 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 04:04:58 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:04:58 bcik systemd[1]: shairport-sync.service: Consumed 1.600s CPU time. Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02: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]" Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02: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]" Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02: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]" Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=info msg="zeroconf server listening on port 44117" Oct 12 04:04:58 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:04:58 bcik volumio[1920]: info: Discovery: adding f1ed5fb6-e21d-47c8-819b-29842600355e Oct 12 04:04:58 bcik volumio[1920]: info: Discovery: Found device Bącik Oct 12 04:04:58 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 04:04:58 bcik sudo[2124]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:58 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 04:04:58 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::volumioGetState Oct 12 04:04:58 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:04:58 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik sudo[2125]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:58 bcik sudo[2128]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:58 bcik volumio[1920]: info: An error occurred while refreshing Spotify Token Error: Bad Request Oct 12 04:04:58 bcik volumio[1920]: info: Shairport-Sync Started Oct 12 04:04:58 bcik volumio[1920]: Error adding Membership: Error: addMembership EINVAL Oct 12 04:04:58 bcik volumio[1920]: info: Shairport-Sync Started Oct 12 04:04:58 bcik volumio[1920]: info: Shairport-Sync Started Oct 12 04:04:58 bcik volumio[1920]: info: Discovery: this is already registered, f1ed5fb6-e21d-47c8-819b-29842600355e Oct 12 04:04:58 bcik volumio[1920]: info: Discovery: Found device Bącik Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::volumioGetState Oct 12 04:04:58 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=debug msg="obtained new client token: AAFtKxKiiveJWj5iW9uF3eZ1G1Al7hXm5sMJeNh7ipDiwyEP5YILufH/DdZO76HQZjfJtwkxkuuZWnbBxRhKyPQACqKK+EEjF8mAb52qHrvzyJvXFOhHYcA9PKkXppPPYmgVQITPV3ejdLrT3TA1rCYv8cIRfNDkSu6Lesyy3tOj+9HlTHcMzqpWnKflsSk1D/RgqClPPPG512ularu87vjNasZkN6Zk7VCYmKJooWDOP89dNwf1ArQ=" Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: info: CoreCommandRouter::volumioGetState Oct 12 04:04:58 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=debug msg="completed keyexchange" Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=debug msg="completed challenge" Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik go-librespot[2115]: time="2026-10-12T04:04:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik mpd[2091]: 2026-10-12T04:04:58 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik sudo[2061]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:58 bcik sudo[2049]: pam_unix(sudo:session): session closed for user root Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: info: Completed starting Core Plugins Oct 12 04:04:58 bcik volumio[1920]: info: ------------------------------------------- Oct 12 04:04:58 bcik volumio[1920]: info: ----- MyVolumio plugins startup ---- Oct 12 04:04:58 bcik volumio[1920]: info: ------------------------------------------- Oct 12 04:04:58 bcik volumio[1920]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: error: MPD error: The expression evaluated to a falsy value: Oct 12 04:04:58 bcik volumio[1920]: assert.ok(self.idling) Oct 12 04:04:58 bcik volumio[1920]: error: The expression evaluated to a falsy value: Oct 12 04:04:58 bcik volumio[1920]: assert.ok(self.idling) Oct 12 04:04:58 bcik volumio[1920]: error: updateQueue error: null Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: info: MPD running with PID2091 Oct 12 04:04:58 bcik volumio[1920]: ,establishing connection Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: error: updateQueue error: null Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:04:58 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:00 bcik volumio-remote-updater[9031]: [2026-10-12 04:05:00] [connect] Successful connection Oct 12 04:05:00 bcik volumio-remote-updater[9031]: [2026-10-12 04:05:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791770700 101 Oct 12 04:05:00 bcik volumio[1920]: 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 Oct 12 04:05:01 bcik volumio[1920]: info: go-librespot daemon successfully initialized Oct 12 04:05:01 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3887. Oct 12 04:05:01 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:01 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:01 bcik go-librespot[2167]: go-librespot daemon starting... Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=debug msg="app state loaded" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02: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]" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02: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]" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02: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]" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=info msg="zeroconf server listening on port 39793" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=debug msg="obtained new client token: AAFmaQn4gpMyXrGal9Ahe8aUDMD37uQM0Uw1sv1HF5dOT6flThCTiOQqkzqSnfGc8x6lAR/9nMbJ5ksUfU5vbBRRUTuuKZRCiDV/Y1t04xCNzGZAgBNQpopDzz8LxYFw+68v1JXSVXaJz67evFg7/XaMdrq36deyrwAmQT2VsebGZq/oIX/sDZFDKuMJ7w7zlLO5wuFF09lLTUc57lxbi1Qlqcg7it2eBRhfO5b1Yk46D6Xk3wdmKHk=" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02: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" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=debug msg="completed challenge" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:01 bcik go-librespot[2168]: time="2026-10-12T04:05:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:01 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:01 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:03 bcik volumio[1920]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 12 04:05:04 bcik volumio[1920]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:04 bcik volumio[1920]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:05 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3888. Oct 12 04:05:05 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:05 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:05 bcik go-librespot[2177]: go-librespot daemon starting... Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=debug msg="app state loaded" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02: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]" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02: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]" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02: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]" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=info msg="zeroconf server listening on port 45055" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=debug msg="obtained new client token: AAF0euyShyAw7xLKgVsqJMGPdXeXBuLQupCQhajAy3o86khjthT+Rsu9HTEil0d/QHmoHGY4+7+UPNIYiOVdd3UkiUAXz5XSQM/A0MMrNokbHYM8C4Lu6HAHFoZ0m3CzSfyc5zvPwBKHhowB1a9cSIIgIc5fyP85ciduP55BDdNoxn7tMA5l2Kc9vV8CXRzeVOowGhmZvtTWzSDOZ1xwIuG80Vwpb9pr9b5XdJUsZ16Y2gbeBfDh" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=debug msg="completed challenge" Oct 12 04:05:05 bcik go-librespot[2178]: time="2026-10-12T04:05:05+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 12 04:05:06 bcik volumio[1920]: info: Adding plugin bluetooth to MyMusic Plugins Oct 12 04:05:06 bcik volumio[1920]: info: Adding plugin multiroom to MyMusic Plugins Oct 12 04:05:06 bcik volumio[1920]: info: Adding plugin metavolumio to MyMusic Plugins Oct 12 04:05:06 bcik volumio[1920]: info: Adding plugin cd_controller to MyMusic Plugins Oct 12 04:05:06 bcik volumio[1920]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 12 04:05:06 bcik volumio[1920]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 12 04:05:06 bcik volumio[1920]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 12 04:05:06 bcik sudo[2202]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 04:05:06 bcik volumio[1920]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 12 04:05:06 bcik sudo[2202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:06 bcik sudo[2202]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:06 bcik sudo[2204]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 04:05:06 bcik sudo[2204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:06 bcik sudo[2204]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:07 bcik volumio[1920]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 12 04:05:07 bcik volumio[1920]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 12 04:05:07 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:07 bcik volumio[1920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:07 bcik volumio[1920]: info: Starting MyVolumio Remote Streaming Endpoints Oct 12 04:05:07 bcik volumio[1920]: info: MyVolumio login type: Token Oct 12 04:05:07 bcik volumio[1920]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 12 04:05:07 bcik volumio[1920]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 12 04:05:07 bcik volumio[1920]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 12 04:05:07 bcik volumio[1920]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 12 04:05:07 bcik volumio[1920]: info: Streaming services startup Oct 12 04:05:07 bcik volumio[1920]: info: Starting Streaming Daemon Oct 12 04:05:07 bcik volumio[1920]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 12 04:05:07 bcik sudo[2208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 04:05:07 bcik sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:07 bcik sudo[2208]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:07 bcik volumio[1920]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:07 bcik volumio[1920]: error: Cannot start Volumio Streaming Daemon Oct 12 04:05:07 bcik volumio[1920]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 12 04:05:07 bcik volumio[1920]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 12 04:05:07 bcik go-librespot[2178]: time="2026-10-12T04:05:07+02:00" level=debug msg="new websocket client" Oct 12 04:05:07 bcik volumio[1920]: info: Connection to go-librespot Websocket established Oct 12 04:05:07 bcik sudo[2215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 12 04:05:07 bcik sudo[2215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:07 bcik sudo[2215]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:07 bcik volumio[1920]: info: Upmpdcli Daemon Started Oct 12 04:05:10 bcik volumio[1920]: info: Getting Spotify volume Oct 12 04:05:10 bcik volumio[1920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 12 04:05:10 bcik volumio[1920]: info: CoreCommandRouter::volumioGetState Oct 12 04:05:10 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:10 bcik volumio[1920]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Oct 12 04:05:10 bcik volumio[1920]: SPOTIFY: SPOTIFY VOLUME undefined Oct 12 04:05:10 bcik volumio[1920]: SPOTIFY: VOLUMIO VOLUME 41 Oct 12 04:05:10 bcik volumio[1920]: info: Aligning Spotify Volume to Volumio Volume Oct 12 04:05:10 bcik volumio[1920]: info: CoreCommandRouter::volumioGetState Oct 12 04:05:10 bcik volumio[1920]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:10 bcik volumio[1920]: info: Setting Spotify Volume from Volumio: 41 Oct 12 04:05:10 bcik go-librespot[2178]: time="2026-10-12T04:05:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:10 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:10 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:10 bcik volumio[1920]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 04:05:10 bcik volumio[1920]: Error: socket hang up Oct 12 04:05:10 bcik volumio[1920]: at connResetException (node:internal/errors:720:14) Oct 12 04:05:10 bcik volumio[1920]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 12 04:05:10 bcik volumio[1920]: at Socket.emit (node:events:526:35) Oct 12 04:05:10 bcik volumio[1920]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 12 04:05:10 bcik volumio[1920]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 12 04:05:10 bcik volumio[1920]: code: 'ECONNRESET', Oct 12 04:05:10 bcik volumio[1920]: response: undefined Oct 12 04:05:10 bcik volumio[1920]: } Oct 12 04:05:10 bcik volumio[1920]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 04:05:10 bcik sudo[2231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 04:04' Oct 12 04:05:10 bcik sudo[2231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:10 bcik sudo[2231]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:12 bcik volumio-remote-updater[9031]: [2026-10-12 04:05:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 12 04:05:12 bcik volumio5-onboarding[21847]: time=2026-10-12T04:05:12.134+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 12 04:05:12 bcik volumio-remote-updater[9031]: [2026-10-12 04:05:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 12 04:05:12 bcik systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:12 bcik volumio5-onboarding[21847]: time=2026-10-12T04:05:12.135+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 12 04:05:12 bcik systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 12 04:05:12 bcik systemd[1]: volumio.service: Consumed 6.151s CPU time. Oct 12 04:05:12 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 12 04:05:12 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 12 04:05:12 bcik systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21758. Oct 12 04:05:12 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 12 04:05:12 bcik systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 12 04:05:12 bcik systemd[1]: volumio.service: Consumed 6.151s CPU time. Oct 12 04:05:12 bcik systemd[1]: Started volumio.service - Volumio Backend Module. Oct 12 04:05:12 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 12 04:05:12 bcik volumio[2251]: info: ------------------------------------------- Oct 12 04:05:12 bcik volumio[2251]: info: ----- Volumio3 ---- Oct 12 04:05:12 bcik volumio[2251]: info: ------------------------------------------- Oct 12 04:05:12 bcik volumio[2251]: info: ----- System startup ---- Oct 12 04:05:12 bcik volumio[2251]: info: ------------------------------------------- Oct 12 04:05:12 bcik volumio[2251]: info: MYVOLUMIO Environment detected Oct 12 04:05:12 bcik volumio[2251]: info: Plugin folders cleanup Oct 12 04:05:12 bcik volumio[2251]: info: Scanning into folder /volumio/app/plugins/ Oct 12 04:05:12 bcik volumio[2251]: info: Scanning category audio_interface Oct 12 04:05:12 bcik volumio[2251]: info: Scanning category miscellanea Oct 12 04:05:12 bcik volumio[2251]: info: Scanning category music_service Oct 12 04:05:12 bcik volumio[2251]: info: Scanning category plugins.json Oct 12 04:05:12 bcik volumio[2251]: info: Scanning category system_controller Oct 12 04:05:12 bcik volumio[2251]: info: Scanning category user_interface Oct 12 04:05:12 bcik volumio[2251]: info: Scanning into folder /data/plugins/ Oct 12 04:05:12 bcik volumio[2251]: info: Scanning category music_service Oct 12 04:05:12 bcik volumio[2251]: info: Plugin folders cleanup completed Oct 12 04:05:12 bcik volumio[2251]: info: ------------------------------------------- Oct 12 04:05:12 bcik volumio[2251]: info: ----- Core plugins startup ---- Oct 12 04:05:12 bcik volumio[2251]: info: ------------------------------------------- Oct 12 04:05:12 bcik volumio[2251]: info: Loading plugins from folder /volumio/app/plugins/ Oct 12 04:05:12 bcik volumio[2251]: info: Adding plugin upnp to MyMusic Plugins Oct 12 04:05:12 bcik volumio[2251]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 12 04:05:12 bcik volumio[2251]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 12 04:05:12 bcik volumio[2251]: info: Loading plugins from folder /data/plugins/ Oct 12 04:05:12 bcik volumio[2251]: info: Loading plugin "system"... Oct 12 04:05:12 bcik volumio[2251]: info: Loading plugin "appearance"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "network"... Oct 12 04:05:13 bcik volumio[2251]: info: Refreshing Cached IP Addresses Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "services"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "volumio5onboarding"... Oct 12 04:05:13 bcik sudo[2279]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 04:05:13 bcik sudo[2281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 04:05:13 bcik sudo[2279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:13 bcik sudo[2281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:13 bcik sudo[2281]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:13 bcik sudo[2279]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "alsa_controller"... Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "wizard"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "networkfs"... Oct 12 04:05:13 bcik volumio[2251]: info: Starting Udev Watcher for removable devices Oct 12 04:05:13 bcik volumio[2251]: info: Ignoring mount for partition: boot Oct 12 04:05:13 bcik volumio[2251]: info: Ignoring mount for partition: volumio Oct 12 04:05:13 bcik volumio[2251]: info: Ignoring mount for partition: volumio_data Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "volumio_command_line_client"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "upnp"... Oct 12 04:05:13 bcik volumio[2251]: info: [1791770713107] Starting Upmpd Daemon Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "my_music"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "mpd"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "upnp_browser"... Oct 12 04:05:13 bcik volumio[2251]: info: Starting UPNP Browser Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "alarm-clock"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "airplay_emulation"... Oct 12 04:05:13 bcik volumio[2251]: info: Starting Shairport Sync Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "last_100"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "webradio"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "i2s_dacs"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "volumiodiscovery"... Oct 12 04:05:13 bcik volumio[2251]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 04:05:13 bcik node[2251]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 04:05:13 bcik volumio[2251]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:05:13 bcik node[2251]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:05:13 bcik volumio[2251]: *** WARNING *** For more information see Oct 12 04:05:13 bcik node[2251]: *** WARNING *** For more information see Oct 12 04:05:13 bcik volumio[2251]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 04:05:13 bcik node[2251]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 04:05:13 bcik volumio[2251]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:05:13 bcik node[2251]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 04:05:13 bcik volumio[2251]: *** WARNING *** For more information see Oct 12 04:05:13 bcik node[2251]: *** WARNING *** For more information see Oct 12 04:05:13 bcik volumio[2251]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 12 04:05:13 bcik volumio[2251]: info: Discovery: Started advertising with name: Bącik Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "spop"... Oct 12 04:05:13 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3889. Oct 12 04:05:13 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:13 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:13 bcik go-librespot[2311]: go-librespot daemon starting... Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=debug msg="app state loaded" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02: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]" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02: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]" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02: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]" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=info msg="zeroconf server listening on port 45067" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=debug msg="obtained new client token: AAE9yb3qiJX3i4xM6VkQadKqT1fogGktgKliE4MY5eERe5skk5sAd5dWaBTAbv0Er4M5fM5pMAKpOXMZj8zrFXOnqm4B/uEv3aiME69GFNuHxXShcJeipwiufD/nv6ePrZYf+3iwajDqo247XA/7dq3dhEpjTlnmieGdlvIsH2Edgx28w7THGfEY12xFDW4xYiALnFmxiPl8bTLeK8NAsYJefPqLr8fNlCvSBuEgtPRw4V+rVoKJSb0=" Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "outputs"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "albumart"... Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:13 bcik volumio[2251]: info: Plugin example_plugin is not enabled Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "inputs"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "updater_comm"... Oct 12 04:05:13 bcik volumio[2251]: info: Plugin mpdemulation is not enabled Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "rest_api"... Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "websocket"... Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=debug msg="completed challenge" Oct 12 04:05:13 bcik volumio[2251]: info: Starting Socket.io Server version 1.7.4 Oct 12 04:05:13 bcik volumio[2251]: info: Loading plugin "radio_browser"... Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:13 bcik volumio[2321]: Forking 3 albumart workers Oct 12 04:05:13 bcik volumio[2251]: info: Loading i18n strings for locale pl Oct 12 04:05:13 bcik volumio[2251]: Updating browse sources language Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:05:13 bcik go-librespot[2312]: time="2026-10-12T04:05:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:13 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:13 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::initPlayerControls Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: Express server listening on port 3000 Oct 12 04:05:13 bcik volumio[2251]: [Metrics] WebUI: 1s 293.96ms Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::resetVolumioState Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::getcurrentVolume Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 04:05:13 bcik volumio[2251]: info: Volumio Network Manager: Network status updated: 1 Oct 12 04:05:13 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:05:13 bcik volumio[2251]: info: VolumeController:: Volume=41 Mute =false Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::pushState Oct 12 04:05:13 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::volumioPushState Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::updateTrackBlock Oct 12 04:05:13 bcik volumio[2251]: info: CorePlayQueue::getTrackBlock Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:13 bcik volumio[2251]: info: Reloading queue from file Oct 12 04:05:13 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::setRepeat false single undefined Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::pushState Oct 12 04:05:13 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::volumioPushState Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::setRandom false Oct 12 04:05:13 bcik volumio[2251]: info: CoreStateMachine::pushState Oct 12 04:05:13 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 04:05:13 bcik volumio[2251]: info: CoreCommandRouter::volumioPushState Oct 12 04:05:13 bcik volumio[2251]: info: Setting Device type: Raspberry PI Oct 12 04:05:13 bcik volumio[2251]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 12 04:05:13 bcik volumio[2251]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04171 Oct 12 04:05:13 bcik volumio[2251]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 12 04:05:13 bcik volumio[2251]: info: Completed loading Core Plugins Oct 12 04:05:13 bcik volumio[2251]: info: Preparing to generate the ALSA configuration file Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:05:14 bcik volumio[2251]: info: VolumeController:: Volume=41 Mute =false Oct 12 04:05:14 bcik volumio[2251]: info: CoreStateMachine::pushState Oct 12 04:05:14 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioPushState Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:05:14 bcik volumio[2251]: info: Asound.conf file unchanged, so no further update is needed Oct 12 04:05:14 bcik volumio[2251]: info: Output device has changed, restarting MPD Oct 12 04:05:14 bcik volumio[2251]: info: Output device has changed, restarting Shairport Sync Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:14 bcik sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 04:05:14 bcik sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik sudo[2377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 04:05:14 bcik sudo[2377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik volumio[2251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 04:05:14 bcik volumio[2251]: info: ___________ START PLUGINS ___________ Oct 12 04:05:14 bcik volumio[2251]: info: ControllerMpd::onStart: Initializing MPD Oct 12 04:05:14 bcik volumio[2251]: info: Creating MPD Configuration file Oct 12 04:05:14 bcik sudo[2377]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:05:14 bcik systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 04:05:14 bcik volumio[2251]: info: [1791770714053] CoreMusicLibrary::Adding element Serwery Mediów Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:05:14 bcik sudo[2386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 12 04:05:14 bcik sudo[2386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik sudo[2388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 04:05:14 bcik sudo[2388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik volumio[2251]: info: UPNP Browser: Client initialized successfully Oct 12 04:05:14 bcik sudo[2390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 04:05:14 bcik sudo[2390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:14 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 12 04:05:14 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 04:05:14 bcik sudo[2388]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 12 04:05:14 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 04:05:14 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 04:05:14 bcik volumio[2251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:05:14 bcik volumio[2251]: info: [1791770714100] CoreMusicLibrary::Adding element Last_100 Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:05:14 bcik volumio[2251]: info: [1791770714101] CoreMusicLibrary::Adding element Webradio Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:05:14 bcik volumio[2251]: info: Initializing BBC Radios Oct 12 04:05:14 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 04:05:14 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 04:05:14 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 12 04:05:14 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 04:05:14 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 12 04:05:14 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 04:05:14 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 04:05:14 bcik sudo[2386]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 04:05:14 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: Creating Spotify config file Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2331]: Starting albumart workers Oct 12 04:05:14 bcik sudo[2413]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 04:05:14 bcik sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 04:05:14 bcik sudo[2413]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik volumio[2333]: Starting albumart workers Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 04:05:14 bcik volumio[2251]: info: [1791770714325] CoreMusicLibrary::Adding element Radio Browser Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 04:05:14 bcik volumio[2251]: Cannot find translation for source Radio Browser Oct 12 04:05:14 bcik volumio[2251]: info: Volumio Calling Home Oct 12 04:05:14 bcik volumio[2332]: Starting albumart workers Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:05:14 bcik volumio[2251]: info: MPD Permissions set Oct 12 04:05:14 bcik volumio[2251]: info: MPD Permissions set Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 12 04:05:14 bcik volumio[2251]: info: Volumio called home Oct 12 04:05:14 bcik volumio[2251]: info: Spotify config file written Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 12 04:05:14 bcik sudo[2438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 12 04:05:14 bcik sudo[2438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:14 bcik volumio[2251]: info: No need to fix Spotify hosts Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:14 bcik go-librespot[2446]: go-librespot daemon starting... Oct 12 04:05:14 bcik sudo[2438]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=debug msg="app state loaded" Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:14 bcik volumio[2251]: info: Discovery: adding f1ed5fb6-e21d-47c8-819b-29842600355e Oct 12 04:05:14 bcik volumio[2251]: info: Discovery: Found device Bącik Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioGetState Oct 12 04:05:14 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: info: Starting Shairport Sync Oct 12 04:05:14 bcik volumio[2251]: info: Starting Shairport Sync Oct 12 04:05:14 bcik volumio[2251]: info: Starting Shairport Sync Oct 12 04:05:14 bcik sudo[2465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 04:05:14 bcik sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik volumio[2251]: info: Discovery: this is already registered, f1ed5fb6-e21d-47c8-819b-29842600355e Oct 12 04:05:14 bcik volumio[2251]: info: Discovery: Found device Bącik Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioGetState Oct 12 04:05:14 bcik sudo[2470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 04:05:14 bcik sudo[2470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:14 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 04:05:14 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 04:05:14 bcik sudo[2467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 04:05:14 bcik sudo[2467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:14 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:05:14 bcik systemd[1]: shairport-sync.service: Consumed 1.519s CPU time. Oct 12 04:05:14 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik sudo[2465]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik volumio[2251]: info: An error occurred while refreshing Spotify Token Error: Bad Request Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02: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]" Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02: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]" Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02: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]" Oct 12 04:05:14 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 04:05:14 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 04:05:14 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:05:14 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=info msg="zeroconf server listening on port 45973" Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik sudo[2467]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik sudo[2470]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik volumio[2251]: info: Shairport-Sync Started Oct 12 04:05:14 bcik volumio[2251]: Error adding Membership: Error: addMembership EINVAL Oct 12 04:05:14 bcik volumio[2251]: info: CoreCommandRouter::volumioGetState Oct 12 04:05:14 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:14 bcik volumio[2251]: info: Shairport-Sync Started Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: info: Shairport-Sync Started Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=debug msg="obtained new client token: AAFcwp0xDjvzyNBW61XPhncCuAgE8Mb3byknwTg6NL1utTUyzPJJUk4bjTQS9/3hYWqxfEun7vmazz6gspfgnn4jcfOTStLaYxOnEpMEXyk8cvls0pvq4gQYx2zPSwZtpB7imlBMGyLfns+IEsFlKH0D+NJafMHDoI/wbSM+a288C/YioN9TFaXkWmiFCHG52YXIkb1ebITgaxHuOzCsB131FqnCUDFEQ2ixql9R5M0opoISshPdA/A=" Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik mpd[2420]: 2026-10-12T04:05:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik sudo[2378]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik sudo[2390]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=debug msg="completed challenge" Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: error: updateQueue error: null Oct 12 04:05:14 bcik volumio[2251]: info: Completed starting Core Plugins Oct 12 04:05:14 bcik volumio[2251]: info: ------------------------------------------- Oct 12 04:05:14 bcik volumio[2251]: info: ----- MyVolumio plugins startup ---- Oct 12 04:05:14 bcik volumio[2251]: info: ------------------------------------------- Oct 12 04:05:14 bcik volumio[2251]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: error: updateQueue error: null Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: info: MPD running with PID2420 Oct 12 04:05:14 bcik volumio[2251]: ,establishing connection Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: error: updateQueue error: null Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:14 bcik go-librespot[2452]: time="2026-10-12T04:05:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:14 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:14 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:17 bcik volumio-remote-updater[9031]: [2026-10-12 04:05:17] [connect] Successful connection Oct 12 04:05:17 bcik volumio-remote-updater[9031]: [2026-10-12 04:05:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791770717 101 Oct 12 04:05:17 bcik volumio[2251]: 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 Oct 12 04:05:17 bcik volumio[2251]: info: go-librespot daemon successfully initialized Oct 12 04:05:18 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3890. Oct 12 04:05:18 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:18 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:18 bcik go-librespot[2496]: go-librespot daemon starting... Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=debug msg="app state loaded" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02: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]" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02: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]" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02: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]" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=info msg="zeroconf server listening on port 44135" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=debug msg="obtained new client token: AAE62f481NG+GyVoGPB4zKHRyNjPDfuIBoSR7etJp+RmOpZ42Tzs/M7DMZb64BPoNPjP+Ti2OyvVwOi8Dw68swx5stn2RihMUAG53heNgf1fiHgm36kSQ8GrAa6uixSWQPm4bMRPHaH0upmefgqTuAKUz0/snxn31XZNYs7uU9m37yGFzqWILU0nTcvJaavqxTnwV5meCm7wcd97TQwZBVARWaTW+Iu+H1R0MR5AyjNPLPHIkc55" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=debug msg="completed challenge" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:18 bcik go-librespot[2497]: time="2026-10-12T04:05:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:18 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:18 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:19 bcik volumio[2251]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 12 04:05:20 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:20 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:21 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3891. Oct 12 04:05:21 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:21 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:21 bcik go-librespot[2508]: go-librespot daemon starting... Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=debug msg="app state loaded" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02: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]" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02: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]" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02: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]" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=info msg="zeroconf server listening on port 40339" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=debug msg="obtained new client token: AAGjPDNaiPOmsyXddmUY0ZgXsH8GHHT2SnKcg/EDU4g7QebnH1CCB6AeDya5H1DFXJNKHG4yOvy9jvmrd/HVa5+e19XMAVlqY2zvVzR2dz2/5aRmXnG3kIRwGDLezyTza09x7pn7CikUXuhjIdRjVcFsT/VDtRvUvgPZxjVNQ2SDsWvMu9ScrM+sgeizVWU5I3kNkjnFDAOHjgNxwGAs3obw6R+bZmjev+CBcLswlA+QMsEHVoxmDBk=" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=debug msg="completed challenge" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:21 bcik go-librespot[2509]: time="2026-10-12T04:05:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:21 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:21 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 12 04:05:22 bcik volumio[2251]: info: Adding plugin bluetooth to MyMusic Plugins Oct 12 04:05:22 bcik volumio[2251]: info: Adding plugin multiroom to MyMusic Plugins Oct 12 04:05:22 bcik volumio[2251]: info: Adding plugin metavolumio to MyMusic Plugins Oct 12 04:05:22 bcik volumio[2251]: info: Adding plugin cd_controller to MyMusic Plugins Oct 12 04:05:22 bcik volumio[2251]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 12 04:05:22 bcik volumio[2251]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 12 04:05:22 bcik volumio[2251]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 12 04:05:22 bcik volumio[2251]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 12 04:05:23 bcik volumio[2251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 12 04:05:23 bcik volumio[2251]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 12 04:05:23 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:23 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:23 bcik volumio[2251]: info: Starting MyVolumio Remote Streaming Endpoints Oct 12 04:05:23 bcik volumio[2251]: info: MyVolumio login type: Token Oct 12 04:05:23 bcik volumio[2251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 12 04:05:23 bcik volumio[2251]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 12 04:05:23 bcik volumio[2251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 12 04:05:23 bcik volumio[2251]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 12 04:05:23 bcik volumio[2251]: info: Streaming services startup Oct 12 04:05:23 bcik volumio[2251]: info: Starting Streaming Daemon Oct 12 04:05:23 bcik sudo[2534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 04:05:23 bcik sudo[2534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:23 bcik volumio[2251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 12 04:05:23 bcik sudo[2534]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:23 bcik sudo[2541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 04:05:23 bcik sudo[2541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:23 bcik sudo[2543]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 04:05:23 bcik sudo[2543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:23 bcik sudo[2541]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:23 bcik sudo[2543]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:23 bcik volumio[2251]: error: Cannot start Volumio Streaming Daemon Oct 12 04:05:23 bcik volumio[2251]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 12 04:05:23 bcik volumio[2251]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 12 04:05:23 bcik sudo[2547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 12 04:05:23 bcik sudo[2547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:23 bcik sudo[2547]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:23 bcik volumio[2251]: info: Upmpdcli Daemon Started Oct 12 04:05:23 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:23 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:23 bcik volumio[2251]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 12 04:05:24 bcik volumio[2251]: info: MyVolumio token set successfully Oct 12 04:05:24 bcik volumio[2251]: info: MYVOLUMIO: Adding device Oct 12 04:05:24 bcik volumio[2251]: info: MYVOLUMIO: Evaluating Server Oct 12 04:05:24 bcik volumio[2251]: info: MyVolumio status changed Oct 12 04:05:24 bcik volumio[2251]: info: Streaming services startup Oct 12 04:05:24 bcik volumio[2251]: info: Starting Streaming Daemon Oct 12 04:05:24 bcik volumio[2251]: info: Removing browser output: myVolumio user plan is not superstar Oct 12 04:05:24 bcik volumio[2251]: info: Removing audio output: Oct 12 04:05:24 bcik volumio[2251]: info: Stoppping Tunnel 1 Oct 12 04:05:24 bcik sudo[2570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 04:05:24 bcik sudo[2570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:24 bcik sudo[2572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 12 04:05:24 bcik sudo[2572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 04:05:24 bcik 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. Oct 12 04:05:24 bcik 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. Oct 12 04:05:24 bcik sudo[2570]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:24 bcik volumio[2251]: error: Cannot start Volumio Streaming Daemon Oct 12 04:05:24 bcik volumio[2251]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 12 04:05:24 bcik volumio[2251]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 12 04:05:24 bcik 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. Oct 12 04:05:24 bcik 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. Oct 12 04:05:24 bcik 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. Oct 12 04:05:24 bcik 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. Oct 12 04:05:24 bcik sudo[2572]: pam_unix(sudo:session): session closed for user root Oct 12 04:05:24 bcik volumio[2251]: info: Remote SSH Stopped Oct 12 04:05:24 bcik volumio[2251]: info: Setting Geolocation for MyVolumio to eu6 Oct 12 04:05:24 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:24 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:24 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:25 bcik volumio[2251]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 12 04:05:25 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3892. Oct 12 04:05:25 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:25 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:25 bcik go-librespot[2575]: go-librespot daemon starting... Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=debug msg="app state loaded" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02: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]" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02: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]" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02: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]" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=info msg="zeroconf server listening on port 42979" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=debug msg="obtained new client token: AAHe54OUvJy2NrguprNgQzNq078jfbC+U7W3D1QB97wbPVZRvGo4YwsqPdM6M9Fs4mRvxIkdxLjLn39qlON3tapZxkQzsHDexXhE/QRzWxh0za2I56zMyoAeob3gHLuiyoiqcw/XD/8yiO4c/A6I7s1u/FFjwZwS+eb3j/hGdlUqU5g9b4PZ9sdbLDykehc7yVCsZZdwAhbtoj/Nka3tw1yQRDDEPkVnQa5WbT+EOYrkJPfB2zip" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=debug msg="completed challenge" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:25 bcik go-librespot[2576]: time="2026-10-12T04:05:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:25 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:25 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:25 bcik volumio[2251]: info: Updating MyVolumio device info Oct 12 04:05:25 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:25 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:25 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:25 bcik volumio[2251]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 12 04:05:26 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:26 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:28 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3893. Oct 12 04:05:28 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:28 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:28 bcik go-librespot[2586]: go-librespot daemon starting... Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=debug msg="app state loaded" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02: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]" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02: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]" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02: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]" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=info msg="zeroconf server listening on port 42235" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=debug msg="obtained new client token: AAHrNlW02+NKkVzIaBWhj36hZx2q6Dvn8lzTU05XgNVcVcnut1SoMG4IMD5qreo7jIbUTWW1n8yieenRCtS+IEsWeA/9nzVGslEpnm6bKzO4/Tnp4jmwq+iBJBuxEeYVtWWarlspbFY7mFDu+0fjFPE54hTPiEugci/dnsVHPcn/MTUbiIBLp+aMKCk4A/Wj5TXvrwXyf1uftRPWRdu7VzIaX419CcSf4WLWdvzhMG2rsW1puUHoxO4=" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=debug msg="completed challenge" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:28 bcik go-librespot[2587]: time="2026-10-12T04:05:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:28 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:28 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:29 bcik volumio[2251]: info: MYVOLUMIO: Adding device Oct 12 04:05:29 bcik volumio[2251]: info: MYVOLUMIO: Evaluating Server Oct 12 04:05:29 bcik volumio[2251]: info: Setting Geolocation for MyVolumio to eu6 Oct 12 04:05:29 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:29 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:29 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:29 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:29 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:29 bcik volumio[2251]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 12 04:05:30 bcik volumio[2251]: info: Updating MyVolumio device info Oct 12 04:05:30 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:30 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:30 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:30 bcik volumio[2251]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 12 04:05:32 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3894. Oct 12 04:05:32 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:32 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:32 bcik go-librespot[2614]: go-librespot daemon starting... Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=debug msg="app state loaded" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02: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]" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02: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]" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02: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]" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=info msg="zeroconf server listening on port 37803" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=debug msg="obtained new client token: AAGLF9bOGEnLntsRh9yg65WAJeSix0MSWN/9UFxJ6j/cybHZIB+jmj5DVoGjASSx9K8ZtZ7wJ3GWN/tR7zOsu8I1lY8m1T+ndUHvdg1JmE01nNcy2qTEMP4RmLPVKKzhGfiEJuy1UCeAZxReGszjvlXGMtkpzCbCMrqVeLoCC6AdwZTQILL1TQMcVnSSZexLLqKIAggd1W/o2CZI/04WkYUZlrMfHip7WoyRmJBCWqK5aGYOeRnQ" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02: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" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=debug msg="completed challenge" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:32 bcik go-librespot[2615]: time="2026-10-12T04:05:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:32 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:32 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:32 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:32 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:35 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3895. Oct 12 04:05:35 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:35 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:35 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:35 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:35 bcik go-librespot[2638]: go-librespot daemon starting... Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=debug msg="app state loaded" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02: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]" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02: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]" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02: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]" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=info msg="zeroconf server listening on port 41765" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=debug msg="obtained new client token: AAEVfgA8Ki1Ga0cPRX870F5HjlacU2JekKBgcOM0539lh6/1h0fhNlAjp3WFshPrzv4nbqEDPvoTdXsAwq1wK3cUwKDBEyC2ripsT3u8WlCbYEGREwL+VoJkFY4sQa8bnwkM8u0PBOsLkNeQa/Y/zalJ2qCEilU38iPhvNqshk1rxL4yNiAGjBU55pZ6rIYMd9SSrZ5cUWeJ3ZRE86DC41OuBb0RqmEtZryEpD+NbTLvpbl5DeN/g3o=" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02: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" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=debug msg="completed challenge" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:35 bcik go-librespot[2639]: time="2026-10-12T04:05:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:35 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:35 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:38 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 04:05:38 bcik volumio[2251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 12 04:05:38 bcik volumio[2251]: info: CoreCommandRouter::volumioGetState Oct 12 04:05:38 bcik volumio[2251]: info: CorePlayQueue::getTrack 0 Oct 12 04:05:38 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:38 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:39 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3896. Oct 12 04:05:39 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:39 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:39 bcik go-librespot[2649]: go-librespot daemon starting... Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=debug msg="app state loaded" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02: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]" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02: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]" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02: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]" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=info msg="zeroconf server listening on port 36143" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=debug msg="obtained new client token: AAEzi+T+q1+ckcL2ZWPHkvAveM4OXneO4mcFn0vS20o489ahYdlJ2r8jDL7HWEfEMykbeYzn46wpOz/i1r/+ch/3vXjnlFYg/iWB38sovGZ5s5oWZR8zEpqiKXNzl8UzTva9WZdexsofNaY4Jsg+gbI4ai4nEA0a3lMEMcG8OMJ+1h6Q0dIN2gWEFvdMcQzJ2W+gSCTvKJlMxb82K7bAECw+gl3TxsNswewIQ1eqoHn9btdLWMmK" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=debug msg="completed challenge" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:39 bcik go-librespot[2650]: time="2026-10-12T04:05:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:39 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:39 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:41 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:41 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:42 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3897. Oct 12 04:05:42 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:42 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:42 bcik go-librespot[2659]: go-librespot daemon starting... Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=debug msg="app state loaded" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02: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]" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02: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]" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02: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]" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=info msg="zeroconf server listening on port 44337" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=debug msg="obtained new client token: AAGhVmwkprXSVdIIEww2CuPnSxYb7MnLVgJ+yZ01ZdfGtMgZLgRiYOZiahVzYWNXu1K+ScVgezPdzJboPgzTPsC20Bvkmr0TSoatTWw6ph6geRNSaH6ehoY42hyE0C5FQ/EBSomh/Y+KuM9Y8O7mYQh6RoC07dQgrMa/0/BAVsUmrZlwCLlIXI7+MCw3gscnWm3AaEabzUk0d5HIdG8+gha26DHT/TEQH3sAQg9KDR11zoCji4XiUNY=" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02: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" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=debug msg="completed challenge" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:42 bcik go-librespot[2660]: time="2026-10-12T04:05:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:42 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:42 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:05:43 bcik volumio[2251]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 12 04:05:43 bcik volumio[2251]: info: Completed starting MyVolumio Plugin Oct 12 04:05:43 bcik volumio[2251]: [Metrics] CommandRouter: 30s 619.12ms Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::volumiosetStartupVolume Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::Close All Modals sent Oct 12 04:05:43 bcik volumio[2251]: info: CoreCommandRouter::Close All Modals sent Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 04:05:44 bcik volumio-remote-updater[9031]: Test mode disabled Oct 12 04:05:44 bcik volumio-remote-updater[9031]: Alpha mode disabled Oct 12 04:05:44 bcik volumio-remote-updater[9031]: Alpha legacy test mode disabled Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 12 04:05:44 bcik volumio[2251]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 12 04:05:44 bcik volumio[2251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 12 04:05:44 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:44 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:46 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3898. Oct 12 04:05:46 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:46 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:46 bcik go-librespot[2689]: go-librespot daemon starting... Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=debug msg="app state loaded" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02: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]" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02: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]" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02: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]" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=info msg="zeroconf server listening on port 40717" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=debug msg="obtained new client token: AAGCAezcU+gtlCmfjZKEn0V+SdWyNSZ69t2qcQBNN/7Rh7/veU0vreIpODM3iQ2iVjjfAwGlnAJcjhuTx/2nIzdHsM/N6ogeZM69YHY2orITPtOy/PtYDHccQAVfrSiWuVnq4oeOdBofqjCIpKirB6LTgBJ0DU4Rsyyr2uv/lWADCo/ttVvsAA0uQvjgJIyKpSwFgAo0w1xa88urOmx/BAx7NND9nuRTa20zKFfLc2Wc1ajNEZaK" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=debug msg="completed challenge" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:46 bcik go-librespot[2690]: time="2026-10-12T04:05:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:46 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:46 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:47 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:47 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:49 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3899. Oct 12 04:05:49 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:49 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:49 bcik go-librespot[2699]: go-librespot daemon starting... Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=debug msg="app state loaded" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02: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]" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02: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]" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02: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]" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=info msg="zeroconf server listening on port 35441" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=debug msg="obtained new client token: AAHimS9yex7VgL4d8U+o7ySBxtKdXZ+biRR5MavFxd/Vajz1JqRdLJuARswvpT7d+rX793q7LNuFbLm2LXFse/m1qyJ7PV71B2+eyJbbCT9l0RdpjuCg/K3MfsdjU98XdEBeHEh8dtnpqFaiZKmnXTOijYv34S0dmJevxFBYegLNDv/ZDlFkjqGX8i1MR1jUwclFM7aL1sqe18NWf9VgdxDnDVYn2Ppxo2ZFxX1Z98XExOjrLVN3a7U=" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=debug msg="completed challenge" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:49 bcik go-librespot[2700]: time="2026-10-12T04:05:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:49 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:49 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:50 bcik volumio[2251]: info: BOOT COMPLETED Oct 12 04:05:50 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:50 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:53 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3900. Oct 12 04:05:53 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:53 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:53 bcik go-librespot[2709]: go-librespot daemon starting... Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=debug msg="app state loaded" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02: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]" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02: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]" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02: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]" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=info msg="zeroconf server listening on port 43963" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=debug msg="obtained new client token: AAHbaqMTCFnPMZVK+TjK3hLtSUwCdquWrh6TBMYzGxxrjE9SO7evYjNHkJYgEGhu6ozBL656lX1jSuRBy0EA9/6xHJcvX98pZjv3kU5anSU/JYfDLa2zrbRG32BqI5H0gVfTYBZoN6xlOKReDEsOggGjOMufyu10vSXsYB9nR3VhSDukWg+6jmtrTJIaCYlnGsDH0XcCkviFrneFBQDAzIYOK+fDRUQe439aGlXuPCH6zH/GjJ8l" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=debug msg="completed challenge" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:53 bcik go-librespot[2710]: time="2026-10-12T04:05:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:53 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:53 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:53 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:53 bcik volumio[2251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:56 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3901. Oct 12 04:05:56 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:56 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 04:05:56 bcik go-librespot[2733]: go-librespot daemon starting... Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=info msg="running go-librespot 0.6.2" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=debug msg="app state loaded" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 04:05:56 bcik volumio[2251]: info: Initializing connection to go-librespot Websocket Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=debug msg="new websocket client" Oct 12 04:05:56 bcik volumio[2251]: info: Connection to go-librespot Websocket established Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02: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]" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02: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]" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02: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]" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=info msg="zeroconf server listening on port 42143" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=debug msg="obtained new client token: AAFs/pUVRzmGyap70m1rsw6mjfZ+8FiQCDX2tlmfrc06/ebPKU7jDXJcul2h59kULTNNAUDuULbVNHaAfrLJeRyhAtp8BlO6yYqn7mdQIM5NhXAKHkEEFsP5qzsHa1GvtH+AeSrpvRmfs/JuA02VoEDgvjicD9vzU9FaTm6nyoFV22DaheGk9/aEeKJZvsfS24/dpJy5l4VXMU1uRIfA20b7cIYUEpqAR9B4nM4qWV+LNdaVjtc9/oY=" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=debug msg="completed keyexchange" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=debug msg="completed challenge" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=info msg="authenticated AP" username="la***pl" Oct 12 04:05:56 bcik go-librespot[2734]: time="2026-10-12T04:05:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 12 04:05:56 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 04:05:56 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 04:05:56 bcik volumio[2251]: info: Connection to go-librespot Websocket closed Oct 12 04:05:59 bcik volumio[2251]: info: Getting Spotify volume Oct 12 04:05:59 bcik volumio[2251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 04:05:59 bcik volumio[2251]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 04:05:59 bcik volumio[2251]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 12 04:05:59 bcik volumio[2251]: errno: -111, Oct 12 04:05:59 bcik volumio[2251]: code: 'ECONNREFUSED', Oct 12 04:05:59 bcik volumio[2251]: syscall: 'connect', Oct 12 04:05:59 bcik volumio[2251]: address: '127.0.0.1', Oct 12 04:05:59 bcik volumio[2251]: port: 9879, Oct 12 04:05:59 bcik volumio[2251]: response: undefined Oct 12 04:05:59 bcik volumio[2251]: } Oct 12 04:05:59 bcik volumio[2251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 04:05:59 bcik sudo[2758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 04:04' Oct 12 04:05:59 bcik sudo[2758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"