Aug 31 18:07:00 volumio-kali kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled Aug 31 18:07:00 volumio-kali dhcpcd[738]: wlan0: waiting for carrier Aug 31 18:07:00 volumio-kali systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 31 18:07:00 volumio-kali avahi-daemon[670]: Service "Volumio Kali" (/services/volumio.service) successfully established. Aug 31 18:07:00 volumio-kali systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Aug 31 18:07:00 volumio-kali systemd[1]: Starting bluetooth.service - Bluetooth service... Aug 31 18:07:00 volumio-kali systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Aug 31 18:07:00 volumio-kali sshd[904]: Server listening on 0.0.0.0 port 22. Aug 31 18:07:00 volumio-kali sshd[904]: Server listening on :: port 22. Aug 31 18:07:00 volumio-kali systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Aug 31 18:07:00 volumio-kali kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 31 18:07:00 volumio-kali kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Aug 31 18:07:00 volumio-kali ntpd[859]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Aug 31 18:07:00 volumio-kali ifplugd(eth0)[882]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Aug 31 18:07:00 volumio-kali sh[956]: wlan0=wlan0 Aug 31 18:07:01 volumio-kali kernel: hwmon hwmon1: Voltage normalised Aug 31 18:07:01 volumio-kali bluetoothd[907]: Bluetooth daemon 5.83 Aug 31 18:07:01 volumio-kali systemd[1]: Started bluetooth.service - Bluetooth service. Aug 31 18:07:01 volumio-kali bluetoothd[907]: Starting SDP server Aug 31 18:07:01 volumio-kali bluetoothd[907]: Excluding (cli) sap Aug 31 18:07:01 volumio-kali bluetoothd[907]: Excluding (cli) scanparam Aug 31 18:07:01 volumio-kali bluetoothd[907]: Excluding (cli) midi Aug 31 18:07:01 volumio-kali bluetoothd[907]: Excluding (cli) battery Aug 31 18:07:01 volumio-kali systemd[1]: Starting bluealsa.service - BlueALSA service... Aug 31 18:07:01 volumio-kali kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 31 18:07:01 volumio-kali kernel: Bluetooth: BNEP filters: protocol multicast Aug 31 18:07:01 volumio-kali kernel: Bluetooth: BNEP socket layer initialized Aug 31 18:07:01 volumio-kali bluetoothd[907]: Bluetooth management interface 1.23 initialized Aug 31 18:07:01 volumio-kali dbus-daemon[672]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=907 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 31 18:07:01 volumio-kali bluetoothd[907]: Battery Provider Manager created Aug 31 18:07:01 volumio-kali kernel: Bluetooth: MGMT ver 1.23 Aug 31 18:07:01 volumio-kali kernel: NET: Registered PF_ALG protocol family Aug 31 18:07:01 volumio-kali systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 31 18:07:01 volumio-kali ntpd[859]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:01 volumio-kali dhcpcd[645]: eth0: offered 192.168.1.6 from 192.168.1.1 Aug 31 18:07:01 volumio-kali sh[638]: eth0: offered 192.168.1.6 from 192.168.1.1 Aug 31 18:07:01 volumio-kali ntpd[859]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 31 18:07:01 volumio-kali ntpd[859]: DNS: dns_check: DNS error: -11, System error Aug 31 18:07:01 volumio-kali ntpd[859]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Aug 31 18:07:01 volumio-kali kernel: cryptd: max_cpu_qlen set to 1000 Aug 31 18:07:01 volumio-kali dhcpcd[645]: eth0: probing address 192.168.1.6/24 Aug 31 18:07:01 volumio-kali sh[638]: eth0: probing address 192.168.1.6/24 Aug 31 18:07:01 volumio-kali dbus-daemon[672]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 31 18:07:01 volumio-kali systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 31 18:07:02 volumio-kali bluetoothd[907]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Aug 31 18:07:02 volumio-kali bluetoothd[907]: bap: Operation not supported (95) Aug 31 18:07:02 volumio-kali bluetoothd[907]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Aug 31 18:07:02 volumio-kali bluealsa[964]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Aug 31 18:07:02 volumio-kali bluealsa[964]: main.c:663: Starting main dispatching loop Aug 31 18:07:02 volumio-kali systemd[1]: Started bluealsa.service - BlueALSA service. Aug 31 18:07:02 volumio-kali systemd[1]: Reached target bluetooth.target - Bluetooth Support. Aug 31 18:07:02 volumio-kali bluealsa[964]: main.c:118: Acquired D-Bus service name: org.bluealsa Aug 31 18:07:02 volumio-kali bluealsa[964]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Aug 31 18:07:02 volumio-kali bluealsa[964]: bluez.c:153: Registering media application: /org/bluez/hci0 Aug 31 18:07:02 volumio-kali bluealsa[964]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Aug 31 18:07:02 volumio-kali bluealsa[964]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Aug 31 18:07:02 volumio-kali bluealsa[964]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 18:07:02 volumio-kali bluealsa[964]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 18:07:02 volumio-kali bluetoothd[907]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 18:07:02 volumio-kali bluetoothd[907]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 18:07:02 volumio-kali ntpd[859]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:02 volumio-kali ntpd[859]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 31 18:07:02 volumio-kali ntpd[859]: DNS: dns_check: DNS error: -11, System error Aug 31 18:07:02 volumio-kali ntpd[859]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Aug 31 18:07:02 volumio-kali bluetoothd[907]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 31 18:07:02 volumio-kali bluetoothd[907]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 31 18:07:02 volumio-kali systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Aug 31 18:07:02 volumio-kali systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Aug 31 18:07:03 volumio-kali kernel: hwmon hwmon1: Undervoltage detected! Aug 31 18:07:03 volumio-kali ntpd[859]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:03 volumio-kali ntpd[859]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 31 18:07:03 volumio-kali ntpd[859]: DNS: dns_check: DNS error: -11, System error Aug 31 18:07:03 volumio-kali ntpd[859]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Aug 31 18:07:03 volumio-kali nmbd[1007]: [2026/08/31 18:07:03.690105, 0] ../../source3/nmbd/nmbd.c:901(main) Aug 31 18:07:03 volumio-kali nmbd[1007]: nmbd version 4.17.8-Raspbian started. Aug 31 18:07:03 volumio-kali nmbd[1007]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 31 18:07:03 volumio-kali nmbd[1007]: [2026/08/31 18:07:03.703603, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 31 18:07:03 volumio-kali nmbd[1007]: started asyncdns process 1013 Aug 31 18:07:03 volumio-kali nmbd[1007]: [2026/08/31 18:07:03.707905, 0] ../../lib/util/become_daemon.c:150(daemon_status) Aug 31 18:07:03 volumio-kali nmbd[1007]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 31 18:07:03 volumio-kali nmbd[1007]: [2026/08/31 18:07:03.708170, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Aug 31 18:07:03 volumio-kali nmbd[1007]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 31 18:07:04 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:04] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 31 18:07:04 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:04] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 31 18:07:04 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:04] [error] handle_connect error: Connection refused Aug 31 18:07:04 volumio-kali ntpd[859]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:04 volumio-kali ntpd[859]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 31 18:07:04 volumio-kali ntpd[859]: DNS: dns_check: DNS error: -11, System error Aug 31 18:07:04 volumio-kali ntpd[859]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: === SNM TRANSITION === Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: New ethernet state: connected Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Single Network Mode: enabled Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: First start: yes Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: === END TRANSITION === Aug 31 18:07:04 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 31 18:07:04 volumio-kali systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 31 18:07:05 volumio-kali sudo[1036]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 31 18:07:05 volumio-kali sudo[1036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 18:07:05 volumio-kali sudo[1036]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:05 volumio-kali sudo[1038]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 31 18:07:05 volumio-kali sudo[1038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 18:07:06 volumio-kali sudo[1038]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:06 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Cleaning previous... Aug 31 18:07:06 volumio-kali sudo[1047]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 31 18:07:06 volumio-kali sudo[1047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 18:07:06 volumio-kali kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 31 18:07:06 volumio-kali sudo[1047]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:06 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 31 18:07:06 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 7ms Aug 31 18:07:06 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:7c:4a:52) Aug 31 18:07:06 volumio-kali sudo[1054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 31 18:07:06 volumio-kali sudo[1054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:06 volumio-kali sudo[1054]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:06 volumio-kali sudo[1062]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 31 18:07:06 volumio-kali sudo[1062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:07 volumio-kali dhcpcd[645]: eth0: leased 192.168.1.6 for 3600 seconds Aug 31 18:07:07 volumio-kali sh[638]: eth0: leased 192.168.1.6 for 3600 seconds Aug 31 18:07:07 volumio-kali sh[638]: eth0: adding route to 192.168.1.0/24 Aug 31 18:07:07 volumio-kali dhcpcd[645]: eth0: adding route to 192.168.1.0/24 Aug 31 18:07:07 volumio-kali avahi-daemon[670]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.6. Aug 31 18:07:07 volumio-kali avahi-daemon[670]: New relevant interface eth0.IPv4 for mDNS. Aug 31 18:07:07 volumio-kali avahi-daemon[670]: Registering new address record for 192.168.1.6 on eth0.IPv4. Aug 31 18:07:07 volumio-kali systemd[1]: welcome.service: Deactivated successfully. Aug 31 18:07:07 volumio-kali systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 31 18:07:07 volumio-kali systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 31 18:07:07 volumio-kali systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 31 18:07:07 volumio-kali systemd[1]: Started nmbd.service - Samba NMB Daemon. Aug 31 18:07:07 volumio-kali welcome[1087]: Resolved ip:[1] 192.168.1.6 Aug 31 18:07:07 volumio-kali systemd[1]: Starting winbind.service - Samba Winbind Daemon... Aug 31 18:07:07 volumio-kali sh[638]: Failed to try-restart systemd-timesyncd.service: Unit systemd-timesyncd.service not found. Aug 31 18:07:07 volumio-kali dhcpcd[645]: eth0: adding default route via 192.168.1.1 Aug 31 18:07:07 volumio-kali sh[638]: eth0: adding default route via 192.168.1.1 Aug 31 18:07:07 volumio-kali systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 31 18:07:07 volumio-kali sh[638]: forked to background, child pid 644 Aug 31 18:07:07 volumio-kali systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 31 18:07:07 volumio-kali dhcpcd[738]: eth0: offered 192.168.1.6 from 192.168.1.1 Aug 31 18:07:08 volumio-kali sh[1134]: eth0=eth0 Aug 31 18:07:08 volumio-kali ifplugd(eth0)[882]: client: ifup: interface eth0 already configured Aug 31 18:07:08 volumio-kali ifplugd(eth0)[882]: Program executed successfully. Aug 31 18:07:08 volumio-kali ifplugd[812]: Network Interface Plugging Daemon...start eth0...done. Aug 31 18:07:08 volumio-kali systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Aug 31 18:07:08 volumio-kali ntpd[859]: IO: Listen normally on 3 eth0 192.168.1.6:123 Aug 31 18:07:08 volumio-kali ntpd[859]: IO: new interface(s) found: waking up resolver Aug 31 18:07:08 volumio-kali ntpd[859]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:08 volumio-kali ntpd[859]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 31 18:07:08 volumio-kali ntpd[859]: DNS: Pool taking: 195.32.70.195 Aug 31 18:07:08 volumio-kali ntpd[859]: DNS: Pool taking: 204.216.214.76 Aug 31 18:07:08 volumio-kali ntpd[859]: DNS: Pool taking: 85.199.214.99 Aug 31 18:07:08 volumio-kali ntpd[859]: DNS: Pool taking: 95.110.135.141 Aug 31 18:07:08 volumio-kali ntpd[859]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 31 18:07:08 volumio-kali dhcpcd[738]: eth0: leased 192.168.1.6 for 3600 seconds Aug 31 18:07:08 volumio-kali dhcpcd[738]: eth0: adding route to 192.168.1.0/24 Aug 31 18:07:08 volumio-kali dhcpcd[738]: eth0: adding default route via 192.168.1.1 Aug 31 18:07:08 volumio-kali systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 31 18:07:08 volumio-kali systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Aug 31 18:07:08 volumio-kali systemd[1]: welcome.service: Deactivated successfully. Aug 31 18:07:08 volumio-kali systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 31 18:07:08 volumio-kali systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 31 18:07:08 volumio-kali winbindd[1128]: [2026/08/31 18:07:08.685300, 0] ../../source3/winbindd/winbindd.c:1440(main) Aug 31 18:07:08 volumio-kali winbindd[1128]: winbindd version 4.17.8-Raspbian started. Aug 31 18:07:08 volumio-kali winbindd[1128]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 31 18:07:08 volumio-kali winbindd[1128]: [2026/08/31 18:07:08.714651, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Aug 31 18:07:08 volumio-kali systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 31 18:07:08 volumio-kali winbindd[1128]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 31 18:07:08 volumio-kali welcome[1155]: Resolved ip:[1] 192.168.1.6 Aug 31 18:07:08 volumio-kali systemd[1]: Started winbind.service - Samba Winbind Daemon. Aug 31 18:07:08 volumio-kali systemd[1]: Starting smbd.service - Samba SMB Daemon... Aug 31 18:07:08 volumio-kali systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 31 18:07:08 volumio-kali systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 31 18:07:09 volumio-kali sudo[1062]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:09 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: IT Aug 31 18:07:09 volumio-kali sudo[1176]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IT Aug 31 18:07:09 volumio-kali sudo[1176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool skipping: 204.216.214.76 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool taking: 185.157.229.254 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool skipping: 85.199.214.99 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool taking: 162.159.200.1 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool taking: 2a05:5502:12:11::123 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool taking: 2a00:6d41:200:2::15 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool taking: 2a03:b020:0:403::254 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: Pool taking: 2a00:6d41:200:2::11 Aug 31 18:07:09 volumio-kali ntpd[859]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 31 18:07:09 volumio-kali sudo[1176]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:09 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: IT Aug 31 18:07:09 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Aug 31 18:07:09 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Aug 31 18:07:09 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Aug 31 18:07:32 volumio-kali systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 31 18:07:32 volumio-kali systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 31 18:07:32 volumio-kali systemd[1]: setdatetime-helper.service: Consumed 2.745s CPU time. Aug 31 18:07:32 volumio-kali smbd[1173]: [2026/08/31 18:07:32.311374, 0] ../../source3/smbd/server.c:1741(main) Aug 31 18:07:32 volumio-kali smbd[1173]: smbd version 4.17.8-Raspbian started. Aug 31 18:07:32 volumio-kali smbd[1173]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 31 18:07:32 volumio-kali sudo[1204]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 31 18:07:32 volumio-kali sudo[1204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 18:07:32 volumio-kali sudo[1204]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:32 volumio-kali sudo[1207]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 31 18:07:32 volumio-kali sudo[1207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 18:07:32 volumio-kali sudo[1207]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:32 volumio-kali wpa_supplicant[1210]: Successfully initialized wpa_supplicant Aug 31 18:07:32 volumio-kali ntpd[859]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:32 volumio-kali ntpd[859]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 31 18:07:32 volumio-kali ntpd[859]: DNS: Pool taking: 172.232.209.103 Aug 31 18:07:32 volumio-kali ntpd[859]: DNS: Pool taking: 93.94.88.50 Aug 31 18:07:32 volumio-kali ntpd[859]: DNS: Pool taking: 37.247.53.178 Aug 31 18:07:32 volumio-kali ntpd[859]: DNS: Pool taking: 93.94.88.51 Aug 31 18:07:32 volumio-kali ntpd[859]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 31 18:07:32 volumio-kali wpa_supplicant[1210]: nl80211: kernel reports: Registration to specific type not supported Aug 31 18:07:33 volumio-kali wpa_supplicant[1214]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Aug 31 18:07:33 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 28343ms Aug 31 18:07:33 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Aug 31 18:07:33 volumio-kali kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 31 18:07:33 volumio-kali wireless.js[685]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 31 18:07:33 volumio-kali systemd[1]: Started wireless.service - Wireless Services. Aug 31 18:07:33 volumio-kali systemd[1]: Started volumio.service - Volumio Backend Module. Aug 31 18:07:33 volumio-kali systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Aug 31 18:07:33 volumio-kali systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Aug 31 18:07:33 volumio-kali systemd[1]: Received SIGRTMIN+21 from PID 296 (plymouthd). Aug 31 18:07:33 volumio-kali systemd[1]: Received SIGRTMIN+21 from PID 296 (plymouthd). Aug 31 18:07:33 volumio-kali systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Aug 31 18:07:33 volumio-kali systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Aug 31 18:07:33 volumio-kali systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 31 18:07:33 volumio-kali systemd[1]: Reached target getty.target - Login Prompts. Aug 31 18:07:33 volumio-kali volumio-cpu-tweak[1233]: Setting RT Priority for mpd Aug 31 18:07:33 volumio-kali volumio-cpu-tweak[1248]: pid 35's current scheduling policy: SCHED_OTHER Aug 31 18:07:33 volumio-kali volumio-cpu-tweak[1248]: pid 35's current scheduling priority: 0 Aug 31 18:07:33 volumio-kali volumio-cpu-tweak[1233]: Setting MPD Affinity Aug 31 18:07:33 volumio-kali ntpd[859]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 18:07:33 volumio-kali volumio-cpu-tweak[1249]: pid 3's current affinity mask: f Aug 31 18:07:33 volumio-kali volumio-cpu-tweak[1233]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 31 18:07:33 volumio-kali volumio-cpu-tweak[1233]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 31 18:07:33 volumio-kali ntpd[859]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 31 18:07:33 volumio-kali ntpd[859]: DNS: Pool taking: 95.110.254.234 Aug 31 18:07:33 volumio-kali ntpd[859]: DNS: Pool taking: 212.45.144.3 Aug 31 18:07:33 volumio-kali ntpd[859]: DNS: Pool taking: 172.232.208.229 Aug 31 18:07:33 volumio-kali ntpd[859]: DNS: Pool taking: 185.19.184.35 Aug 31 18:07:33 volumio-kali ntpd[859]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 31 18:07:33 volumio-kali systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Aug 31 18:07:33 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:33] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 31 18:07:33 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:33] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 31 18:07:33 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:33] [error] handle_connect error: Connection refused Aug 31 18:07:34 volumio-kali systemd[1]: Started smbd.service - Samba SMB Daemon. Aug 31 18:07:34 volumio-kali systemd[1]: Reached target multi-user.target - Multi-User System. Aug 31 18:07:34 volumio-kali systemd[1]: Reached target graphical.target - Graphical Interface. Aug 31 18:07:34 volumio-kali systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 31 18:07:34 volumio-kali systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 31 18:07:34 volumio-kali systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 31 18:07:34 volumio-kali systemd[1]: Startup finished in 15.583s (kernel) + 20.824s (userspace) = 36.407s. Aug 31 18:07:37 volumio-kali systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 31 18:07:37 volumio-kali systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 18:07:37 volumio-kali systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 18:07:37 volumio-kali upmpdcli[1259]: Could not open config: /tmp/upmpdcli.conf Aug 31 18:07:37 volumio-kali systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 31 18:07:37 volumio-kali systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 31 18:07:39 volumio-kali ntpd[859]: CLOCK: time stepped by 0.587875 Aug 31 18:07:39 volumio-kali ntpd[859]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 31 18:07:40 volumio-kali volumio[1230]: info: ------------------------------------------- Aug 31 18:07:40 volumio-kali volumio[1230]: info: ----- Volumio3 ---- Aug 31 18:07:40 volumio-kali volumio[1230]: info: ------------------------------------------- Aug 31 18:07:40 volumio-kali volumio[1230]: info: ----- System startup ---- Aug 31 18:07:40 volumio-kali volumio[1230]: info: ------------------------------------------- Aug 31 18:07:40 volumio-kali kernel: hwmon hwmon1: Voltage normalised Aug 31 18:07:41 volumio-kali volumio[1230]: info: MYVOLUMIO Environment detected Aug 31 18:07:41 volumio-kali volumio[1230]: info: Plugin folders cleanup Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning into folder /volumio/app/plugins/ Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category audio_interface Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category miscellanea Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category music_service Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category plugins.json Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category system_controller Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category user_interface Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning into folder /data/plugins/ Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category system_controller Aug 31 18:07:41 volumio-kali volumio[1230]: info: Scanning category system_hardware Aug 31 18:07:41 volumio-kali volumio[1230]: info: Plugin folders cleanup completed Aug 31 18:07:41 volumio-kali volumio[1230]: info: ------------------------------------------- Aug 31 18:07:41 volumio-kali volumio[1230]: info: ----- Core plugins startup ---- Aug 31 18:07:41 volumio-kali volumio[1230]: info: ------------------------------------------- Aug 31 18:07:41 volumio-kali volumio[1230]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 18:07:41 volumio-kali volumio[1230]: info: Adding plugin upnp to MyMusic Plugins Aug 31 18:07:41 volumio-kali volumio[1230]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 18:07:41 volumio-kali volumio[1230]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 18:07:41 volumio-kali volumio[1230]: info: Loading plugins from folder /data/plugins/ Aug 31 18:07:41 volumio-kali volumio[1230]: info: Loading plugin "system"... Aug 31 18:07:41 volumio-kali volumio[1230]: info: Loading plugin "appearance"... Aug 31 18:07:44 volumio-kali volumio[1230]: info: Loading plugin "network"... Aug 31 18:07:44 volumio-kali volumio[1230]: info: Refreshing Cached IP Addresses Aug 31 18:07:44 volumio-kali sudo[1275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 18:07:44 volumio-kali sudo[1275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:44 volumio-kali sudo[1275]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:44 volumio-kali volumio[1230]: info: Loading plugin "services"... Aug 31 18:07:44 volumio-kali volumio[1230]: info: Loading plugin "volumio5onboarding"... Aug 31 18:07:44 volumio-kali sudo[1276]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 18:07:44 volumio-kali sudo[1276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:44 volumio-kali sudo[1276]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:44 volumio-kali volumio[1230]: info: Loading plugin "alsa_controller"... Aug 31 18:07:44 volumio-kali sudo[1285]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 31 18:07:44 volumio-kali sudo[1285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:44 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:07:44 volumio-kali volumio[1230]: info: Loading plugin "wizard"... Aug 31 18:07:44 volumio-kali volumio[1230]: info: Loading plugin "networkfs"... Aug 31 18:07:44 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:44] [connect] Successful connection Aug 31 18:07:44 volumio-kali volumio[1230]: info: Starting Udev Watcher for removable devices Aug 31 18:07:44 volumio-kali volumio[1230]: info: Ignoring mount for partition: boot Aug 31 18:07:44 volumio-kali volumio[1230]: info: Ignoring mount for partition: volumio Aug 31 18:07:44 volumio-kali volumio[1230]: info: Ignoring mount for partition: volumio_data Aug 31 18:07:44 volumio-kali kernel: hwmon hwmon1: Undervoltage detected! Aug 31 18:07:44 volumio-kali volumio[1230]: info: Mounting Device Volume_m-sata Aug 31 18:07:44 volumio-kali sudo[1311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Volume_m-sata -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 18:07:44 volumio-kali sudo[1311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:45 volumio-kali sudo[1311]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:45 volumio-kali ntfs-3g[1315]: Version 2022.10.3 integrated FUSE 28 Aug 31 18:07:45 volumio-kali ntfs-3g[1315]: Mounted /dev/sda1 (Read-Write, label "Volume m-sata", NTFS 3.1) Aug 31 18:07:45 volumio-kali ntfs-3g[1315]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 18:07:45 volumio-kali ntfs-3g[1315]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Aug 31 18:07:45 volumio-kali ntfs-3g[1315]: Global ownership and permissions enforced, configuration type 7 Aug 31 18:07:45 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 18:07:45 volumio-kali volumio[1230]: info: Loading plugin "volumio_command_line_client"... Aug 31 18:07:45 volumio-kali volumio[1230]: info: Loading plugin "upnp"... Aug 31 18:07:45 volumio-kali volumio[1230]: info: [1788192465773] Starting Upmpd Daemon Aug 31 18:07:45 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 18:07:45 volumio-kali volumio[1230]: info: Loading plugin "my_music"... Aug 31 18:07:45 volumio-kali volumio[1230]: info: Loading plugin "mpd"... Aug 31 18:07:46 volumio-kali volumio[1230]: info: Loading plugin "upnp_browser"... Aug 31 18:07:46 volumio-kali sudo[1285]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:48 volumio-kali systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 31 18:07:49 volumio-kali volumio[1230]: info: Starting UPNP Browser Aug 31 18:07:49 volumio-kali volumio[1230]: info: Loading plugin "alarm-clock"... Aug 31 18:07:49 volumio-kali volumio[1230]: info: Loading plugin "airplay_emulation"... Aug 31 18:07:49 volumio-kali volumio[1230]: info: Starting Shairport Sync Aug 31 18:07:49 volumio-kali volumio[1230]: info: Loading plugin "last_100"... Aug 31 18:07:49 volumio-kali volumio[1230]: info: Loading plugin "webradio"... Aug 31 18:07:49 volumio-kali volumio[1230]: info: Loading plugin "i2s_dacs"... Aug 31 18:07:49 volumio-kali volumio[1230]: info: Loading plugin "volumiodiscovery"... Aug 31 18:07:49 volumio-kali volumio[1230]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 18:07:49 volumio-kali volumio[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:07:49 volumio-kali volumio[1230]: *** WARNING *** For more information see Aug 31 18:07:49 volumio-kali volumio[1230]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 18:07:49 volumio-kali volumio[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:07:49 volumio-kali volumio[1230]: *** WARNING *** For more information see Aug 31 18:07:49 volumio-kali node[1230]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 18:07:49 volumio-kali node[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:07:49 volumio-kali node[1230]: *** WARNING *** For more information see Aug 31 18:07:49 volumio-kali node[1230]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 18:07:49 volumio-kali node[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:07:49 volumio-kali node[1230]: *** WARNING *** For more information see Aug 31 18:07:49 volumio-kali volumio[1230]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 18:07:49 volumio-kali volumio[1230]: info: Discovery: Started advertising with name: Volumio Kali Aug 31 18:07:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 18:07:49 volumio-kali volumio[1230]: info: Loading plugin "autostart"... Aug 31 18:07:50 volumio-kali volumio[1230]: info: Applying required configuration parameters for plugin autostart Aug 31 18:07:50 volumio-kali volumio[1230]: info: AutoStart - onVolumioStart - read config.json Aug 31 18:07:50 volumio-kali volumio[1230]: info: Loading plugin "gpio-buttons"... Aug 31 18:07:50 volumio-kali volumio[1230]: gpiox_arm32 V2.2.1 Aug 31 18:07:50 volumio-kali kernel: hwmon hwmon1: Voltage normalised Aug 31 18:07:51 volumio-kali volumio[1230]: info: gpio-buttons: Initialized Aug 31 18:07:51 volumio-kali volumio[1230]: info: Loading plugin "gpio_button_led"... Aug 31 18:07:51 volumio-kali volumio[1230]: gpiox_arm32 V2.2.1 Aug 31 18:07:52 volumio-kali volumio[1230]: info: Loading plugin "outputs"... Aug 31 18:07:52 volumio-kali volumio[1230]: info: Loading plugin "albumart"... Aug 31 18:07:52 volumio-kali volumio[1230]: info: Plugin example_plugin is not enabled Aug 31 18:07:52 volumio-kali volumio[1230]: info: Loading plugin "inputs"... Aug 31 18:07:52 volumio-kali volumio[1230]: info: Loading plugin "updater_comm"... Aug 31 18:07:52 volumio-kali volumio[1230]: info: Plugin mpdemulation is not enabled Aug 31 18:07:52 volumio-kali volumio[1230]: info: Loading plugin "rest_api"... Aug 31 18:07:52 volumio-kali volumio[1230]: info: Loading plugin "websocket"... Aug 31 18:07:52 volumio-kali volumio[1230]: info: Starting Socket.io Server version 1.7.4 Aug 31 18:07:52 volumio-kali volumio[1230]: info: Loading i18n strings for locale it Aug 31 18:07:52 volumio-kali volumio[1230]: Updating browse sources language Aug 31 18:07:52 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:07:52 volumio-kali systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 31 18:07:52 volumio-kali systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 18:07:52 volumio-kali systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 18:07:53 volumio-kali upmpdcli[1329]: Could not open config: /tmp/upmpdcli.conf Aug 31 18:07:53 volumio-kali systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 31 18:07:53 volumio-kali systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::initPlayerControls Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 18:07:53 volumio-kali volumio[1230]: Express server listening on port 3000 Aug 31 18:07:53 volumio-kali volumio[1230]: [Metrics] WebUI: 14s 491.97ms Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreStateMachine::resetVolumioState Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreStateMachine::getcurrentVolume Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreStateMachine::pushState Aug 31 18:07:53 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 18:07:53 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioPushState Aug 31 18:07:53 volumio-kali volumio[1230]: info: Volumio Network Manager: Network status updated: 1 Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:07:54 volumio-kali volumio[1230]: info: Executing start script for DAC BassFly-uHAT with I2S Mic Aug 31 18:07:54 volumio-kali volumio[1319]: Forking 3 albumart workers Aug 31 18:07:54 volumio-kali volumio[1230]: info: Reloading queue from file Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:07:54 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:07:54 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:07:54 volumio-kali sudo[1342]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x00 0x2000 w Aug 31 18:07:54 volumio-kali sudo[1342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:54 volumio-kali sudo[1342]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:54 volumio-kali volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 31 18:07:54 volumio-kali sudo[1354]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x13 0x3016 w Aug 31 18:07:54 volumio-kali sudo[1354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:54 volumio-kali sudo[1354]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreStateMachine::setRepeat true single undefined Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreStateMachine::pushState Aug 31 18:07:54 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioPushState Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreStateMachine::setRandom null Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreStateMachine::pushState Aug 31 18:07:54 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:07:54 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioPushState Aug 31 18:07:54 volumio-kali volumio[1230]: info: Setting Device type: Raspberry PI Aug 31 18:07:54 volumio-kali sudo[1382]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x01 0xD805 w Aug 31 18:07:54 volumio-kali sudo[1382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:54 volumio-kali sudo[1382]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:55 volumio-kali sudo[1390]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x00 0x0900 w Aug 31 18:07:55 volumio-kali sudo[1390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:55 volumio-kali sudo[1390]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:55 volumio-kali systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 31 18:07:55 volumio-kali volumio[1230]: info: Discovery: adding 04135292-3f45-4e02-b5c9-55717680aac2 Aug 31 18:07:55 volumio-kali volumio[1230]: info: Discovery: Found device Volumio Kali Aug 31 18:07:55 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:07:55 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:07:55 volumio-kali sudo[1394]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x00 0x2000 w Aug 31 18:07:55 volumio-kali sudo[1394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:55 volumio-kali sudo[1394]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:55 volumio-kali volumio[1230]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.4 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Aug 31 18:07:55 volumio-kali sudo[1400]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x13 0x3016 w Aug 31 18:07:55 volumio-kali sudo[1400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:55 volumio-kali sudo[1400]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:55 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 18:07:55 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 18:07:55 volumio-kali sudo[1402]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x01 0xD801 w Aug 31 18:07:55 volumio-kali sudo[1402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:55 volumio-kali sudo[1402]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:56 volumio-kali sudo[1405]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 18:07:56 volumio-kali sudo[1405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:56 volumio-kali sudo[1405]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:56 volumio-kali sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 18:07:56 volumio-kali sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:56 volumio-kali sudo[1408]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:56 volumio-kali volumio[1230]: info: Discovery: this is already registered, 04135292-3f45-4e02-b5c9-55717680aac2 Aug 31 18:07:56 volumio-kali volumio[1230]: info: Discovery: Found device Volumio Kali Aug 31 18:07:56 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:07:56 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:07:56 volumio-kali sudo[1407]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x00 0x0900 w Aug 31 18:07:56 volumio-kali sudo[1407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:56 volumio-kali sudo[1407]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:56 volumio-kali sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x05 0xDD59 w Aug 31 18:07:56 volumio-kali sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:56 volumio-kali sudo[1412]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:56 volumio-kali volumio[1230]: info: gpio-buttons: Socket connected to Volumio Aug 31 18:07:56 volumio-kali sudo[1414]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x06 0x24CC w Aug 31 18:07:56 volumio-kali sudo[1414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:56 volumio-kali sudo[1414]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:56 volumio-kali sudo[1416]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x07 0x1A65 w Aug 31 18:07:56 volumio-kali sudo[1416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:56 volumio-kali sudo[1416]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:57 volumio-kali sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x08 0x2CDB w Aug 31 18:07:57 volumio-kali sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:57 volumio-kali sudo[1418]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:57 volumio-kali sudo[1421]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x09 0x1646 w Aug 31 18:07:57 volumio-kali sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:57 volumio-kali sudo[1421]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:57 volumio-kali sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 31 18:07:57 volumio-kali sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:57 volumio-kali sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0A 0x3AEA w Aug 31 18:07:57 volumio-kali sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:57 volumio-kali sudo[1424]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:57 volumio-kali sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0B 0xF34D w Aug 31 18:07:57 volumio-kali sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:57 volumio-kali sudo[1427]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:58 volumio-kali sudo[1429]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0C 0x3EF9 w Aug 31 18:07:58 volumio-kali sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:58 volumio-kali sudo[1429]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:58 volumio-kali sudo[1431]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0D 0xE05E w Aug 31 18:07:58 volumio-kali sudo[1431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:58 volumio-kali sudo[1431]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:58 volumio-kali sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0E 0x34F8 w Aug 31 18:07:58 volumio-kali sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:58 volumio-kali sudo[1433]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:58 volumio-kali sudo[1435]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0F 0x9A00 w Aug 31 18:07:58 volumio-kali sudo[1435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:58 volumio-kali sudo[1435]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:58 volumio-kali sudo[1437]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x11 0x2E13 w Aug 31 18:07:58 volumio-kali sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:58 volumio-kali sudo[1437]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:59 volumio-kali sudo[1439]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x05 0xDD59 w Aug 31 18:07:59 volumio-kali sudo[1439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:59 volumio-kali sudo[1439]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:59 volumio-kali sudo[1441]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x06 0x24CC w Aug 31 18:07:59 volumio-kali sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:59 volumio-kali sudo[1441]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:59 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:59] [connect] Successful connection Aug 31 18:07:59 volumio-kali volumio-remote-updater[684]: [2026-08-31 18:07:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788192479 101 Aug 31 18:07:59 volumio-kali sudo[1443]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x07 0x1A65 w Aug 31 18:07:59 volumio-kali volumio[1230]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Aug 31 18:07:59 volumio-kali sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:59 volumio-kali sudo[1443]: pam_unix(sudo:session): session closed for user root Aug 31 18:07:59 volumio-kali sudo[1445]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x08 0x2CDB w Aug 31 18:07:59 volumio-kali sudo[1445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:07:59 volumio-kali sudo[1445]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:00 volumio-kali sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x09 0x1646 w Aug 31 18:08:00 volumio-kali sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:00 volumio-kali sudo[1447]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:00 volumio-kali sudo[1449]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0A 0x3AEA w Aug 31 18:08:00 volumio-kali sudo[1449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:00 volumio-kali sudo[1449]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:00 volumio-kali sudo[1451]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0B 0xF34D w Aug 31 18:08:00 volumio-kali sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:00 volumio-kali sudo[1451]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:00 volumio-kali sudo[1453]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0C 0x3EF9 w Aug 31 18:08:00 volumio-kali sudo[1453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:00 volumio-kali sudo[1453]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:01 volumio-kali sudo[1455]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0D 0xE05E w Aug 31 18:08:01 volumio-kali sudo[1455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:01 volumio-kali sudo[1455]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:01 volumio-kali sudo[1457]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0E 0x34F8 w Aug 31 18:08:01 volumio-kali sudo[1457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:01 volumio-kali sudo[1457]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:01 volumio-kali sudo[1459]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0F 0x9A00 w Aug 31 18:08:01 volumio-kali sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:01 volumio-kali sudo[1459]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:01 volumio-kali sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x11 0x2E13 w Aug 31 18:08:01 volumio-kali sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:01 volumio-kali sudo[1461]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:01 volumio-kali volumio[1230]: info: DAC script executed Aug 31 18:08:01 volumio-kali volumio[1230]: info: Completed loading Core Plugins Aug 31 18:08:01 volumio-kali volumio[1230]: info: Preparing to generate the ALSA configuration file Aug 31 18:08:02 volumio-kali volumio[1230]: info: Asound.conf file unchanged, so no further update is needed Aug 31 18:08:02 volumio-kali volumio[1230]: info: Output device has changed, restarting MPD Aug 31 18:08:02 volumio-kali volumio[1230]: info: Output device has changed, restarting Shairport Sync Aug 31 18:08:02 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:02 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:02 volumio-kali sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 18:08:02 volumio-kali sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:02 volumio-kali sudo[1466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 18:08:02 volumio-kali sudo[1464]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:02 volumio-kali sudo[1466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:02 volumio-kali volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 18:08:02 volumio-kali volumio[1230]: info: ___________ START PLUGINS ___________ Aug 31 18:08:02 volumio-kali volumio[1230]: info: ControllerMpd::onStart: Initializing MPD Aug 31 18:08:02 volumio-kali volumio[1230]: info: Creating MPD Configuration file Aug 31 18:08:02 volumio-kali systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 31 18:08:02 volumio-kali sudo[1475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 31 18:08:02 volumio-kali systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 18:08:02 volumio-kali systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 18:08:02 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 18:08:02 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 18:08:02 volumio-kali volumio[1230]: info: [1788192482787] CoreMusicLibrary::Adding element Server multimediali Aug 31 18:08:02 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:08:02 volumio-kali sudo[1475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:02 volumio-kali volumio[1230]: info: UPNP Browser: Client initialized successfully Aug 31 18:08:02 volumio-kali sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 18:08:02 volumio-kali sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:02 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:02 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:03 volumio-kali sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 18:08:03 volumio-kali sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:03 volumio-kali sudo[1491]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 18:08:03 volumio-kali sudo[1491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 18:08:03 volumio-kali sudo[1498]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 31 18:08:03 volumio-kali sudo[1491]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:03 volumio-kali systemd[1]: mpd.service: Deactivated successfully. Aug 31 18:08:03 volumio-kali sudo[1477]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:03 volumio-kali systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 18:08:03 volumio-kali systemd[1]: mpd.socket: Deactivated successfully. Aug 31 18:08:03 volumio-kali systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 18:08:03 volumio-kali systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 18:08:03 volumio-kali systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 18:08:03 volumio-kali systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 18:08:03 volumio-kali systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 31 18:08:03 volumio-kali sudo[1475]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:03 volumio-kali sudo[1507]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 18:08:03 volumio-kali sudo[1507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 18:08:03 volumio-kali sudo[1515]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 31 18:08:03 volumio-kali sudo[1507]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:03 volumio-kali volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 18:08:03 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:03 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:04 volumio-kali volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 18:08:04 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 18:08:04 volumio-kali volumio[1230]: info: [1788192484072] CoreMusicLibrary::Adding element Last_100 Aug 31 18:08:04 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:08:04 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 18:08:04 volumio-kali volumio[1230]: info: [1788192484180] CoreMusicLibrary::Adding element Webradio Aug 31 18:08:04 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:08:04 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:04.199+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 31 18:08:04 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:08:04 volumio-kali volumio[1346]: Starting albumart workers Aug 31 18:08:04 volumio-kali volumio[1230]: info: Initializing BBC Radios Aug 31 18:08:04 volumio-kali systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 31 18:08:04 volumio-kali systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 31 18:08:04 volumio-kali systemd[1]: setdatetime-helper.service: Consumed 1.219s CPU time. Aug 31 18:08:05 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:08:05 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:05 volumio-kali volumio[1230]: info: AutoStart - onStart - waiting for system ready state Aug 31 18:08:05 volumio-kali volumio[1230]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Aug 31 18:08:05 volumio-kali volumio[1230]: info: AutoStart - Maximum wait time: 300 seconds Aug 31 18:08:05 volumio-kali volumio[1230]: info: AutoStart - Startup volume disabled Aug 31 18:08:05 volumio-kali volumio[1230]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 31 18:08:05 volumio-kali volumio[1230]: info: gpio-buttons: Creating GPIO triggers... Aug 31 18:08:05 volumio-kali volumio[1230]: info: gpio-buttons: GPIO3 configured: button6 -> shutdown Aug 31 18:08:05 volumio-kali volumio[1230]: info: GPIOButtonLED: Starting plugin Aug 31 18:08:05 volumio-kali volumio[1230]: info: Volumio Calling Home Aug 31 18:08:05 volumio-kali volumio[1343]: Starting albumart workers Aug 31 18:08:06 volumio-kali volumio[1344]: Starting albumart workers Aug 31 18:08:06 volumio-kali volumio[1230]: info: gpio-buttons: Started successfully Aug 31 18:08:08 volumio-kali systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 31 18:08:08 volumio-kali systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 18:08:08 volumio-kali systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 18:08:08 volumio-kali sudo[1422]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:11 volumio-kali volumio[1230]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 31 18:08:11 volumio-kali volumio[1230]: info: MPD Permissions set Aug 31 18:08:11 volumio-kali volumio[1230]: info: MPD Permissions set Aug 31 18:08:12 volumio-kali volumio[1230]: info: Upmpdcli Daemon Started Aug 31 18:08:12 volumio-kali mpd[1516]: 2026-08-31T18:08:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 18:08:12 volumio-kali volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 31 18:08:12 volumio-kali systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 18:08:12 volumio-kali sudo[1481]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:12 volumio-kali sudo[1466]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:12 volumio-kali volumio[1230]: info: Volumio called home Aug 31 18:08:12 volumio-kali volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 31 18:08:12 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:12 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:12 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:12 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:12.452+02:00 level=INFO msg="system info for 5e8be1d24545549684f3f18e102a347c" deviceName="Volumio Kali" deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 31 18:08:12 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:12.493+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 31 18:08:12 volumio-kali volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:12 volumio-kali volumio[1230]: info: GPIOButtonLED: dtoverlay already correct Aug 31 18:08:12 volumio-kali volumio[1230]: info: GPIOButtonLED: LED+ on GPIO 22 Aug 31 18:08:12 volumio-kali volumio[1230]: info: GPIOButtonLED: LED- on GPIO 4 Aug 31 18:08:12 volumio-kali volumio[1230]: info: GPIOButtonLED: LED polarity = normal Aug 31 18:08:12 volumio-kali volumio[1230]: info: GPIOButtonLED: LED blink slow Aug 31 18:08:12 volumio-kali volumio[1230]: info: Completed starting Core Plugins Aug 31 18:08:12 volumio-kali volumio[1230]: info: ------------------------------------------- Aug 31 18:08:12 volumio-kali volumio[1230]: info: ----- MyVolumio plugins startup ---- Aug 31 18:08:12 volumio-kali volumio[1230]: info: ------------------------------------------- Aug 31 18:08:12 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:13 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:13 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:13 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:13 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:14 volumio-kali volumio-remote-updater[684]: Test mode disabled Aug 31 18:08:14 volumio-kali volumio-remote-updater[684]: Alpha mode disabled Aug 31 18:08:14 volumio-kali volumio-remote-updater[684]: Alpha legacy test mode disabled Aug 31 18:08:15 volumio-kali volumio[1230]: error: MPD error: The expression evaluated to a falsy value: Aug 31 18:08:15 volumio-kali volumio[1230]: assert.ok(self.idling) Aug 31 18:08:15 volumio-kali volumio[1230]: error: The expression evaluated to a falsy value: Aug 31 18:08:15 volumio-kali volumio[1230]: assert.ok(self.idling) Aug 31 18:08:15 volumio-kali volumio[1230]: info: MPD running with PID1516 Aug 31 18:08:15 volumio-kali volumio[1230]: ,establishing connection Aug 31 18:08:15 volumio-kali volumio[1230]: info: GPIOButtonLED: Boot complete - LED solid Aug 31 18:08:15 volumio-kali volumio[1230]: error: updateQueue error: null Aug 31 18:08:15 volumio-kali volumio[1230]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 31 18:08:15 volumio-kali volumio[1230]: info: Starting Shairport Sync Aug 31 18:08:15 volumio-kali volumio[1230]: info: Starting Shairport Sync Aug 31 18:08:15 volumio-kali volumio[1230]: info: Starting Shairport Sync Aug 31 18:08:15 volumio-kali sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 18:08:15 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:15.372+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Aug 31 18:08:15 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:15.374+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Aug 31 18:08:15 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:15.374+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Aug 31 18:08:15 volumio-kali volumio[1230]: error: updateQueue error: null Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 18:08:15 volumio-kali sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:15 volumio-kali sudo[1619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 18:08:15 volumio-kali sudo[1621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 18:08:15 volumio-kali sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:15 volumio-kali sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 18:08:15 volumio-kali systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 18:08:15 volumio-kali systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 18:08:15 volumio-kali systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 18:08:15 volumio-kali systemd[1]: shairport-sync.service: Consumed 2.047s CPU time. Aug 31 18:08:15 volumio-kali systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 18:08:15 volumio-kali sudo[1617]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:15 volumio-kali sudo[1621]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:15 volumio-kali systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 18:08:15 volumio-kali systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 18:08:15 volumio-kali systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 18:08:15 volumio-kali systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 18:08:15 volumio-kali sudo[1619]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:15 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:15 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:15 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:15 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:16 volumio-kali volumio[1230]: info: Shairport-Sync Started Aug 31 18:08:16 volumio-kali volumio[1230]: Error adding Membership: Error: addMembership EINVAL Aug 31 18:08:16 volumio-kali volumio[1230]: info: Shairport-Sync Started Aug 31 18:08:16 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 18:08:16 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 18:08:16 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:16.220+02:00 level=INFO msg="enabling local network discovery" Aug 31 18:08:16 volumio-kali volumio[1230]: info: Shairport-Sync Started Aug 31 18:08:16 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:16.261+02:00 level=INFO msg="enabling BLE discovery" Aug 31 18:08:16 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:16 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:16 volumio-kali volumio[1230]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 31 18:08:17 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:17.132+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 31 18:08:17 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:17.994+02:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Aug 31 18:08:18 volumio-kali dbus-daemon[672]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.15" (uid=0 pid=1508 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=907 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 31 18:08:18 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:18.355+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s Aug 31 18:08:18 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:18.537+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.96464501s timeout=20s Aug 31 18:08:18 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:18.541+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" Aug 31 18:08:18 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:18.571+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=http://pushupdates.volumio.org duration=216.247689ms Aug 31 18:08:18 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:18 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:18 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:18 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:18 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:18 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:18 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:18 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:18.692+02:00 level=INFO msg="emitting device name changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" name="Volumio Kali" Aug 31 18:08:19 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:19.015+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=http://cddb.volumio.org duration=656.245591ms Aug 31 18:08:19 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:19.038+02:00 level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" language=it Aug 31 18:08:19 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:19.153+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=http://plugins.volumio.org duration=794.32608ms Aug 31 18:08:19 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 18:08:19 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:19.766+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" timezone=Europe/Berlin Aug 31 18:08:19 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:19.930+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=1.575150732s Aug 31 18:08:20 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:20.065+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=1.710247346s Aug 31 18:08:20 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:20.094+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://www.googleapis.com duration=1.738187762s Aug 31 18:08:20 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:20.152+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://securetoken.googleapis.com duration=1.795225597s Aug 31 18:08:20 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:20.377+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" available=true connected=true macAddress=b8:27:eb:29:1f:07 ip4Address=192.168.1.6/24 ip6Address= Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.151+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://functions.volumio.cloud duration=2.79175641s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.151+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://functions.volumio.cloud duration=2.7955829s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.259+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://database.volumio.cloud duration=2.900570454s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.266+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.325+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.337+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=2.979586285s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.352+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=26.645295ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.370+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=3.015306607s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.371+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=44.098647ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.372+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=http://pushupdates.volumio.org duration=44.981625ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.382+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=55.476367ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.428+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.784805906s timeout=10s endpoint=https://google.com duration=3.073298724s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.448+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.151943881s timeout=20s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.448+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.466+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://www.googleapis.com duration=140.645533ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.483+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=156.940419ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.485+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://securetoken.googleapis.com duration=159.310063ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.498+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://database.volumio.cloud duration=169.823713ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.506+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://functions.volumio.cloud duration=179.270569ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.515+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://functions.volumio.cloud duration=188.193692ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.578+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=http://cddb.volumio.org duration=250.76149ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.793+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=http://plugins.volumio.org duration=465.018978ms Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.877+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m0.083758738s timeout=20s Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.877+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" Aug 31 18:08:21 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:21.981+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.031137456s timeout=10s endpoint=https://google.com duration=655.052277ms Aug 31 18:08:22 volumio-kali volumio[1230]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 31 18:08:22 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 18:08:22 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 18:08:22 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:22.848+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" setupComplete=true Aug 31 18:08:22 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:22 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:22 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:22 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:22 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:22 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:22 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:22 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:22.881+02:00 level=INFO msg="emitting device name changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" name="Volumio Kali" Aug 31 18:08:23 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:23 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:23 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:23 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:23 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:23 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:23 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:23 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:23.334+02:00 level=INFO msg="emitting device name changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" name="Volumio Kali" Aug 31 18:08:23 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:23.545+02:00 level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" language=it Aug 31 18:08:23 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:23.666+02:00 level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" language=it Aug 31 18:08:24 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 18:08:25 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 18:08:25 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:25 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 18:08:25 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.232+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" timezone=Europe/Berlin Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.233+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" timezone=Europe/Berlin Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.296+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" available=true connected=true macAddress=b8:27:eb:29:1f:07 ip4Address=192.168.1.6/24 ip6Address= Aug 31 18:08:25 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 18:08:25 volumio-kali volumio[1230]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.398+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" available=true connected=true macAddress=b8:27:eb:29:1f:07 ip4Address=192.168.1.6/24 ip6Address= Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.444+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.506+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.555+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" setupComplete=true Aug 31 18:08:25 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:25.682+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" setupComplete=true Aug 31 18:08:25 volumio-kali volumio[1230]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 31 18:08:26 volumio-kali volumio[1230]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 31 18:08:26 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 18:08:27 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 18:08:27 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:27 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 18:08:27 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 18:08:27 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 18:08:27 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:27 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 18:08:27 volumio-kali volumio[1230]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 31 18:08:27 volumio-kali volumio[1230]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 31 18:08:27 volumio-kali volumio[1230]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 31 18:08:28 volumio-kali volumio[1230]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 31 18:08:28 volumio-kali volumio[1230]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 31 18:08:28 volumio-kali volumio[1230]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 31 18:08:28 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 31 18:08:28 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:28 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:28 volumio-kali volumio[1230]: amixer -c 3 info | grep "snd_rpi_googlevoicehat_soundcar" Aug 31 18:08:29 volumio-kali volumio[1230]: Card sysdefault:3 'sndrpigooglevoi'/'snd_rpi_googlevoicehat_soundcard' Aug 31 18:08:29 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Aug 31 18:08:29 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:29 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:29 volumio-kali volumio[1230]: amixer -c 2 info | grep "vc4-hdmi" Aug 31 18:08:29 volumio-kali volumio[1230]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Aug 31 18:08:29 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 31 18:08:29 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:29 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:29 volumio-kali volumio[1230]: amixer -c 3 info | grep "BassFly-uHAT with I2S Mic" Aug 31 18:08:29 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:29.780+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" selectedOutputId=3 Aug 31 18:08:29 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:29.785+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" selectedOutputId=3 Aug 31 18:08:29 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:29.799+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" selectedOutputId=3 Aug 31 18:08:29 volumio-kali volumio[1230]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 31 18:08:33 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:33.488+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=269.291388ms platform=PLATFORM_IOS version=6.260807.0 Aug 31 18:08:33 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:33.550+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=177.735899ms timeout=20s Aug 31 18:08:33 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:33.550+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" Aug 31 18:08:35 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 31 18:08:35 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:35 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:35 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:35.527+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.4:56951 Aug 31 18:08:35 volumio-kali volumio[1230]: amixer -c 3 info | grep "snd_rpi_googlevoicehat_soundcar" Aug 31 18:08:35 volumio-kali volumio[1230]: Card sysdefault:3 'sndrpigooglevoi'/'snd_rpi_googlevoicehat_soundcard' Aug 31 18:08:35 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:35.878+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.4:56951 Aug 31 18:08:35 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:35.878+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.4:56951 Aug 31 18:08:35 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Aug 31 18:08:35 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:35 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:35 volumio-kali volumio[1230]: amixer -c 2 info | grep "vc4-hdmi" Aug 31 18:08:35 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:35.891+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.4:56955 Aug 31 18:08:35 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:35.916+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.4:56955 @ 0x1da0ea0" latency=136.119406ms timeout=20s Aug 31 18:08:35 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:35.916+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.4:56955 @ 0x1da0ea0" Aug 31 18:08:36 volumio-kali upmpdcli[1769]: writing RSA key Aug 31 18:08:36 volumio-kali volumio[1230]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Aug 31 18:08:36 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 31 18:08:36 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:36 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:36 volumio-kali volumio[1230]: amixer -c 3 info | grep "BassFly-uHAT with I2S Mic" Aug 31 18:08:36 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 31 18:08:36 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:36 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:36 volumio-kali volumio[1230]: amixer -c 3 info | grep "snd_rpi_googlevoicehat_soundcar" Aug 31 18:08:36 volumio-kali volumio[1230]: Card sysdefault:3 'sndrpigooglevoi'/'snd_rpi_googlevoicehat_soundcard' Aug 31 18:08:36 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Aug 31 18:08:36 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:36 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:36 volumio-kali volumio[1230]: amixer -c 2 info | grep "vc4-hdmi" Aug 31 18:08:36 volumio-kali volumio[1230]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Aug 31 18:08:36 volumio-kali volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 31 18:08:36 volumio-kali volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 18:08:36 volumio-kali volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 18:08:36 volumio-kali volumio[1230]: amixer -c 3 info | grep "BassFly-uHAT with I2S Mic" Aug 31 18:08:36 volumio-kali volumio[1230]: error: MyVolumio Plugin failed to start in a timely fashion Aug 31 18:08:36 volumio-kali volumio[1230]: [Metrics] CommandRouter: 56s 805.05ms Aug 31 18:08:36 volumio-kali volumio[1230]: info: CoreCommandRouter::volumiosetStartupVolume Aug 31 18:08:36 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:08:36 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:36 volumio-kali volumio[1230]: info: CoreCommandRouter::Close All Modals sent Aug 31 18:08:36 volumio-kali volumio[1230]: info: CoreCommandRouter::Close All Modals sent Aug 31 18:08:37 volumio-kali volumio[1230]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 31 18:08:37 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:08:37 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:37 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 18:08:37 volumio-kali volumio[1230]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 18:08:37 volumio-kali volumio[1230]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 18:08:37 volumio-kali volumio[1230]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 18:08:37 volumio-kali kernel: voicehat-codec voicehat-codec: Enabling audio amp... Aug 31 18:08:37 volumio-kali volumio[1230]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 18:08:37 volumio-kali volumio[1230]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 31 18:08:37 volumio-kali volumio[1230]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 18:08:37 volumio-kali volumio[1230]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 18:08:37 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 18:08:38 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:38.539+02:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=4m25.96464501s timeout=20s error="failed to get system info: could not get system info: context deadline exceeded" Aug 31 18:08:39 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:39.256+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.4:56955 @ 0x1da0ea0" latency=138.018501ms platform=PLATFORM_IOS version=6.260807.0 Aug 31 18:08:39 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:39.256+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.4:56955 @ 0x1da0ea0" latency=135.404958ms timeout=20s Aug 31 18:08:39 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:39.256+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.4:56955 @ 0x1da0ea0" Aug 31 18:08:41 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:41.449+02:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m5.151943881s timeout=20s error="failed to get system info: could not get system info: context deadline exceeded" Aug 31 18:08:41 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:41.859+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 31 18:08:41 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:41.860+02:00 level=ERROR msg="failed to send response" component=server dst="00:00:00:00:00:00%01 @ 0x1fd1830" id=307560397 status=STATUS_UNKNOWN error="peer is gone" Aug 31 18:08:41 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:41.878+02:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=2m0.083758738s timeout=20s error="failed to get system info: could not get system info: context deadline exceeded" Aug 31 18:08:41 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:41.878+02:00 level=ERROR msg="failed to send response" component=server dst=" @ 0x1fd1830" id=148492165 status=STATUS_UNKNOWN error="no addresses to write to" Aug 31 18:08:42 volumio-kali kernel: voicehat-codec voicehat-codec: Disabling audio amp... Aug 31 18:08:43 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 18:08:43 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 18:08:43 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:43 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:08:43 volumio-kali volumio[1230]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 18:08:43 volumio-kali volumio[1230]: info: MyVolumio login type: Token Aug 31 18:08:44 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 18:08:44 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 31 18:08:45 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:45.170+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 31 18:08:45 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:45.170+02:00 level=ERROR msg="failed to send response" component=server dst="00:00:00:00:00:00%01 @ 0x1fd1830" id=1665428843 status=STATUS_UNKNOWN error="peer is gone" Aug 31 18:08:49 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 31 18:08:49 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 31 18:08:49 volumio-kali volumio[1230]: info: Streaming services startup Aug 31 18:08:49 volumio-kali volumio[1230]: info: Starting Streaming Daemon Aug 31 18:08:49 volumio-kali volumio[1230]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 31 18:08:49 volumio-kali sudo[1826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 18:08:49 volumio-kali sudo[1826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 18:08:49 volumio-kali sudo[1826]: pam_unix(sudo:session): session closed for user root Aug 31 18:08:49 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:49 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:49 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:49 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:49 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:49 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:49 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:49 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:49 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:49 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:50 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:50 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:50 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:50 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:50 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:50 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:50 volumio-kali volumio[1230]: info: Received Get System Info Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 18:08:50 volumio-kali volumio[1230]: info: Discovery: Getting this device information Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 31 18:08:50 volumio-kali volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.129+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.4:56955 @ 0x1da0ea0" name="Volumio Kali" Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.130+02:00 level=INFO msg="emitting device name changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" name="Volumio Kali" Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.130+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1fd1830" event=SERVER_EVENT_TYPE_DEVICE_NAME_CHANGED error="no addresses to write to" Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.131+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.4:56955 @ 0x1da0ea0" language=it Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.131+02:00 level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1fd1830" language=it Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.131+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1fd1830" event=SERVER_EVENT_TYPE_DEVICE_LANGUAGE_CHANGED error="no addresses to write to" Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.131+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.4:56955 @ 0x1da0ea0" name="Volumio Kali" Aug 31 18:08:50 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:50.133+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.4:56955 @ 0x1da0ea0" language=it Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 18:08:50 volumio-kali volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 31 18:08:50 volumio-kali volumio[1230]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 31 18:08:50 volumio-kali volumio[1230]: info: BOOT COMPLETED Aug 31 18:08:50 volumio-kali volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 18:08:50 volumio-kali volumio[1230]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 31 18:08:50 volumio-kali volumio[1230]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 31 18:08:50 volumio-kali volumio[1230]: at new NodeError (node:internal/errors:405:5) Aug 31 18:08:50 volumio-kali volumio[1230]: at assert (node:internal/assert:14:11) Aug 31 18:08:50 volumio-kali volumio[1230]: at internalConnectMultiple (node:net:1118:3) Aug 31 18:08:50 volumio-kali volumio[1230]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 31 18:08:50 volumio-kali volumio[1230]: at listOnTimeout (node:internal/timers:575:11) Aug 31 18:08:50 volumio-kali volumio[1230]: at process.processTimers (node:internal/timers:514:7) { Aug 31 18:08:50 volumio-kali volumio[1230]: code: 'ERR_INTERNAL_ASSERTION' Aug 31 18:08:50 volumio-kali volumio[1230]: } Aug 31 18:08:50 volumio-kali volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 18:08:51 volumio-kali bluealsa[964]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_9D_63_8C_76_DB, ...) Aug 31 18:08:53 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:53.551+02:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%01 @ 0x1fd1830" latency=177.735899ms timeout=20s error="failed to get current timezone: could not get current timezone: context deadline exceeded" Aug 31 18:08:53 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:53.552+02:00 level=ERROR msg="failed to send response" component=server dst=" @ 0x1fd1830" id=228961354 status=STATUS_UNKNOWN error="no addresses to write to" Aug 31 18:08:55 volumio-kali volumio5-onboarding[1508]: time=2026-08-31T18:08:55.916+02:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.4:56955 @ 0x1da0ea0" latency=136.119406ms timeout=20s error="failed to get current timezone: could not get current timezone: context deadline exceeded" Aug 31 18:08:57 volumio-kali sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 18:07' Aug 31 18:08:57 volumio-kali sudo[1861]: 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"