Jan 12 04:06:14 volumio wpa_supplicant[1000]: wlan0: CTRL-EVENT-DISCONNECTED bssid=60:32:b1:3a:6c:cf reason=0 locally_generated=1 Jan 12 04:06:14 volumio dhcpcd[695]: wlan0: carrier lost Jan 12 04:06:14 volumio wpa_supplicant[1000]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP Jan 12 04:06:14 volumio avahi-daemon[1083]: Withdrawing address record for 192.168.3.178 on wlan0. Jan 12 04:06:14 volumio avahi-daemon[1083]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.178. Jan 12 04:06:14 volumio dhcpcd[695]: wlan0: deleting route to 192.168.3.0/24 Jan 12 04:06:14 volumio dhcpcd[695]: wlan0: deleting default route via 192.168.3.1 Jan 12 04:06:14 volumio avahi-daemon[1083]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 12 04:06:14 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Jan 12 04:06:14 volumio volumio[11646]: info: Discovery: A device disappeared from network Jan 12 04:06:14 volumio volumio[11646]: info: Discovery: Device volumio disappeared from network Jan 12 04:06:14 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:14 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 12 04:06:14 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 12 04:06:14 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 12 04:06:14 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 12 04:06:14 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 12 04:06:14 volumio welcome[28725]: Resolved ip:[0] Jan 12 04:06:14 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 12 04:06:14 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:14 volumio wpa_supplicant[1000]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jan 12 04:06:14 volumio wpa_supplicant[1000]: wlan0: Trying to associate with 60:32:b1:3a:6c:cf (SSID='TP-Link_6CD0' freq=2417 MHz) Jan 12 04:06:15 volumio wpa_supplicant[1000]: wlan0: Associated with 60:32:b1:3a:6c:cf Jan 12 04:06:15 volumio wpa_supplicant[1000]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jan 12 04:06:15 volumio wpa_supplicant[1000]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 12 04:06:15 volumio wpa_supplicant[1000]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Jan 12 04:06:15 volumio wpa_supplicant[1000]: wlan0: WPA: Key negotiation completed with 60:32:b1:3a:6c:cf [PTK=CCMP GTK=CCMP] Jan 12 04:06:15 volumio wpa_supplicant[1000]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:32:b1:3a:6c:cf completed [id=0 id_str=] Jan 12 04:06:15 volumio dhcpcd[695]: wlan0: carrier acquired Jan 12 04:06:15 volumio dhcpcd[695]: wlan0: IAID b0:08:2b:38 Jan 12 04:06:15 volumio dhcpcd[695]: wlan0: soliciting an IPv6 router Jan 12 04:06:16 volumio ntpd[866]: IO: Deleting interface #497 wlan0, 192.168.3.178#123, interface stats: received=27, sent=27, dropped=0, active_time=2076 secs Jan 12 04:06:16 volumio ntpd[866]: PROTO: 85.121.50.82 unlink local addr 192.168.3.178 -> Jan 12 04:06:16 volumio ntpd[866]: PROTO: 167.179.119.205 unlink local addr 192.168.3.178 -> Jan 12 04:06:16 volumio ntpd[866]: PROTO: 142.91.108.61 unlink local addr 192.168.3.178 -> Jan 12 04:06:16 volumio ntpd[866]: PROTO: 160.16.113.133 unlink local addr 192.168.3.178 -> Jan 12 04:06:16 volumio ntpd[866]: PROTO: 160.25.135.55 unlink local addr 192.168.3.178 -> Jan 12 04:06:16 volumio ntpd[866]: PROTO: 139.162.81.45 unlink local addr 192.168.3.178 -> Jan 12 04:06:16 volumio ntpd[866]: PROTO: 45.77.20.103 unlink local addr 192.168.3.178 -> Jan 12 04:06:16 volumio dhcpcd[695]: wlan0: rebinding lease of 192.168.3.178 Jan 12 04:06:17 volumio volumio[11646]: error: [ytmusic] Innertube support service: node:internal/deps/undici/undici:11576 Jan 12 04:06:17 volumio volumio[11646]: Error.captureStackTrace(err, this); Jan 12 04:06:17 volumio volumio[11646]: ^ Jan 12 04:06:17 volumio volumio[11646]: TypeError: fetch failed Jan 12 04:06:17 volumio volumio[11646]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Jan 12 04:06:17 volumio volumio[11646]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 12 04:06:17 volumio volumio[11646]: at async createPoTokenMinter (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/PoToken.js:14:31) Jan 12 04:06:17 volumio volumio[11646]: at async InnertubeSupportService._InnertubeSupportService_createMinter (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Service.js:112:26) { Jan 12 04:06:17 volumio volumio[11646]: cause: Error: getaddrinfo ENOTFOUND www.youtube.com Jan 12 04:06:17 volumio volumio[11646]: at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:118:26) { Jan 12 04:06:17 volumio volumio[11646]: errno: -3007, Jan 12 04:06:17 volumio volumio[11646]: code: 'ENOTFOUND', Jan 12 04:06:17 volumio volumio[11646]: syscall: 'getaddrinfo', Jan 12 04:06:17 volumio volumio[11646]: hostname: 'www.youtube.com' Jan 12 04:06:17 volumio volumio[11646]: } Jan 12 04:06:17 volumio volumio[11646]: } Jan 12 04:06:17 volumio volumio[11646]: Node.js v20.5.1 Jan 12 04:06:17 volumio volumio[11646]: info: [ytmusic] Innertube support service: Process closed with code 1 Jan 12 04:06:17 volumio volumio[11646]: info: [ytmusic] Innertube support service: Stopped Jan 12 04:06:18 volumio volumio[11646]: info: Volumio Network Manager: Network status updated: 0 Jan 12 04:06:21 volumio dhcpcd[695]: wlan0: probing for an IPv4LL address Jan 12 04:06:21 volumio dhcpcd[695]: wlan0: DHCP lease expired Jan 12 04:06:21 volumio dhcpcd[695]: wlan0: soliciting a DHCP lease Jan 12 04:06:24 volumio dhcpcd[695]: wlan0: offered 192.168.3.178 from 192.168.3.1 Jan 12 04:06:24 volumio dhcpcd[695]: wlan0: probing address 192.168.3.178/24 Jan 12 04:06:26 volumio dhcpcd[695]: wlan0: using IPv4LL address 169.254.101.132 Jan 12 04:06:26 volumio avahi-daemon[1083]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.101.132. Jan 12 04:06:26 volumio avahi-daemon[1083]: New relevant interface wlan0.IPv4 for mDNS. Jan 12 04:06:26 volumio avahi-daemon[1083]: Registering new address record for 169.254.101.132 on wlan0.IPv4. Jan 12 04:06:26 volumio dhcpcd[695]: wlan0: adding route to 169.254.0.0/16 Jan 12 04:06:26 volumio dhcpcd[695]: wlan0: adding default route Jan 12 04:06:26 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Jan 12 04:06:26 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:26 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 12 04:06:26 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 12 04:06:26 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 12 04:06:26 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 12 04:06:26 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 12 04:06:26 volumio welcome[28766]: Resolved ip:[1] 169.254.101.132 Jan 12 04:06:26 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 12 04:06:26 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:27 volumio ntpd[866]: IO: Listen normally on 498 wlan0 169.254.101.132:123 Jan 12 04:06:27 volumio ntpd[866]: IO: new interface(s) found: waking up resolver Jan 12 04:06:28 volumio volumio[11646]: info: Volumio Network Manager: Network status updated: 2 Jan 12 04:06:29 volumio dhcpcd[695]: wlan0: leased 192.168.3.178 for 7200 seconds Jan 12 04:06:29 volumio avahi-daemon[1083]: Registering new address record for 192.168.3.178 on wlan0.IPv4. Jan 12 04:06:29 volumio dhcpcd[695]: wlan0: adding route to 192.168.3.0/24 Jan 12 04:06:29 volumio dhcpcd[695]: wlan0: changing default route via 192.168.3.1 Jan 12 04:06:29 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Jan 12 04:06:29 volumio avahi-daemon[1083]: Withdrawing address record for 169.254.101.132 on wlan0. Jan 12 04:06:29 volumio avahi-daemon[1083]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.101.132. Jan 12 04:06:29 volumio dhcpcd[695]: wlan0: deleting route to 169.254.0.0/16 Jan 12 04:06:29 volumio avahi-daemon[1083]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.178. Jan 12 04:06:29 volumio kernel: ieee80211 phy1: brcmf_inetaddr_changed: fail to get arp ip table err:-52 Jan 12 04:06:29 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jan 12 04:06:29 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:29 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 12 04:06:29 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 12 04:06:29 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 12 04:06:29 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 12 04:06:29 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 12 04:06:29 volumio welcome[28822]: Resolved ip:[1] 192.168.3.178 Jan 12 04:06:29 volumio dbus-daemon[617]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.58715' (uid=0 pid=28820 comm="timedatectl show --property=NTPSynchronized --valu") Jan 12 04:06:29 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 12 04:06:29 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:29 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jan 12 04:06:29 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:29 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 12 04:06:29 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 12 04:06:29 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 12 04:06:29 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 12 04:06:29 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 12 04:06:29 volumio welcome[28829]: Resolved ip:[1] 192.168.3.178 Jan 12 04:06:29 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 12 04:06:29 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 12 04:06:29 volumio dbus-daemon[617]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 12 04:06:29 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jan 12 04:06:29 volumio setdatetime-helper.sh[28803]: Time is already synchronized. Jan 12 04:06:29 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jan 12 04:06:29 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jan 12 04:06:30 volumio ntpd[866]: IO: Listen normally on 499 wlan0 192.168.3.178:123 Jan 12 04:06:30 volumio ntpd[866]: IO: Deleting interface #498 wlan0, 169.254.101.132#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs Jan 12 04:06:30 volumio ntpd[866]: PROTO: 85.121.50.82 unlink local addr 169.254.101.132 -> Jan 12 04:06:30 volumio ntpd[866]: PROTO: 167.179.119.205 unlink local addr 169.254.101.132 -> Jan 12 04:06:30 volumio ntpd[866]: PROTO: 142.91.108.61 unlink local addr 169.254.101.132 -> Jan 12 04:06:30 volumio ntpd[866]: PROTO: 160.16.113.133 unlink local addr 169.254.101.132 -> Jan 12 04:06:30 volumio ntpd[866]: PROTO: 160.25.135.55 unlink local addr 169.254.101.132 -> Jan 12 04:06:30 volumio ntpd[866]: PROTO: 139.162.81.45 unlink local addr 169.254.101.132 -> Jan 12 04:06:30 volumio ntpd[866]: PROTO: 45.77.20.103 unlink local addr 169.254.101.132 -> Jan 12 04:06:30 volumio ntpd[866]: IO: new interface(s) found: waking up resolver Jan 12 04:06:32 volumio volumio[11646]: info: Discovery: adding 4afee536-7b59-4262-814d-ad7b6e219ab7 Jan 12 04:06:32 volumio volumio[11646]: info: Discovery: Found device Volumio Jan 12 04:06:32 volumio volumio[11646]: info: CoreCommandRouter::volumioGetState Jan 12 04:06:59 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jan 12 04:07:56 volumio volumio[11646]: info: [ytmusic] Refresh session PO token Jan 12 04:07:56 volumio volumio[11646]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 04:07:56 volumio volumio[11646]: Error: Innertube support service not started Jan 12 04:07:56 volumio volumio[11646]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:96:19) Jan 12 04:07:56 volumio volumio[11646]: at InnertubeWrapper._InnertubeWrapper_generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:185:42) Jan 12 04:07:56 volumio volumio[11646]: at InnertubeWrapper._InnertubeWrapper_initInnertube (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:205:135) Jan 12 04:07:56 volumio volumio[11646]: at InnertubeWrapper._InnertubeWrapper_refreshSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:238:166) Jan 12 04:07:56 volumio volumio[11646]: at Timeout. (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:231:202) Jan 12 04:07:56 volumio volumio[11646]: at listOnTimeout (node:internal/timers:573:17) Jan 12 04:07:56 volumio volumio[11646]: at process.processTimers (node:internal/timers:514:7) Jan 12 04:07:56 volumio volumio[11646]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 04:07:57 volumio sudo[28967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 04:06' Jan 12 04:07:57 volumio sudo[28967]: 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"