Oct 16 07:56:03 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E, ...) Oct 16 07:56:06 garage volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 07:56:06 garage volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 07:56:09 garage volumio5-onboarding[2262]: time=2026-10-16T07:56:09.144-04:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Oct 16 07:56:10 garage dbus-daemon[628]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.34" (uid=0 pid=2262 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=896 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0001, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0001/char0002, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0001/char0004, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0006, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0006/char0007, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0006/char0007/desc0009, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000a, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000a/char000b, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000a/char000d, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f/char0010, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f/char0010/desc0012, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f/char0010/desc0013, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014/char0015, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014/char0015/desc0017, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014/char0015/desc0018, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0019, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0019/char001a, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0019/char001a/desc001c, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d/char001e, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d/char001e/desc0020, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d/char0021, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0024, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0024/desc0026, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0027, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0027/desc0029, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char002a, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char002a/desc002c, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char002e, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char002e/desc0030, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char002e/desc0031, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0032, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0032/desc0034, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0032/desc0035, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0036, ...) Oct 16 07:56:10 garage bluealsa[959]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0036/desc0038, ...) Oct 16 07:56:12 garage volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 07:56:17 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 07:56:17 garage dbus-daemon[628]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.42' (uid=1000 pid=2627 comm="hostnamectl --json short") Oct 16 07:56:17 garage systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 16 07:56:18 garage dbus-daemon[628]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 16 07:56:18 garage systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 16 07:56:18 garage dbus-daemon[628]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.43' (uid=0 pid=2628 comm="/lib/systemd/systemd-hostnamed") Oct 16 07:56:18 garage systemd[1]: Starting polkit.service - Authorization Manager... Oct 16 07:56:18 garage polkitd[2630]: Started polkitd version 122 Oct 16 07:56:18 garage polkitd[2630]: Loading rules from directory /etc/polkit-1/rules.d Oct 16 07:56:18 garage polkitd[2630]: Loading rules from directory /usr/share/polkit-1/rules.d Oct 16 07:56:18 garage polkitd[2630]: Finished loading, compiling and executing 2 rules Oct 16 07:56:18 garage dbus-daemon[628]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 16 07:56:18 garage systemd[1]: Started polkit.service - Authorization Manager. Oct 16 07:56:18 garage polkitd[2630]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Oct 16 07:56:18 garage dbus-daemon[628]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.43" (uid=0 pid=2628 comm="/lib/systemd/systemd-hostnamed") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.InteractiveAuthorizationRequired" requested_reply="0" destination=":1.42" (uid=1000 pid=2627 comm="hostnamectl --json short") Oct 16 07:56:18 garage dbus-daemon[628]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.34" (uid=0 pid=2262 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=896 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 16 07:56:20 garage sudo[2849]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/vcgencmd version Oct 16 07:56:20 garage sudo[2849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:56:20 garage sudo[2849]: pam_unix(sudo:session): session closed for user root Oct 16 07:56:21 garage volumio[1133]: info: Firmware detection stderr: [sudo] password for volumio: Oct 16 07:56:21 garage volumio5-onboarding[2262]: time=2026-10-16T07:56:21.958-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Oct 16 07:56:27 garage sudo[3443]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 07:56:27 garage sudo[3443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:56:27 garage sudo[3443]: pam_unix(sudo:session): session closed for user root Oct 16 07:56:41 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 07:56:41 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 07:56:41 garage volumio[1133]: info: Discovery: Getting this device information Oct 16 07:56:41 garage volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 07:56:41 garage volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 07:56:41 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 07:56:41 garage volumio[1133]: verbose: New Socket.io Connection to 192.168.4.24:3000 from 192.168.4.28 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 16 07:56:41 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 07:56:41 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 07:56:48 garage systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0001/char0002, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0001/char0004, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0001, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0006/char0007/desc0009, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0006/char0007, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0006, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000a/char000b, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000a/char000d, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000a, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f/char0010/desc0012, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f/char0010/desc0013, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f/char0010, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service000f, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014/char0015/desc0017, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014/char0015/desc0018, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014/char0015, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0014, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0019/char001a/desc001c, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0019/char001a, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0019, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d/char001e/desc0020, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d/char001e, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d/char0021, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service001d, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0024/desc0026, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0024, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0027/desc0029, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char0027, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char002a/desc002c, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023/char002a, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service0023, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char002e/desc0030, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char002e/desc0031, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char002e, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0032/desc0034, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0032/desc0035, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0032, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0036/desc0038, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d/char0036, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E/service002d, ...) Oct 16 07:56:48 garage bluealsa[959]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_73_4A_D3_A4_4B_4E, ...) Oct 16 07:57:15 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:15.938-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.4.28:49620 @ 0x34d0900" latency=13.493472ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 16 07:57:15 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:15.964-04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s Oct 16 07:57:15 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:15.975-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=10.829674ms Oct 16 07:57:15 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:15.988-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=23.687763ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.005-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://google.com duration=41.358248ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.007-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://securetoken.googleapis.com duration=43.615515ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.038-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://functions.volumio.cloud duration=73.313682ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.038-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://functions.volumio.cloud duration=73.327015ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.067-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=http://pushupdates.volumio.org duration=102.460449ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.070-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://database.volumio.cloud duration=105.624373ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.075-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://www.googleapis.com duration=111.529673ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.086-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=http://plugins.volumio.org duration=121.090976ms Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.136-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=172.005527ms Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 07:57:16 garage volumio[1133]: info: Discovery: Getting this device information Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 07:57:16 garage volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 07:57:16 garage volumio[1133]: verbose: New Socket.io Connection to 192.168.4.24 from 192.168.4.28 UA: Mozilla/5.0 (iPad; CPU OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: websocket Total Clients: 8 Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.226-04:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=http://cddb.volumio.org duration=261.683304ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 07:57:16 garage volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::volumioGetQueue Oct 16 07:57:16 garage volumio[1133]: info: CoreStateMachine::getQueue Oct 16 07:57:16 garage volumio[1133]: info: CorePlayQueue::getQueue Oct 16 07:57:16 garage volumio[1133]: info: Received Get System Version Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 07:57:16 garage volumio[1133]: info: Received Get System Info Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 07:57:16 garage volumio[1133]: info: Discovery: Getting this device information Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 07:57:16 garage volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 07:57:16 garage volumio[1133]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 16 07:57:16 garage volumio5-onboarding[2262]: time=2026-10-16T07:57:16.297-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.28:49620 @ 0x34d0900" latency=5.918744ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=333.410651ms Oct 16 07:57:16 garage sudo[4149]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 16 07:57:16 garage sudo[4149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:57:16 garage sudo[4149]: pam_unix(sudo:session): session closed for user root Oct 16 07:57:16 garage sudo[4154]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 07:57:16 garage sudo[4154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:57:16 garage sudo[4165]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 07:57:16 garage sudo[4165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 07:57:16 garage sudo[4161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 07:57:16 garage sudo[4161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:57:16 garage volumio[1133]: info: Received Get System Version Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 07:57:16 garage volumio[1133]: info: Received Get System Info Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 07:57:16 garage sudo[4154]: pam_unix(sudo:session): session closed for user root Oct 16 07:57:16 garage volumio[1133]: info: Discovery: Getting this device information Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 07:57:16 garage volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::volumioGetState Oct 16 07:57:16 garage volumio[1133]: info: CorePlayQueue::getTrack 0 Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::volumioGetQueue Oct 16 07:57:16 garage volumio[1133]: info: CoreStateMachine::getQueue Oct 16 07:57:16 garage volumio[1133]: info: CorePlayQueue::getQueue Oct 16 07:57:16 garage sudo[4161]: pam_unix(sudo:session): session closed for user root Oct 16 07:57:16 garage sudo[4165]: pam_unix(sudo:session): session closed for user root Oct 16 07:57:16 garage sudo[4171]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 07:57:16 garage sudo[4171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:57:16 garage sudo[4171]: pam_unix(sudo:session): session closed for user root Oct 16 07:57:16 garage sudo[4174]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 07:57:16 garage sudo[4174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 07:57:16 garage sudo[4174]: pam_unix(sudo:session): session closed for user root Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 07:57:16 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 07:57:19 garage volumio[1133]: info: CALLMETHOD: user_interface Systeminfo runBench [object Object] Oct 16 07:57:19 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , runBench Oct 16 07:57:21 garage volumio[1133]: info: CALLMETHOD: user_interface Systeminfo runSysbench undefined Oct 16 07:57:21 garage volumio[1133]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , runSysbench Oct 16 07:57:21 garage volumio[1133]: info: Starting full sysbench sequence... Oct 16 07:57:21 garage volumio[1133]: info: CoreCommandRouter::Close All Modals sent Oct 16 07:57:21 garage volumio[1133]: error: Sysbench failed: Command failed: sysbench cpu --threads=$(nproc) --time=10 run Oct 16 07:57:21 garage volumio[1133]: /bin/sh: 1: sysbench: not found Oct 16 07:57:21 garage volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 07:57:21 garage volumio[1133]: Error: Command failed: sysbench cpu --threads=$(nproc) --time=10 run Oct 16 07:57:21 garage volumio[1133]: /bin/sh: 1: sysbench: not found Oct 16 07:57:21 garage volumio[1133]: at ChildProcess.exithandler (node:child_process:421:12) Oct 16 07:57:21 garage volumio[1133]: at ChildProcess.emit (node:events:514:28) Oct 16 07:57:21 garage volumio[1133]: at maybeClose (node:internal/child_process:1105:16) Oct 16 07:57:21 garage volumio[1133]: at Socket. (node:internal/child_process:457:11) Oct 16 07:57:21 garage volumio[1133]: at Socket.emit (node:events:514:28) Oct 16 07:57:21 garage volumio[1133]: at Pipe. (node:net:337:12) { Oct 16 07:57:21 garage volumio[1133]: code: 127, Oct 16 07:57:21 garage volumio[1133]: killed: false, Oct 16 07:57:21 garage volumio[1133]: signal: null, Oct 16 07:57:21 garage volumio[1133]: cmd: 'sysbench cpu --threads=$(nproc) --time=10 run' Oct 16 07:57:21 garage volumio[1133]: } Oct 16 07:57:21 garage volumio[1133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 07:57:22 garage sudo[4212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 07:56' Oct 16 07:57:22 garage sudo[4212]: 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="ebd772339e193fb4b3a98c68e63a0009e72d6f27" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 14 07:39:23 UTC 2026" VOLUMIO_VERSION="4.204" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="915e71dafc26fd850574d238b4f947d5"