Mar 24 19:08:12 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Mar 24 19:08:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 19:08:13 volumio volumio[1099]: info: Received Get System Info Mar 24 19:08:13 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 19:08:13 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 19:08:13 volumio volumio[1099]: info: Discovery: Getting this device information Mar 24 19:08:13 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Mar 24 19:08:13 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Mar 24 19:08:13 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 19:08:26 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-DISCONNECTED bssid=60:32:b1:3a:6c:cf reason=0 locally_generated=1 Mar 24 19:08:26 volumio dhcpcd[661]: wlan0: carrier lost Mar 24 19:08:26 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP Mar 24 19:08:26 volumio avahi-daemon[1071]: Withdrawing address record for 192.168.3.178 on wlan0. Mar 24 19:08:26 volumio avahi-daemon[1071]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.178. Mar 24 19:08:26 volumio avahi-daemon[1071]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 24 19:08:26 volumio dhcpcd[661]: wlan0: deleting route to 192.168.3.0/24 Mar 24 19:08:26 volumio dhcpcd[661]: wlan0: deleting default route via 192.168.3.1 Mar 24 19:08:26 volumio volumio[1099]: info: Discovery: A device disappeared from network Mar 24 19:08:26 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Mar 24 19:08:26 volumio volumio[1099]: info: Discovery: Device volumio disappeared from network Mar 24 19:08:26 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:26 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Mar 24 19:08:26 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 24 19:08:26 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 24 19:08:26 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 24 19:08:26 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 24 19:08:26 volumio welcome[2307]: Resolved ip:[0] Mar 24 19:08:26 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 24 19:08:26 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:27 volumio wpa_supplicant[1002]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Mar 24 19:08:27 volumio wpa_supplicant[1002]: wlan0: Trying to associate with 60:32:b1:3a:6c:cf (SSID='TP-Link_6CD0' freq=2417 MHz) Mar 24 19:08:27 volumio wpa_supplicant[1002]: wlan0: Associated with 60:32:b1:3a:6c:cf Mar 24 19:08:27 volumio wpa_supplicant[1002]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Mar 24 19:08:27 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 24 19:08:27 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Mar 24 19:08:27 volumio wpa_supplicant[1002]: wlan0: WPA: Key negotiation completed with 60:32:b1:3a:6c:cf [PTK=CCMP GTK=CCMP] Mar 24 19:08:27 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:32:b1:3a:6c:cf completed [id=0 id_str=] Mar 24 19:08:27 volumio dhcpcd[661]: wlan0: carrier acquired Mar 24 19:08:27 volumio dhcpcd[661]: wlan0: IAID b0:08:2b:38 Mar 24 19:08:27 volumio dhcpcd[661]: wlan0: soliciting an IPv6 router Mar 24 19:08:28 volumio dhcpcd[661]: wlan0: rebinding lease of 192.168.3.178 Mar 24 19:08:29 volumio ntpd[876]: IO: Deleting interface #3 wlan0, 192.168.3.178#123, interface stats: received=191, sent=192, dropped=0, active_time=496 secs Mar 24 19:08:29 volumio ntpd[876]: PROTO: 85.121.50.82 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 129.250.35.251 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 103.131.151.30 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 43.167.237.61 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 122.215.240.52 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 45.77.20.103 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 139.162.81.45 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 172.105.192.74 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 142.91.105.55 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 142.91.108.61 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 108.160.132.224 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 160.16.113.133 unlink local addr 192.168.3.178 -> Mar 24 19:08:29 volumio ntpd[876]: PROTO: 208.88.66.8 unlink local addr 192.168.3.178 -> Mar 24 19:08:33 volumio dhcpcd[661]: wlan0: probing for an IPv4LL address Mar 24 19:08:33 volumio dhcpcd[661]: wlan0: DHCP lease expired Mar 24 19:08:33 volumio dhcpcd[661]: wlan0: soliciting a DHCP lease Mar 24 19:08:35 volumio volumio[1099]: info: Volumio Network Manager: Network status updated: 0 Mar 24 19:08:38 volumio dhcpcd[661]: wlan0: using IPv4LL address 169.254.101.132 Mar 24 19:08:38 volumio avahi-daemon[1071]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.101.132. Mar 24 19:08:38 volumio avahi-daemon[1071]: New relevant interface wlan0.IPv4 for mDNS. Mar 24 19:08:38 volumio avahi-daemon[1071]: Registering new address record for 169.254.101.132 on wlan0.IPv4. Mar 24 19:08:38 volumio dhcpcd[661]: wlan0: adding route to 169.254.0.0/16 Mar 24 19:08:38 volumio dhcpcd[661]: wlan0: adding default route Mar 24 19:08:38 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Mar 24 19:08:38 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:38 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Mar 24 19:08:38 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 24 19:08:38 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 24 19:08:38 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 24 19:08:38 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 24 19:08:38 volumio welcome[2347]: Resolved ip:[1] 169.254.101.132 Mar 24 19:08:38 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 24 19:08:38 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:40 volumio ntpd[876]: IO: Listen normally on 4 wlan0 169.254.101.132:123 Mar 24 19:08:40 volumio ntpd[876]: IO: new interface(s) found: waking up resolver Mar 24 19:08:43 volumio dhcpcd[661]: wlan0: using IPv4LL address 169.254.101.132 Mar 24 19:08:45 volumio volumio[1099]: info: Volumio Network Manager: Network status updated: 2 Mar 24 19:08:46 volumio dhcpcd[661]: wlan0: offered 192.168.3.178 from 192.168.3.1 Mar 24 19:08:46 volumio dhcpcd[661]: wlan0: probing address 192.168.3.178/24 Mar 24 19:08:51 volumio dhcpcd[661]: wlan0: leased 192.168.3.178 for 7200 seconds Mar 24 19:08:51 volumio avahi-daemon[1071]: Registering new address record for 192.168.3.178 on wlan0.IPv4. Mar 24 19:08:51 volumio dhcpcd[661]: wlan0: adding route to 192.168.3.0/24 Mar 24 19:08:51 volumio dhcpcd[661]: wlan0: changing default route via 192.168.3.1 Mar 24 19:08:51 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Mar 24 19:08:51 volumio avahi-daemon[1071]: Withdrawing address record for 169.254.101.132 on wlan0. Mar 24 19:08:51 volumio avahi-daemon[1071]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.101.132. Mar 24 19:08:51 volumio avahi-daemon[1071]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.178. Mar 24 19:08:51 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Mar 24 19:08:51 volumio dhcpcd[661]: wlan0: deleting route to 169.254.0.0/16 Mar 24 19:08:51 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Mar 24 19:08:51 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:51 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Mar 24 19:08:51 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 24 19:08:51 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 24 19:08:51 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 24 19:08:51 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 24 19:08:51 volumio welcome[2414]: Resolved ip:[1] 192.168.3.178 Mar 24 19:08:51 volumio dbus-daemon[574]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.35' (uid=0 pid=2412 comm="timedatectl show --property=NTPSynchronized --valu") Mar 24 19:08:51 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Mar 24 19:08:51 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 24 19:08:51 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:51 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:51 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Mar 24 19:08:51 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 24 19:08:51 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 24 19:08:51 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 24 19:08:51 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 24 19:08:51 volumio welcome[2421]: Resolved ip:[1] 192.168.3.178 Mar 24 19:08:52 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 24 19:08:52 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 19:08:52 volumio dbus-daemon[574]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 24 19:08:52 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Mar 24 19:08:52 volumio setdatetime-helper.sh[2395]: Time is already synchronized. Mar 24 19:08:52 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Mar 24 19:08:52 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Mar 24 19:08:53 volumio ntpd[876]: IO: Listen normally on 5 wlan0 192.168.3.178:123 Mar 24 19:08:53 volumio ntpd[876]: IO: Deleting interface #4 wlan0, 169.254.101.132#123, interface stats: received=0, sent=6, dropped=1, active_time=13 secs Mar 24 19:08:53 volumio ntpd[876]: PROTO: 208.88.66.8 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 160.16.113.133 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 108.160.132.224 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 142.91.108.61 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 142.91.105.55 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 172.105.192.74 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 139.162.81.45 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 45.77.20.103 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 122.215.240.52 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 43.167.237.61 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 103.131.151.30 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 129.250.35.251 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: PROTO: 85.121.50.82 unlink local addr 169.254.101.132 -> Mar 24 19:08:53 volumio ntpd[876]: IO: new interface(s) found: waking up resolver Mar 24 19:08:53 volumio volumio[1099]: info: Discovery: adding 4afee536-7b59-4262-814d-ad7b6e219ab7 Mar 24 19:08:53 volumio volumio[1099]: info: Discovery: Found device Volumio Mar 24 19:08:53 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Mar 24 19:08:53 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Mar 24 19:09:22 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Mar 24 19:09:26 volumio volumio[1099]: info: CALLMETHOD: music_service ytmusic configSaveAccount [object Object] Mar 24 19:09:26 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveAccount Mar 24 19:09:26 volumio volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 19:09:26 volumio volumio[1099]: Error: VM operation timed out Mar 24 19:09:26 volumio volumio[1099]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:93:15) Mar 24 19:09:26 volumio volumio[1099]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 24 19:09:26 volumio volumio[1099]: at async InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:209:31) Mar 24 19:09:26 volumio volumio[1099]: at async InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:172:21) Mar 24 19:09:26 volumio volumio[1099]: at async InnertubeWrapper._InnertubeWrapper_init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:164:5) Mar 24 19:09:26 volumio volumio[1099]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:72:9) Mar 24 19:09:26 volumio volumio[1099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 19:09:27 volumio sudo[2500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 19:08' Mar 24 19:09:27 volumio sudo[2500]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"