Feb 21 17:19:00 volumio haveged[640]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Feb 21 17:19:00 volumio haveged[640]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 Feb 21 17:19:00 volumio haveged[640]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9952 Feb 21 17:19:00 volumio haveged[640]: haveged: fills: 0, generated: 0 Feb 21 17:19:00 volumio (udev-worker)[506]: Network interface NamePolicy= disabled on kernel command line. Feb 21 17:19:00 volumio (udev-worker)[503]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Feb 21 17:19:00 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Feb 21 17:19:00 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... Feb 21 17:19:00 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Feb 21 17:19:00 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Feb 21 17:19:00 volumio dhcpcd[824]: dhcpcd_prestartinterface: Operation not possible due to RF-kill Feb 21 17:19:00 volumio dhcpcd[824]: wlan0: waiting for carrier Feb 21 17:19:00 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Feb 21 17:19:00 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Feb 21 17:19:00 volumio bluetoothd[908]: Bluetooth daemon 5.83 Feb 21 17:19:00 volumio systemd[1]: Started bluetooth.service - Bluetooth service. Feb 21 17:19:00 volumio bluetoothd[908]: Starting SDP server Feb 21 17:19:00 volumio bluetoothd[908]: Excluding (cli) sap Feb 21 17:19:00 volumio bluetoothd[908]: Excluding (cli) midi Feb 21 17:19:00 volumio bluetoothd[908]: Excluding (cli) battery Feb 21 17:19:00 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 21 17:19:00 volumio kernel: Bluetooth: BNEP filters: protocol multicast Feb 21 17:19:00 volumio kernel: Bluetooth: BNEP socket layer initialized Feb 21 17:19:00 volumio bluetoothd[908]: Bluetooth management interface 1.23 initialized Feb 21 17:19:00 volumio bluetoothd[908]: Battery Provider Manager created Feb 21 17:19:00 volumio dbus-daemon[667]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=908 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 21 17:19:00 volumio kernel: Bluetooth: MGMT ver 1.23 Feb 21 17:19:00 volumio systemd[1]: Starting bluealsa.service - BlueALSA service... Feb 21 17:19:00 volumio kernel: NET: Registered PF_ALG protocol family Feb 21 17:19:00 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Feb 21 17:19:01 volumio kernel: cryptd: max_cpu_qlen set to 1000 Feb 21 17:19:01 volumio dhcpcd[824]: eth0: carrier acquired Feb 21 17:19:01 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Feb 21 17:19:01 volumio sh[970]: wlan0=wlan0 Feb 21 17:19:01 volumio dhcpcd[824]: eth0: IAID 32:1f:77:fc Feb 21 17:19:01 volumio dhcpcd[824]: eth0: adding address fe80::adea:55ef:76b2:6297 Feb 21 17:19:01 volumio dhcpcd[824]: ipv6_addaddr1: Permission denied Feb 21 17:19:01 volumio dhcpcd[824]: eth0: probing address 192.168.1.108/24 Feb 21 17:19:01 volumio dhcpcd[824]: eth0: soliciting an IPv6 router Feb 21 17:19:01 volumio bluetoothd[908]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Feb 21 17:19:01 volumio bluetoothd[908]: bap: Operation not supported (95) Feb 21 17:19:01 volumio bluetoothd[908]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Feb 21 17:19:01 volumio bluetoothd[908]: Failed to set mode: Failed (0x03) Feb 21 17:19:01 volumio dbus-daemon[667]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 21 17:19:01 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Feb 21 17:19:02 volumio bluealsa[955]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Feb 21 17:19:02 volumio bluealsa[955]: main.c:663: Starting main dispatching loop Feb 21 17:19:02 volumio systemd[1]: Started bluealsa.service - BlueALSA service. Feb 21 17:19:02 volumio bluealsa[955]: main.c:118: Acquired D-Bus service name: org.bluealsa Feb 21 17:19:02 volumio bluealsa[955]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Feb 21 17:19:02 volumio systemd[1]: Reached target bluetooth.target - Bluetooth Support. Feb 21 17:19:02 volumio bluealsa[955]: ../src/ba-adapter.c:53: Couldn't get HCI version: Network is down Feb 21 17:19:02 volumio bluealsa[955]: bluez.c:153: Registering media application: /org/bluez/hci0 Feb 21 17:19:02 volumio bluealsa[955]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Feb 21 17:19:02 volumio bluealsa[955]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Feb 21 17:19:02 volumio bluealsa[955]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Feb 21 17:19:02 volumio bluealsa[955]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Feb 21 17:19:02 volumio bluetoothd[908]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Feb 21 17:19:02 volumio bluetoothd[908]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Feb 21 17:19:02 volumio bluetoothd[908]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Feb 21 17:19:02 volumio bluetoothd[908]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: New ethernet state: connected Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: First start: yes Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: === END TRANSITION === Feb 21 17:19:02 volumio wireless.js[690]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 21 17:19:03 volumio sudo[996]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 21 17:19:03 volumio sudo[996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 17:19:03 volumio sudo[996]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:03 volumio sudo[998]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 21 17:19:03 volumio sudo[998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 17:19:03 volumio sudo[998]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:03 volumio wireless.js[690]: WIRELESS.JS - INFO: Cleaning previous... Feb 21 17:19:03 volumio sudo[1001]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 21 17:19:03 volumio sudo[1001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 17:19:03 volumio sudo[1001]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:03 volumio wireless.js[690]: WIRELESS.JS - INFO: ensureInterfaceReady: ifconfig up failed: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up Feb 21 17:19:03 volumio wireless.js[690]: SIOCSIFFLAGS: Operation not possible due to RF-kill Feb 21 17:19:03 volumio volumio-remote-updater[686]: [2026-02-21 17:19:03] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 21 17:19:03 volumio volumio-remote-updater[686]: [2026-02-21 17:19:03] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 21 17:19:03 volumio volumio-remote-updater[686]: [2026-02-21 17:19:03] [error] handle_connect error: Connection refused Feb 21 17:19:05 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Feb 21 17:19:05 volumio dhcpcd[824]: eth0: using static address 192.168.1.108/24 Feb 21 17:19:05 volumio avahi-daemon[666]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.108. Feb 21 17:19:05 volumio avahi-daemon[666]: New relevant interface eth0.IPv4 for mDNS. Feb 21 17:19:05 volumio avahi-daemon[666]: Registering new address record for 192.168.1.108 on eth0.IPv4. Feb 21 17:19:05 volumio dhcpcd[824]: eth0: adding route to 192.168.1.0/24 Feb 21 17:19:05 volumio dhcpcd[824]: eth0: adding default route via 192.168.1.1 Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: UdevCoordinator: udev settle timeout or error: Error: spawnSync /bin/sh ETIMEDOUT Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 5ms Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: dc:a6:32:1f:77:fd) Feb 21 17:19:08 volumio sudo[1026]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 21 17:19:08 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:08 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:08 volumio sudo[1034]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 21 17:19:08 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:08 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:08 volumio wireless.js[690]: command failed: Network is down (-100) Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 21 17:19:08 volumio sudo[1044]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 21 17:19:08 volumio sudo[1044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 17:19:08 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: keepWlanUpWithoutIP: Failed to bring interface UP: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up Feb 21 17:19:08 volumio wireless.js[690]: SIOCSIFFLAGS: Operation not possible due to RF-kill Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: Failed to maintain scan mode: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up Feb 21 17:19:08 volumio wireless.js[690]: SIOCSIFFLAGS: Operation not possible due to RF-kill Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: Falling back to interface DOWN Feb 21 17:19:08 volumio wireless.js[690]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 21 17:19:08 volumio systemd[1]: Started wireless.service - Wireless Services. Feb 21 17:19:09 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 21 17:19:09 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 21 17:19:09 volumio volumio-cpu-tweak[1050]: Setting RT Priority for mpd Feb 21 17:19:09 volumio volumio-cpu-tweak[1064]: pid 35's current scheduling policy: SCHED_OTHER Feb 21 17:19:09 volumio volumio-cpu-tweak[1064]: pid 35's current scheduling priority: 0 Feb 21 17:19:09 volumio volumio-cpu-tweak[1050]: Setting MPD Affinity Feb 21 17:19:09 volumio volumio-cpu-tweak[1065]: pid 3's current affinity mask: f Feb 21 17:19:09 volumio volumio-cpu-tweak[1050]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 21 17:19:09 volumio volumio-cpu-tweak[1050]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 21 17:19:09 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 21 17:19:11 volumio volumio-remote-updater[686]: [2026-02-21 17:19:11] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 21 17:19:11 volumio volumio-remote-updater[686]: [2026-02-21 17:19:11] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 21 17:19:11 volumio volumio-remote-updater[686]: [2026-02-21 17:19:11] [error] handle_connect error: Connection refused Feb 21 17:19:14 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 21 17:19:14 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 17:19:14 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 17:19:14 volumio upmpdcli[1069]: Could not open config: /tmp/upmpdcli.conf Feb 21 17:19:14 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 21 17:19:14 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 21 17:19:15 volumio volumio[1049]: info: ------------------------------------------- Feb 21 17:19:15 volumio volumio[1049]: info: ----- Volumio3 ---- Feb 21 17:19:15 volumio volumio[1049]: info: ------------------------------------------- Feb 21 17:19:15 volumio volumio[1049]: info: ----- System startup ---- Feb 21 17:19:15 volumio volumio[1049]: info: ------------------------------------------- Feb 21 17:19:16 volumio volumio[1049]: info: MYVOLUMIO Environment detected Feb 21 17:19:17 volumio volumio[1049]: info: Plugin folders cleanup Feb 21 17:19:17 volumio volumio[1049]: info: Scanning into folder /volumio/app/plugins/ Feb 21 17:19:17 volumio volumio[1049]: info: Scanning category audio_interface Feb 21 17:19:17 volumio volumio[1049]: info: Scanning category miscellanea Feb 21 17:19:17 volumio volumio[1049]: info: Scanning category music_service Feb 21 17:19:17 volumio volumio[1049]: info: Scanning category plugins.json Feb 21 17:19:17 volumio volumio[1049]: info: Scanning category system_controller Feb 21 17:19:17 volumio volumio[1049]: info: Scanning category user_interface Feb 21 17:19:17 volumio volumio[1049]: info: Scanning into folder /data/plugins/ Feb 21 17:19:17 volumio volumio[1049]: info: Plugin folders cleanup completed Feb 21 17:19:17 volumio volumio[1049]: info: ------------------------------------------- Feb 21 17:19:17 volumio volumio[1049]: info: ----- Core plugins startup ---- Feb 21 17:19:17 volumio volumio[1049]: info: ------------------------------------------- Feb 21 17:19:17 volumio volumio[1049]: info: Loading plugins from folder /volumio/app/plugins/ Feb 21 17:19:17 volumio volumio[1049]: info: Adding plugin upnp to MyMusic Plugins Feb 21 17:19:17 volumio volumio[1049]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 21 17:19:17 volumio volumio[1049]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 21 17:19:17 volumio volumio[1049]: info: Loading plugins from folder /data/plugins/ Feb 21 17:19:17 volumio volumio[1049]: info: Loading plugin "system"... Feb 21 17:19:17 volumio volumio[1049]: info: Loading plugin "appearance"... Feb 21 17:19:17 volumio kernel: sd 0:0:0:0: [sda] tag#10 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD Feb 21 17:19:17 volumio kernel: sd 0:0:0:0: [sda] tag#10 CDB: opcode=0x88 88 00 00 00 00 00 00 06 48 00 00 00 00 08 00 00 Feb 21 17:19:19 volumio volumio[1049]: info: Loading plugin "network"... Feb 21 17:19:19 volumio volumio[1049]: info: Refreshing Cached IP Addresses Feb 21 17:19:19 volumio sudo[1083]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 17:19:19 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:19 volumio sudo[1085]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 17:19:19 volumio sudo[1085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:19 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:19 volumio sudo[1085]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:19 volumio volumio[1049]: info: Loading plugin "services"... Feb 21 17:19:19 volumio sudo[1093]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 21 17:19:19 volumio sudo[1093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:19 volumio sudo[1093]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:19 volumio volumio[1049]: info: Loading plugin "volumio5onboarding"... Feb 21 17:19:20 volumio volumio[1049]: info: Loading plugin "alsa_controller"... Feb 21 17:19:20 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 17:19:20 volumio volumio[1049]: info: Loading plugin "wizard"... Feb 21 17:19:20 volumio volumio[1049]: info: Loading plugin "networkfs"... Feb 21 17:19:20 volumio volumio[1049]: info: Starting Udev Watcher for removable devices Feb 21 17:19:20 volumio volumio[1049]: info: Ignoring mount for partition: boot Feb 21 17:19:20 volumio volumio[1049]: info: Ignoring mount for partition: volumio Feb 21 17:19:20 volumio volumio[1049]: info: Ignoring mount for partition: volumio_data Feb 21 17:19:20 volumio volumio[1049]: error: Cannot associate FS Label, not mounting Feb 21 17:19:20 volumio volumio[1049]: error: Cannot associate FS Label, not mounting Feb 21 17:19:20 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 17:19:20 volumio volumio[1049]: info: Loading plugin "volumio_command_line_client"... Feb 21 17:19:20 volumio volumio[1049]: info: Loading plugin "upnp"... Feb 21 17:19:20 volumio volumio[1049]: info: [1771661960752] Starting Upmpd Daemon Feb 21 17:19:20 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 17:19:20 volumio volumio[1049]: info: Loading plugin "my_music"... Feb 21 17:19:20 volumio volumio[1049]: info: Loading plugin "mpd"... Feb 21 17:19:21 volumio volumio-remote-updater[686]: [2026-02-21 17:19:21] [connect] Successful connection Feb 21 17:19:21 volumio volumio[1049]: info: Loading plugin "upnp_browser"... Feb 21 17:19:24 volumio volumio[1049]: info: Starting UPNP Browser Feb 21 17:19:24 volumio volumio[1049]: info: Loading plugin "alarm-clock"... Feb 21 17:19:24 volumio volumio[1049]: info: Loading plugin "airplay_emulation"... Feb 21 17:19:24 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 21 17:19:24 volumio volumio[1049]: info: Starting Shairport Sync Feb 21 17:19:24 volumio volumio[1049]: info: Loading plugin "last_100"... Feb 21 17:19:24 volumio volumio[1049]: info: Loading plugin "webradio"... Feb 21 17:19:25 volumio volumio[1049]: info: Loading plugin "i2s_dacs"... Feb 21 17:19:25 volumio volumio[1049]: info: Loading plugin "volumiodiscovery"... Feb 21 17:19:25 volumio volumio[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 17:19:25 volumio volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 17:19:25 volumio volumio[1049]: *** WARNING *** For more information see Feb 21 17:19:25 volumio volumio[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 17:19:25 volumio volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 17:19:25 volumio volumio[1049]: *** WARNING *** For more information see Feb 21 17:19:25 volumio node[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 17:19:25 volumio node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 17:19:25 volumio node[1049]: *** WARNING *** For more information see Feb 21 17:19:25 volumio node[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 17:19:25 volumio node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 17:19:25 volumio node[1049]: *** WARNING *** For more information see Feb 21 17:19:25 volumio volumio[1049]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 21 17:19:25 volumio volumio[1049]: info: Discovery: Started advertising with name: Volumio Feb 21 17:19:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 17:19:25 volumio volumio[1049]: info: Loading plugin "outputs"... Feb 21 17:19:25 volumio volumio[1049]: info: Loading plugin "albumart"... Feb 21 17:19:25 volumio volumio[1049]: info: Plugin example_plugin is not enabled Feb 21 17:19:25 volumio volumio[1049]: info: Loading plugin "inputs"... Feb 21 17:19:25 volumio volumio[1049]: info: Loading plugin "updater_comm"... Feb 21 17:19:26 volumio volumio[1049]: info: Plugin mpdemulation is not enabled Feb 21 17:19:26 volumio volumio[1049]: info: Loading plugin "rest_api"... Feb 21 17:19:26 volumio volumio[1049]: info: Loading plugin "websocket"... Feb 21 17:19:26 volumio kernel: sd 0:0:0:0: [sda] tag#0 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD Feb 21 17:19:26 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x12 12 00 00 00 24 00 Feb 21 17:19:26 volumio volumio[1049]: info: Starting Socket.io Server version 1.7.4 Feb 21 17:19:26 volumio kernel: scsi host0: uas_eh_device_reset_handler start Feb 21 17:19:26 volumio volumio[1049]: info: Loading i18n strings for locale ko Feb 21 17:19:26 volumio kernel: usb 1-1.3: reset high-speed USB device number 3 using xhci_hcd Feb 21 17:19:26 volumio volumio[1049]: Updating browse sources language Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 17:19:26 volumio kernel: scsi host0: uas_eh_device_reset_handler success Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::initPlayerControls Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:19:26 volumio volumio[1049]: Express server listening on port 3000 Feb 21 17:19:26 volumio volumio[1049]: [Metrics] WebUI: 13s 406.66ms Feb 21 17:19:26 volumio volumio[1049]: info: CoreStateMachine::resetVolumioState Feb 21 17:19:26 volumio volumio[1049]: info: CoreStateMachine::getcurrentVolume Feb 21 17:19:26 volumio volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 17:19:27 volumio volumio[1049]: info: Cannot read play queue from file Feb 21 17:19:27 volumio volumio[1049]: info: Volumio Network Manager: Network status updated: 1 Feb 21 17:19:27 volumio volumio[1049]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 21 17:19:27 volumio volumio[1049]: wlan0 Interface doesn't support scanning : Network is down Feb 21 17:19:27 volumio volumio[1049]: info: Cannot use regular scanning, forcing with ap-force Feb 21 17:19:27 volumio sudo[1134]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 21 17:19:27 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:27 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:27 volumio volumio[1049]: command failed: Network is down (-100) Feb 21 17:19:27 volumio volumio[1049]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 21 17:19:27 volumio volumio[1049]: command failed: Network is down (-100) Feb 21 17:19:27 volumio volumio[1118]: Forking 3 albumart workers Feb 21 17:19:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:19:28 volumio volumio[1049]: info: VolumeController:: Volume=46 Mute =false Feb 21 17:19:29 volumio volumio[1049]: info: CoreStateMachine::pushState Feb 21 17:19:29 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:29 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 17:19:29 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Feb 21 17:19:29 volumio volumio[1049]: info: CoreStateMachine::updateTrackBlock Feb 21 17:19:29 volumio volumio[1049]: info: CorePlayQueue::getTrackBlock Feb 21 17:19:29 volumio volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 17:19:29 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 21 17:19:29 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 17:19:29 volumio volumio[1049]: info: CoreStateMachine::setRepeat null single undefined Feb 21 17:19:29 volumio volumio[1049]: info: CoreStateMachine::pushState Feb 21 17:19:29 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:29 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Feb 21 17:19:29 volumio volumio[1049]: info: CoreStateMachine::setRandom null Feb 21 17:19:29 volumio volumio[1049]: info: CoreStateMachine::pushState Feb 21 17:19:29 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:29 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 17:19:29 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Feb 21 17:19:29 volumio volumio[1049]: info: Setting Device type: Raspberry PI Feb 21 17:19:29 volumio upmpdcli[1175]: Could not open config: /tmp/upmpdcli.conf Feb 21 17:19:29 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 21 17:19:29 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 21 17:19:29 volumio volumio[1049]: info: Completed loading Core Plugins Feb 21 17:19:29 volumio volumio[1049]: info: Preparing to generate the ALSA configuration file Feb 21 17:19:29 volumio volumio[1049]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 21 17:19:29 volumio volumio[1049]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Feb 21 17:19:29 volumio volumio[1049]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 21 17:19:30 volumio volumio[1049]: info: Discovery: adding 95b823ba-0ff3-4076-82d2-570bc98eb1fc Feb 21 17:19:30 volumio volumio[1049]: info: Discovery: Found device Volumio Feb 21 17:19:30 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:19:30 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:30 volumio volumio[1049]: info: Discovery: this is already registered, 95b823ba-0ff3-4076-82d2-570bc98eb1fc Feb 21 17:19:30 volumio volumio[1049]: info: Discovery: Found device Volumio Feb 21 17:19:30 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:19:30 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:30 volumio volumio[1049]: info: Asound.conf file unchanged, so no further update is needed Feb 21 17:19:30 volumio volumio[1049]: info: Output device has changed, restarting MPD Feb 21 17:19:30 volumio volumio[1049]: info: Output device has changed, restarting Shairport Sync Feb 21 17:19:30 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:30 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:19:30 volumio sudo[1187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 17:19:30 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:30 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:30 volumio sudo[1190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 17:19:30 volumio volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 17:19:30 volumio volumio[1049]: info: ___________ START PLUGINS ___________ Feb 21 17:19:30 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:30 volumio volumio[1049]: info: ControllerMpd::onStart: Initializing MPD Feb 21 17:19:30 volumio volumio[1049]: info: Creating MPD Configuration file Feb 21 17:19:31 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:19:31 volumio volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 17:19:31 volumio volumio[1049]: info: [1771661971034] CoreMusicLibrary::Adding element 미디어 서버 Feb 21 17:19:31 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 17:19:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 17:19:31 volumio sudo[1203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 21 17:19:31 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:31 volumio volumio[1049]: info: UPNP Browser: Client initialized successfully Feb 21 17:19:31 volumio sudo[1205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 17:19:31 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:31 volumio sudo[1208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 17:19:31 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:31 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:31 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:31 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:19:31 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 21 17:19:31 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:31 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 21 17:19:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 17:19:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 17:19:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 17:19:31 volumio volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 17:19:31 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:31 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:19:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 17:19:32 volumio volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 17:19:32 volumio volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 17:19:32 volumio volumio[1049]: info: [1771661972265] CoreMusicLibrary::Adding element Last_100 Feb 21 17:19:32 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 17:19:32 volumio volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 17:19:32 volumio volumio[1049]: info: [1771661972337] CoreMusicLibrary::Adding element Webradio Feb 21 17:19:32 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 17:19:32 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 17:19:32 volumio volumio5-onboarding[1212]: time=2026-02-21T17:19:32.362+09:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 21 17:19:32 volumio volumio[1049]: info: Initializing BBC Radios Feb 21 17:19:32 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 17:19:32 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:19:32 volumio volumio[1049]: info: Volumio Calling Home Feb 21 17:19:36 volumio volumio-remote-updater[686]: [2026-02-21 17:19:36] [connect] Successful connection Feb 21 17:19:38 volumio sudo[1249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 17:19:38 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:38 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:38 volumio sudo[1251]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 17:19:38 volumio sudo[1251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:38 volumio sudo[1251]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:38 volumio volumio[1049]: info: MPD Permissions set Feb 21 17:19:38 volumio volumio[1049]: info: MPD Permissions set Feb 21 17:19:38 volumio volumio[1049]: info: VolumeController:: Volume=46 Mute =false Feb 21 17:19:38 volumio volumio[1049]: info: CoreStateMachine::pushState Feb 21 17:19:38 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:38 volumio volumio[1049]: info: CoreCommandRouter::volumioPushState Feb 21 17:19:38 volumio volumio[1049]: 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: 1 Feb 21 17:19:38 volumio volumio[1049]: info: Volumio called home Feb 21 17:19:38 volumio volumio[1049]: 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: 1 Feb 21 17:19:38 volumio volumio[1049]: info: Received Get System Info Feb 21 17:19:38 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 17:19:38 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 17:19:38 volumio volumio[1049]: info: Discovery: Getting this device information Feb 21 17:19:38 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:19:38 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:38 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 17:19:38 volumio volumio5-onboarding[1212]: time=2026-02-21T17:19:38.980+09:00 level=INFO msg="system info for 38a115ee1200b1d0c495257cfbcb4eab" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 21 17:19:39 volumio volumio-remote-updater[686]: [2026-02-21 17:19:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771661976 101 Feb 21 17:19:39 volumio volumio[1049]: 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 Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:39 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:40 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:19:40 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:19:40 volumio volumio[1049]: info: Received Get System Info Feb 21 17:19:40 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 17:19:40 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 17:19:40 volumio volumio[1049]: info: Discovery: Getting this device information Feb 21 17:19:40 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:19:40 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:40 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 17:19:40 volumio volumio[1138]: Starting albumart workers Feb 21 17:19:41 volumio volumio[1137]: Starting albumart workers Feb 21 17:19:41 volumio volumio[1139]: Starting albumart workers Feb 21 17:19:41 volumio volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 21 17:19:41 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 17:19:41 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 17:19:41 volumio volumio5-onboarding[1212]: time=2026-02-21T17:19:41.676+09:00 level=INFO msg="enabling local network discovery" Feb 21 17:19:41 volumio volumio5-onboarding[1212]: time=2026-02-21T17:19:41.750+09:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 21 17:19:42 volumio volumio[1049]: info: Received Get System Info Feb 21 17:19:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 17:19:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 17:19:42 volumio volumio[1049]: info: Discovery: Getting this device information Feb 21 17:19:42 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:19:42 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:42 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 17:19:42 volumio volumio[1049]: info: Starting Shairport Sync Feb 21 17:19:42 volumio volumio[1049]: info: Starting Shairport Sync Feb 21 17:19:42 volumio volumio[1049]: info: Starting Shairport Sync Feb 21 17:19:43 volumio sudo[1285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 21 17:19:43 volumio volumio-remote-updater[686]: Test mode disabled Feb 21 17:19:43 volumio volumio-remote-updater[686]: Alpha mode disabled Feb 21 17:19:43 volumio volumio-remote-updater[686]: Alpha legacy test mode disabled Feb 21 17:19:43 volumio sudo[1289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 17:19:43 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:43 volumio sudo[1287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 17:19:43 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:43 volumio sudo[1287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:43 volumio sudo[1291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 17:19:43 volumio sudo[1291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:19:43 volumio volumio[1049]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 21 17:19:43 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 21 17:19:44 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 17:19:44 volumio volumio5-onboarding[1212]: time=2026-02-21T17:19:44.111+09: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" Feb 21 17:19:44 volumio volumio5-onboarding[1212]: time=2026-02-21T17:19:44.115+09: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" Feb 21 17:19:44 volumio volumio5-onboarding[1212]: time=2026-02-21T17:19:44.116+09: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" Feb 21 17:19:44 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:19:44 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:19:44 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 17:19:44 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 17:19:44 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 21 17:19:44 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 17:19:44 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 17:19:44 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root Feb 21 17:19:44 volumio volumio[1049]: info: Upmpdcli Daemon Started Feb 21 17:19:55 volumio systemd-udevd[479]: sda: Worker [509] processing SEQNUM=1980 is taking a long time Feb 21 17:19:56 volumio kernel: sd 0:0:0:0: [sda] tag#7 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD Feb 21 17:19:56 volumio kernel: sd 0:0:0:0: [sda] tag#7 CDB: opcode=0x88 88 00 00 00 00 01 d1 c0 be 00 00 00 00 08 00 00 Feb 21 17:19:56 volumio kernel: scsi host0: uas_eh_device_reset_handler start Feb 21 17:19:56 volumio kernel: sd 0:0:0:0: [sda] tag#6 uas_zap_pending 0 uas-tag 2 inflight: CMD Feb 21 17:19:56 volumio kernel: sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x88 88 00 00 00 00 00 00 06 48 00 00 00 00 08 00 00 Feb 21 17:19:57 volumio kernel: usb 1-1.3: reset high-speed USB device number 3 using xhci_hcd Feb 21 17:19:57 volumio kernel: scsi host0: uas_eh_device_reset_handler success Feb 21 17:20:03 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 17:20:03 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:20:03 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 17:20:03 volumio volumio[1049]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Feb 21 17:20:03 volumio volumio[1049]: info: ------------------------------------------- Feb 21 17:20:03 volumio volumio[1049]: info: ----- MyVolumio plugins startup ---- Feb 21 17:20:03 volumio volumio[1049]: info: ------------------------------------------- Feb 21 17:20:03 volumio volumio[1049]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 21 17:20:07 volumio sudo[1359]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 17:20:07 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:20:07 volumio sudo[1361]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 17:20:07 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Feb 21 17:20:07 volumio sudo[1361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:20:07 volumio sudo[1361]: pam_unix(sudo:session): session closed for user root Feb 21 17:20:08 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.3 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:08 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 17:20:08 volumio volumio[1049]: info: Received Get System Info Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 17:20:08 volumio volumio[1049]: info: Discovery: Getting this device information Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:08 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:08 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:08 volumio volumio[1049]: info: Listing playlists Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 17:20:08 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 17:20:09 volumio sudo[1365]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 17:20:09 volumio sudo[1365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:20:09 volumio sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 17:20:09 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:20:09 volumio sudo[1365]: pam_unix(sudo:session): session closed for user root Feb 21 17:20:09 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Feb 21 17:20:09 volumio volumio[1049]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.3 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:09 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 17:20:09 volumio volumio[1049]: info: Received Get System Info Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 17:20:09 volumio volumio[1049]: info: Discovery: Getting this device information Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:09 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:09 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:09 volumio volumio[1049]: info: Listing playlists Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 17:20:09 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 17:20:10 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:10 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 17:20:11 volumio volumio[1049]: info: Received Get System Info Feb 21 17:20:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 17:20:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 17:20:11 volumio volumio[1049]: info: Discovery: Getting this device information Feb 21 17:20:11 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:11 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 17:20:11 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 21 17:20:11 volumio volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 21 17:20:11 volumio volumio[1049]: info: Adding plugin bluetooth to MyMusic Plugins Feb 21 17:20:11 volumio volumio[1049]: info: Adding plugin multiroom to MyMusic Plugins Feb 21 17:20:11 volumio volumio[1049]: info: Adding plugin metavolumio to MyMusic Plugins Feb 21 17:20:11 volumio volumio[1049]: info: Adding plugin cd_controller to MyMusic Plugins Feb 21 17:20:11 volumio volumio[1049]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 21 17:20:11 volumio volumio[1049]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 21 17:20:12 volumio volumio[1049]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 21 17:20:12 volumio volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 21 17:20:13 volumio volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 21 17:20:13 volumio volumio[1049]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 21 17:20:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:20:13 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:20:13 volumio volumio[1049]: info: Starting MyVolumio Remote Streaming Endpoints Feb 21 17:20:13 volumio volumio[1049]: info: MyVolumio not started Feb 21 17:20:13 volumio volumio[1049]: info: Initializing device activation check Feb 21 17:20:14 volumio volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 21 17:20:14 volumio volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 21 17:20:15 volumio volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 21 17:20:15 volumio volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 21 17:20:15 volumio volumio[1049]: info: Streaming services startup Feb 21 17:20:15 volumio volumio[1049]: info: Starting Streaming Daemon Feb 21 17:20:15 volumio sudo[1387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 21 17:20:15 volumio volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 21 17:20:15 volumio sudo[1387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:20:16 volumio sudo[1387]: pam_unix(sudo:session): session closed for user root Feb 21 17:20:16 volumio volumio[1049]: error: Cannot start Volumio Streaming Daemon Feb 21 17:20:16 volumio volumio[1049]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 21 17:20:16 volumio volumio[1049]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 21 17:20:19 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:20:25 volumio volumio[1049]: info: Retrieving Cloud Streaming UI Feb 21 17:20:25 volumio volumio[1049]: info: Getting Tidal Cloud Configuration Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:20:25 volumio volumio[1049]: info: Getting Qobuz Cloud Configuration Feb 21 17:20:25 volumio volumio[1049]: info: Asking plugin for UI Config Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:20:25 volumio volumio[1049]: info: Getting Spotify Cloud Configuration Feb 21 17:20:25 volumio volumio[1049]: info: Asking plugin for UI Config Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:20:25 volumio volumio[1049]: info: Saving Spotify Acccount Feb 21 17:20:25 volumio volumio[1049]: info: Got Tidal Cloud Configuration Feb 21 17:20:25 volumio volumio[1049]: info: Got it Feb 21 17:20:25 volumio volumio[1049]: info: Got it Feb 21 17:20:25 volumio volumio[1049]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:20:25 volumio volumio[1049]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::volumioGetBrowseSources Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::volumioGetBrowseSources Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::volumioGetBrowseSources Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 21 17:20:25 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 21 17:20:27 volumio kernel: scsi host0: uas_eh_device_reset_handler start Feb 21 17:20:27 volumio kernel: sd 0:0:0:0: [sda] tag#2 uas_zap_pending 0 uas-tag 2 inflight: CMD Feb 21 17:20:27 volumio kernel: sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x88 88 00 00 00 00 00 00 06 48 00 00 00 00 08 00 00 Feb 21 17:20:27 volumio kernel: sd 0:0:0:0: [sda] tag#3 uas_zap_pending 0 uas-tag 3 inflight: CMD Feb 21 17:20:27 volumio kernel: sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x88 88 00 00 00 00 01 d1 c0 be 00 00 00 00 08 00 00 Feb 21 17:20:27 volumio kernel: usb 1-1.3: reset high-speed USB device number 3 using xhci_hcd Feb 21 17:20:27 volumio kernel: scsi host0: uas_eh_device_reset_handler success Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 17:20:28 volumio volumio[1049]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 21 17:20:28 volumio volumio[1049]: info: Received Get System Version Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 17:20:28 volumio volumio[1049]: info: Received Get System Info Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 17:20:28 volumio volumio[1049]: info: Discovery: Getting this device information Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:28 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 17:20:28 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 17:20:28 volumio volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 21 17:20:29 volumio volumio[1049]: info: CoreCommandRouter::volumioGetState Feb 21 17:20:29 volumio volumio[1049]: info: CorePlayQueue::getTrack 0 Feb 21 17:20:29 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 21 17:20:29 volumio volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 21 17:20:29 volumio volumio[1049]: verbose: ControllerMpd::sendMpdCommand rescan Feb 21 17:20:29 volumio volumio[1049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 17:20:29 volumio volumio[1049]: TypeError: Cannot read properties of undefined (reading 'then') Feb 21 17:20:29 volumio volumio[1049]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Feb 21 17:20:29 volumio volumio[1049]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) Feb 21 17:20:29 volumio volumio[1049]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 21 17:20:29 volumio volumio[1049]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1098:26) Feb 21 17:20:29 volumio volumio[1049]: at Socket.emit (node:events:514:28) Feb 21 17:20:29 volumio volumio[1049]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 21 17:20:29 volumio volumio[1049]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 21 17:20:29 volumio volumio[1049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 17:20:30 volumio sudo[1426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 17:19' Feb 21 17:20:30 volumio sudo[1426]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"