Oct 11 22:45:01 volumiopc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16566. Oct 11 22:45:01 volumiopc systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:01 volumiopc systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:01 volumiopc go-librespot[453130]: go-librespot daemon starting... Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=info msg="running go-librespot 0.9.1" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="app state loaded" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=info msg="zeroconf server listening on port 42273" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=info msg="loading previously persisted zeroconf credentials" username="31************************zq" Oct 11 22:45:01 volumiopc volumio[1024]: info: Initializing connection to go-librespot Websocket Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="new websocket client" Oct 11 22:45:01 volumiopc volumio[1024]: info: Connection to go-librespot Websocket established Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="obtained new client token: AAFdl9fxJvIrRyc5CIdLiG9Yp4VTH1A1QfFahB2uQHMmeg+NCRp4hbO2S7r3/Iyywhi0qwyweq93//46y0P+7tGKdyF4/kv7XYp7qhoxzNQpWnkSujyaPfg+wZKWBf9iGfQ1LWJsMQFPq20plF0j3Nj4UcnlcRBv9MxEwSNabPsHcbBf+qysq9c6H4dT6WjMnCLYXpw2ADqwOIZwkGZ2HmnsvSsoTU7Sj59UPSkte668vM1VLL72e+Ig" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="completed keyexchange" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=debug msg="completed challenge" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=info msg="authenticated AP" username="31************************zq" Oct 11 22:45:01 volumiopc go-librespot[453131]: time="2026-10-11T22:45:01+03:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 22:45:01 volumiopc volumio[1024]: info: Connection to go-librespot Websocket closed Oct 11 22:45:01 volumiopc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 22:45:01 volumiopc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 22:45:04 volumiopc volumio[1024]: info: Getting Spotify volume Oct 11 22:45:04 volumiopc volumio[1024]: error: Failed to get Spotify volume from local API: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 22:45:04 volumiopc volumio[1024]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 11 22:45:04 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:04 volumiopc volumio[1024]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 11 22:45:04 volumiopc volumio[1024]: info: Initializing connection to go-librespot Websocket Oct 11 22:45:04 volumiopc volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 22:45:05 volumiopc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16567. Oct 11 22:45:05 volumiopc systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:05 volumiopc systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:05 volumiopc go-librespot[453162]: go-librespot daemon starting... Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=info msg="running go-librespot 0.9.1" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="app state loaded" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=info msg="zeroconf server listening on port 43515" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=info msg="loading previously persisted zeroconf credentials" username="31************************zq" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="obtained new client token: AAGTPlyJmB6q+DJQrvmTzT5xgfDzkCtqeV+GqXrK9v0ELaRIkZuCJovnTYvUuCxYyptSXCl2Nlf8d2A9nuPJE10JH1EB9I5bEuujJrKhNUXDW0ChvZ9QstA09xXs+szMtncnBIJ20ixoUrHESm/4G2YU5uTjqQ+OGO3EcpHcETA+stYHMbZGS/4qS+OubLrQphWhwIUby1aJm+UIsa3sPwyB5sqV5Hu0rDg05hSA2c4IJWRAohjtOeL+" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="completed keyexchange" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=debug msg="completed challenge" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=info msg="authenticated AP" username="31************************zq" Oct 11 22:45:05 volumiopc go-librespot[453163]: time="2026-10-11T22:45:05+03:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 22:45:05 volumiopc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 22:45:05 volumiopc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 22:45:06 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:07 volumiopc volumio[1024]: info: Initializing connection to go-librespot Websocket Oct 11 22:45:07 volumiopc volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 22:45:08 volumiopc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16568. Oct 11 22:45:08 volumiopc systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:08 volumiopc systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:08 volumiopc go-librespot[453178]: go-librespot daemon starting... Oct 11 22:45:08 volumiopc go-librespot[453179]: time="2026-10-11T22:45:08+03:00" level=info msg="running go-librespot 0.9.1" Oct 11 22:45:08 volumiopc go-librespot[453179]: time="2026-10-11T22:45:08+03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 22:45:08 volumiopc go-librespot[453179]: time="2026-10-11T22:45:08+03:00" level=debug msg="app state loaded" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=info msg="zeroconf server listening on port 46473" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=info msg="loading previously persisted zeroconf credentials" username="31************************zq" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=debug msg="obtained new client token: AAGyWLRJ2Uq/b2o976coF6EDLGZWyKTBOsBBZ822slQzeQXvwBy9PyDKXe+D8fA9ZXwFs1I8fFWV98au0EpABkd25QO5DSaQ1DGSzr2YYwvL7VDCuAoXdBH5zqAxp18zhwbXuZ9y37wa/ALKpy6BC1TwxMm6rwkJUfvcTYN9zZgCtYoKQ1RgDA9SdjtB/h4tyLVLlaHiZpW1YzhWAfNgQ/7XJNLtSMR/3CfXR1XT0Bi23O71D8G97g==" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=debug msg="completed keyexchange" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=debug msg="completed challenge" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=info msg="authenticated AP" username="31************************zq" Oct 11 22:45:09 volumiopc go-librespot[453179]: time="2026-10-11T22:45:09+03:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 22:45:09 volumiopc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 22:45:09 volumiopc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 22:45:10 volumiopc volumio[1024]: info: Initializing connection to go-librespot Websocket Oct 11 22:45:10 volumiopc volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 22:45:12 volumiopc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16569. Oct 11 22:45:12 volumiopc systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:12 volumiopc systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 22:45:12 volumiopc go-librespot[453197]: go-librespot daemon starting... Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=info msg="running go-librespot 0.9.1" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=debug msg="app state loaded" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=info msg="zeroconf server listening on port 38333" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=info msg="loading previously persisted zeroconf credentials" username="31************************zq" Oct 11 22:45:12 volumiopc go-librespot[453198]: time="2026-10-11T22:45:12+03:00" level=debug msg="obtained new client token: AAGp5ljwyU0RTSs+4k9ABfBEzFc2C8RSn0GS7qqNqXckWv1bjg2vavAzp289XDI3OdFg5Q/RAh6l2gqU+CiwQMnn+PJqqtMR80vf4WJqsAMDDy583Ob2KNkP9nOrhZoOnqmm+9NcoAiDceshgyfN6O5BLl5wSyvC3HqBuWTaWwQ7mi2PIbMoSHKwYtUc6WdbYqdl1YgX29a+z8lJ6EPC+H41A1y7E55GW6TyQX59YpJG7eS27ymSQ7NK" Oct 11 22:45:13 volumiopc go-librespot[453198]: time="2026-10-11T22:45:13+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 11 22:45:13 volumiopc go-librespot[453198]: time="2026-10-11T22:45:13+03:00" level=debug msg="avahi service \"VolumioPC\" established" Oct 11 22:45:13 volumiopc volumio[1024]: info: Initializing connection to go-librespot Websocket Oct 11 22:45:13 volumiopc go-librespot[453198]: time="2026-10-11T22:45:13+03:00" level=debug msg="new websocket client" Oct 11 22:45:13 volumiopc volumio[1024]: info: Connection to go-librespot Websocket established Oct 11 22:45:16 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:16 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:45:16 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:45:17 volumiopc volumio[1024]: info: Getting Spotify volume Oct 11 22:45:17 volumiopc volumio[1024]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 11 22:45:17 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:17 volumiopc volumio[1024]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 11 22:45:26 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:36 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:36 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:45:36 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:45:46 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:56 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:45:56 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:45:56 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:46:06 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:46:16 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:46:16 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:46:16 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:46:26 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:46:36 volumiopc volumio[1024]: info: CoreCommandRouter::volumioGetState Oct 11 22:46:36 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:46:36 volumiopc volumio[1024]: info: Listing playlists Oct 11 22:46:43 volumiopc volumio[1024]: info: [ytcr] Refresh session PO token Oct 11 22:46:43 volumiopc volumio[1024]: info: [ytcr] Obtained session PO token using visitorData (expires in 43101 seconds) Oct 11 22:46:43 volumiopc volumio[1024]: info: [ytcr] Going to refresh session PO token in 43001 seconds Oct 11 22:46:43 volumiopc volumio[1024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 22:46:43 volumiopc volumio[1024]: TypeError: Chaining cycle detected for promise # Oct 11 22:46:43 volumiopc volumio[1024]: at InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:187:12) Oct 11 22:46:43 volumiopc volumio[1024]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 11 22:46:43 volumiopc volumio[1024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 22:46:43 volumiopc sudo[453483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 22:45' Oct 11 22:46:43 volumiopc sudo[453483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="976b86a0f4b6665e5d00be3942489f2a9db63ec5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="865f53b4d15bf520d4a29b69a9510ae0fd92a621" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 9 16:02:03 UTC 2026" VOLUMIO_VERSION="4.225" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="d6cd26fc47e331aa7d590a3f1e4adcd9"