Mar 27 03:09:00 volumio go-librespot[152148]: time="2026-03-27T03:09:00Z" level=trace msg="received accesspoint ping" Mar 27 03:09:00 volumio go-librespot[152148]: time="2026-03-27T03:09:00Z" level=trace msg="received accesspoint pong ack" Mar 27 03:09:08 volumio go-librespot[152148]: time="2026-03-27T03:09:08Z" level=debug msg="fetched chunk 8/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:09:14 volumio go-librespot[152148]: time="2026-03-27T03:09:14Z" level=trace msg="sent dealer ping" Mar 27 03:09:14 volumio go-librespot[152148]: time="2026-03-27T03:09:14Z" level=trace msg="received dealer pong" Mar 27 03:09:22 volumio go-librespot[152148]: time="2026-03-27T03:09:22Z" level=debug msg="fetched chunk 9/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:09:35 volumio go-librespot[152148]: time="2026-03-27T03:09:35Z" level=debug msg="fetched chunk 10/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:09:37 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 03:09:37 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 27 03:09:37 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 27 03:09:37 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 27 03:09:37 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 27 03:09:37 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 27 03:09:37 volumio welcome[177262]: Resolved ip:[1] 192.168.2.17 Mar 27 03:09:37 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 27 03:09:37 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 03:09:38 volumio volumio[1084]: info: Received Get System Info Mar 27 03:09:38 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 03:09:38 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 03:09:38 volumio volumio[1084]: info: Discovery: Getting this device information Mar 27 03:09:38 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState Mar 27 03:09:38 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 03:09:38 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 03:09:38 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 03:09:39 volumio volumio5-onboarding[2081]: time=2026-03-27T03:09:39.216Z level=INFO msg="service successfully established" component=discovery/localnet Mar 27 03:09:44 volumio go-librespot[152148]: time="2026-03-27T03:09:44Z" level=trace msg="sent dealer ping" Mar 27 03:09:44 volumio go-librespot[152148]: time="2026-03-27T03:09:44Z" level=trace msg="received dealer pong" Mar 27 03:09:48 volumio go-librespot[152148]: time="2026-03-27T03:09:48Z" level=debug msg="fetched chunk 11/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:10:03 volumio go-librespot[152148]: time="2026-03-27T03:10:03Z" level=debug msg="fetched chunk 12/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:10:14 volumio go-librespot[152148]: time="2026-03-27T03:10:14Z" level=trace msg="sent dealer ping" Mar 27 03:10:14 volumio go-librespot[152148]: time="2026-03-27T03:10:14Z" level=trace msg="received dealer pong" Mar 27 03:10:16 volumio go-librespot[152148]: time="2026-03-27T03:10:16Z" level=debug msg="fetched chunk 13/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:10:21 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 03:10:21 volumio volumio-remote-updater[789]: Test mode disabled Mar 27 03:10:21 volumio volumio-remote-updater[789]: Alpha mode disabled Mar 27 03:10:21 volumio volumio-remote-updater[789]: Alpha legacy test mode disabled Mar 27 03:10:21 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 27 03:10:26 volumio volumio[1084]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 27 03:10:26 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 27 03:10:30 volumio go-librespot[152148]: time="2026-03-27T03:10:30Z" level=debug msg="fetched chunk 14/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:10:44 volumio go-librespot[152148]: time="2026-03-27T03:10:44Z" level=trace msg="sent dealer ping" Mar 27 03:10:44 volumio go-librespot[152148]: time="2026-03-27T03:10:44Z" level=trace msg="received dealer pong" Mar 27 03:10:44 volumio go-librespot[152148]: time="2026-03-27T03:10:44Z" level=debug msg="fetched chunk 15/20, size: 524288" uri="spotify:track:6foRtB5hAqJQuya1QWaf1Y" Mar 27 03:10:48 volumio volumio[1084]: info: [youtube2] Refresh session PO token Mar 27 03:10:48 volumio volumio[1084]: info: [youtube2] Obtained session PO token using datasyncIdToken (expires in 43111 seconds) Mar 27 03:10:48 volumio volumio[1084]: info: [youtube2] Going to refresh session PO token in 43011 seconds Mar 27 03:10:48 volumio volumio[1084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 03:10:48 volumio volumio[1084]: TypeError: Chaining cycle detected for promise # Mar 27 03:10:48 volumio volumio[1084]: at InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:187:12) Mar 27 03:10:48 volumio volumio[1084]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 27 03:10:48 volumio volumio[1084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 03:10:48 volumio sudo[177473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 03:09' Mar 27 03:10:48 volumio sudo[177473]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 17:11:42 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="21a5161aef4cb85a1f93b140ccadc4f1"