Jun 01 19:33:06 rpi-004-blkcasevolumio wpa_supplicant[1157]: wlan0: WNM: Disassociation Imminent - Disassociation Timer 200 Jun 01 19:33:06 rpi-004-blkcasevolumio wpa_supplicant[1157]: wlan0: WNM: Preferred List Available Jun 01 19:33:07 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:07-03:00" level=debug msg="fetched chunk 9/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:33:08 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:08-03:00" level=trace msg="sent dealer ping" Jun 01 19:33:09 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:09-03:00" level=trace msg="received dealer pong" Jun 01 19:33:09 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:09-03:00" level=trace msg="received accesspoint ping" Jun 01 19:33:09 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:09-03:00" level=trace msg="received accesspoint pong ack" Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: Received Get System Info Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: Discovery: Getting this device information Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::volumioGetState Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 19:33:11 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 19:33:11 rpi-004-blkcasevolumio volumio5-onboarding[1580]: time=2026-06-01T19:33:11.102-03:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%04,192.168.15.6:46974 @ 0x2d2c8a0" available=true connected=true macAddress=dc:a6:32:10:bd:0c ip4Address=192.168.15.175/24 ip6Address= ssid=OM Jun 01 19:33:11 rpi-004-blkcasevolumio volumio5-onboarding[1580]: time=2026-06-01T19:33:11.102-03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.15.6:46974 @ 0x2d2d170" available=true connected=true macAddress=dc:a6:32:10:bd:0c ip4Address=192.168.15.175/24 ip6Address= ssid=OM Jun 01 19:33:11 rpi-004-blkcasevolumio volumio5-onboarding[1580]: time=2026-06-01T19:33:11.963-03:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 01 19:33:17 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:17-03:00" level=debug msg="fetched chunk 10/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:33:27 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:27-03:00" level=debug msg="fetched chunk 11/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:33:37 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:37-03:00" level=debug msg="fetched chunk 12/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:33:38 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:38-03:00" level=trace msg="sent dealer ping" Jun 01 19:33:38 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:38-03:00" level=trace msg="received dealer pong" Jun 01 19:33:46 rpi-004-blkcasevolumio wpa_supplicant[1157]: wlan0: WNM: Disassociation Imminent - Disassociation Timer 200 Jun 01 19:33:46 rpi-004-blkcasevolumio wpa_supplicant[1157]: wlan0: WNM: Preferred List Available Jun 01 19:33:47 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:47-03:00" level=debug msg="fetched chunk 13/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: Received Get System Info Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: Discovery: Getting this device information Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::volumioGetState Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 19:33:50 rpi-004-blkcasevolumio volumio[4892]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 19:33:50 rpi-004-blkcasevolumio volumio5-onboarding[1580]: time=2026-06-01T19:33:50.788-03:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%04,192.168.15.6:46974 @ 0x2d2c8a0" available=true connected=true macAddress=dc:a6:32:10:bd:0c ip4Address=192.168.15.175/24 ip6Address= ssid=OM Jun 01 19:33:50 rpi-004-blkcasevolumio volumio5-onboarding[1580]: time=2026-06-01T19:33:50.788-03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.15.6:46974 @ 0x2d2d170" available=true connected=true macAddress=dc:a6:32:10:bd:0c ip4Address=192.168.15.175/24 ip6Address= ssid=OM Jun 01 19:33:51 rpi-004-blkcasevolumio volumio5-onboarding[1580]: time=2026-06-01T19:33:51.733-03:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 01 19:33:57 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:33:57-03:00" level=debug msg="fetched chunk 14/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:34:07 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:34:07-03:00" level=debug msg="fetched chunk 15/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:34:08 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:34:08-03:00" level=trace msg="sent dealer ping" Jun 01 19:34:08 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:34:08-03:00" level=trace msg="received dealer pong" Jun 01 19:34:18 rpi-004-blkcasevolumio go-librespot[5240]: time="2026-06-01T19:34:18-03:00" level=debug msg="fetched chunk 16/26, size: 524288" uri="spotify:track:7CdDIvw3jvjNaIs5m3mM4A" Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: info: [ytmusic] Refresh session PO token Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: info: [ytmusic] Obtained session PO token using visitorData (expires in 43102 seconds) Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: info: [ytmusic] Going to refresh session PO token in 43002 seconds Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: TypeError: Chaining cycle detected for promise # Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: at #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:128:21) Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 01 19:34:18 rpi-004-blkcasevolumio volumio[4892]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 19:34:19 rpi-004-blkcasevolumio sudo[9368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 19:33' Jun 01 19:34:19 rpi-004-blkcasevolumio sudo[9368]: 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"