Aug 28 23:49:00 volumio4b systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Aug 28 23:49:00 volumio4b systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Aug 28 23:49:00 volumio4b systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Aug 28 23:49:00 volumio4b systemd[1]: Starting bluetooth.service - Bluetooth service... Aug 28 23:49:00 volumio4b systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Aug 28 23:49:00 volumio4b dhcpcd[790]: wlan0: waiting for carrier Aug 28 23:49:00 volumio4b kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled Aug 28 23:49:00 volumio4b kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 28 23:49:00 volumio4b kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Aug 28 23:49:00 volumio4b systemd[1]: Reloading. Aug 28 23:49:00 volumio4b bluetoothd[915]: Bluetooth daemon 5.83 Aug 28 23:49:00 volumio4b bluetoothd[915]: Starting SDP server Aug 28 23:49:00 volumio4b bluetoothd[915]: Excluding (cli) sap Aug 28 23:49:00 volumio4b bluetoothd[915]: Excluding (cli) scanparam Aug 28 23:49:00 volumio4b bluetoothd[915]: Excluding (cli) midi Aug 28 23:49:00 volumio4b bluetoothd[915]: Excluding (cli) battery Aug 28 23:49:00 volumio4b kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 28 23:49:00 volumio4b kernel: Bluetooth: BNEP filters: protocol multicast Aug 28 23:49:00 volumio4b kernel: Bluetooth: BNEP socket layer initialized Aug 28 23:49:00 volumio4b bluetoothd[915]: Bluetooth management interface 1.23 initialized Aug 28 23:49:00 volumio4b bluetoothd[915]: Battery Provider Manager created Aug 28 23:49:00 volumio4b dbus-daemon[648]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=915 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 28 23:49:00 volumio4b kernel: Bluetooth: MGMT ver 1.23 Aug 28 23:49:00 volumio4b kernel: NET: Registered PF_ALG protocol family Aug 28 23:49:00 volumio4b kernel: cryptd: max_cpu_qlen set to 1000 Aug 28 23:49:00 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Aug 28 23:49:00 volumio4b bluetoothd[915]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Aug 28 23:49:00 volumio4b bluetoothd[915]: bap: Operation not supported (95) Aug 28 23:49:00 volumio4b bluetoothd[915]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Aug 28 23:49:00 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 28 23:49:01 volumio4b systemd[1]: Reloading. Aug 28 23:49:02 volumio4b systemd[1]: Started bluetooth.service - Bluetooth service. Aug 28 23:49:02 volumio4b sh[1027]: wlan0=wlan0 Aug 28 23:49:02 volumio4b systemd[1]: Starting bluealsa.service - BlueALSA service... Aug 28 23:49:02 volumio4b systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 28 23:49:02 volumio4b sudo[1031]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 28 23:49:02 volumio4b dbus-daemon[648]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 28 23:49:02 volumio4b sudo[1031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:02 volumio4b systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 28 23:49:02 volumio4b sudo[1031]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:02 volumio4b sudo[1033]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 28 23:49:02 volumio4b sudo[1033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:02 volumio4b bluealsa[1028]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Aug 28 23:49:02 volumio4b bluealsa[1028]: main.c:663: Starting main dispatching loop Aug 28 23:49:02 volumio4b systemd[1]: Started bluealsa.service - BlueALSA service. Aug 28 23:49:02 volumio4b bluealsa[1028]: main.c:118: Acquired D-Bus service name: org.bluealsa Aug 28 23:49:02 volumio4b bluealsa[1028]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Aug 28 23:49:02 volumio4b systemd[1]: Reached target bluetooth.target - Bluetooth Support. Aug 28 23:49:02 volumio4b bluealsa[1028]: bluez.c:153: Registering media application: /org/bluez/hci0 Aug 28 23:49:02 volumio4b bluealsa[1028]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Aug 28 23:49:02 volumio4b bluealsa[1028]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Aug 28 23:49:02 volumio4b bluealsa[1028]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Aug 28 23:49:02 volumio4b bluealsa[1028]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Aug 28 23:49:02 volumio4b bluetoothd[915]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 28 23:49:02 volumio4b bluetoothd[915]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 28 23:49:02 volumio4b bluetoothd[915]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 28 23:49:02 volumio4b bluetoothd[915]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 28 23:49:03 volumio4b sudo[1033]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:03 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Cleaning previous... Aug 28 23:49:03 volumio4b sudo[1039]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 28 23:49:03 volumio4b sudo[1039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:03 volumio4b sudo[1039]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:03 volumio4b kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 28 23:49:03 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:03] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 28 23:49:03 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:03] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 28 23:49:03 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:03] [error] handle_connect error: Connection refused Aug 28 23:49:04 volumio4b systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 28 23:49:08 volumio4b wireless.js[664]: WIRELESS.JS - INFO: UdevCoordinator: udev settle timeout or error: Error: spawnSync /bin/sh ETIMEDOUT Aug 28 23:49:08 volumio4b wireless.js[664]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 28 23:49:08 volumio4b wireless.js[664]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Aug 28 23:49:08 volumio4b wireless.js[664]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: d8:3a:dd:32:60:b1) Aug 28 23:49:08 volumio4b sudo[1046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 28 23:49:08 volumio4b sudo[1046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:08 volumio4b sudo[1046]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:08 volumio4b sudo[1054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 28 23:49:08 volumio4b sudo[1054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:10 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:10] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 28 23:49:10 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:10] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 28 23:49:10 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:10] [error] handle_connect error: Connection refused Aug 28 23:49:11 volumio4b sudo[1054]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:11 volumio4b wireless.js[664]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Aug 28 23:49:11 volumio4b sudo[1060]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Aug 28 23:49:11 volumio4b sudo[1060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:11 volumio4b sudo[1060]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:11 volumio4b wireless.js[664]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Aug 28 23:49:11 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Start wireless flow Aug 28 23:49:11 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Stopped hotspot (if there).. Aug 28 23:49:11 volumio4b sudo[1068]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 28 23:49:11 volumio4b sudo[1068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:11 volumio4b sudo[1068]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:11 volumio4b sudo[1070]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 28 23:49:11 volumio4b sudo[1070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:11 volumio4b sudo[1070]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:13 volumio4b systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 28 23:49:13 volumio4b systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 23:49:14 volumio4b systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 23:49:14 volumio4b upmpdcli[1075]: Could not open config: /tmp/upmpdcli.conf Aug 28 23:49:14 volumio4b systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 23:49:14 volumio4b systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 23:49:16 volumio4b wireless.js[664]: WIRELESS.JS - INFO: UdevCoordinator: udev settle timeout or error: Error: spawnSync /bin/sh ETIMEDOUT Aug 28 23:49:16 volumio4b wireless.js[664]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 28 23:49:16 volumio4b wireless.js[664]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: d8:3a:dd:32:60:b1, USB: false) Aug 28 23:49:16 volumio4b wpa_supplicant[1077]: Successfully initialized wpa_supplicant Aug 28 23:49:17 volumio4b kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 28 23:49:17 volumio4b wpa_supplicant[1077]: nl80211: kernel reports: Registration to specific type not supported Aug 28 23:49:17 volumio4b sudo[1083]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 28 23:49:17 volumio4b sudo[1083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:17 volumio4b sudo[1083]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:18 volumio4b wireless.js[664]: WIRELESS.JS - INFO: DHCP IP fallback Aug 28 23:49:18 volumio4b wireless.js[664]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor Aug 28 23:49:18 volumio4b wireless.js[664]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 Aug 28 23:49:19 volumio4b wireless.js[664]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: wlan0: Trying to associate with e4:8f:34:76:b8:b6 (SSID='Vodafone-A76230587' freq=5520 MHz) Aug 28 23:49:20 volumio4b wireless.js[664]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> ASSOCIATING (duration: 1019ms) Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: wlan0: Associated with e4:8f:34:76:b8:b6 Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: wlan0: WPA: Key negotiation completed with e4:8f:34:76:b8:b6 [PTK=CCMP GTK=CCMP] Aug 28 23:49:20 volumio4b wpa_supplicant[1080]: wlan0: CTRL-EVENT-CONNECTED - Connection to e4:8f:34:76:b8:b6 completed [id=0 id_str=] Aug 28 23:49:20 volumio4b dhcpcd[790]: wlan0: carrier acquired Aug 28 23:49:20 volumio4b dhcpcd[790]: wlan0: connected to Access Point: Vodafone-A76230587 Aug 28 23:49:20 volumio4b dhcpcd[790]: wlan0: IAID dd:32:60:b1 Aug 28 23:49:20 volumio4b dhcpcd[790]: wlan0: adding address fe80::ce04:4e9f:b141:886a Aug 28 23:49:20 volumio4b dhcpcd[790]: ipv6_addaddr1: Permission denied Aug 28 23:49:20 volumio4b wireless.js[664]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> COMPLETED (duration: 508ms) Aug 28 23:49:20 volumio4b wireless.js[664]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful Aug 28 23:49:20 volumio4b wireless.js[664]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to e4:8f:34:76:b8:b6 Aug 28 23:49:20 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow Aug 28 23:49:20 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:20] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 28 23:49:20 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:20] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 28 23:49:20 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:20] [error] handle_connect error: Connection refused Aug 28 23:49:21 volumio4b dhcpcd[790]: wlan0: soliciting an IPv6 router Aug 28 23:49:21 volumio4b sudo[1107]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 Aug 28 23:49:21 volumio4b sudo[1107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:21 volumio4b dhcpcd[790]: control command: /sbin/dhcpcd wlan0 Aug 28 23:49:21 volumio4b dhcpcd[790]: control_free: No such file or directory Aug 28 23:49:21 volumio4b sudo[1107]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:21 volumio4b dhcpcd[790]: wlan0: rebinding lease of 192.168.1.123 Aug 28 23:49:23 volumio4b dhcpcd[790]: wlan0: probing address 192.168.1.123/24 Aug 28 23:49:23 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Start ap Aug 28 23:49:23 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 28 23:49:23 volumio4b systemd[1]: Started wireless.service - Wireless Services. Aug 28 23:49:23 volumio4b kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 28 23:49:23 volumio4b systemd[1]: Started volumio.service - Volumio Backend Module. Aug 28 23:49:24 volumio4b systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Aug 28 23:49:24 volumio4b volumio-cpu-tweak[1118]: Setting RT Priority for mpd Aug 28 23:49:24 volumio4b volumio-cpu-tweak[1132]: pid 35's current scheduling policy: SCHED_OTHER Aug 28 23:49:24 volumio4b volumio-cpu-tweak[1132]: pid 35's current scheduling priority: 0 Aug 28 23:49:24 volumio4b volumio-cpu-tweak[1118]: Setting MPD Affinity Aug 28 23:49:24 volumio4b volumio-cpu-tweak[1133]: pid 3's current affinity mask: f Aug 28 23:49:24 volumio4b volumio-cpu-tweak[1118]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 28 23:49:24 volumio4b volumio-cpu-tweak[1118]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 28 23:49:24 volumio4b systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Aug 28 23:49:24 volumio4b wireless.js[664]: WIRELESS.JS - INFO: trying... Aug 28 23:49:25 volumio4b sudo[1146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 23:49:25 volumio4b sudo[1146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:25 volumio4b sudo[1146]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:25 volumio4b sudo[1149]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 23:49:25 volumio4b sudo[1149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:25 volumio4b sudo[1149]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:25 volumio4b wireless.js[664]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 28 23:49:25 volumio4b wireless.js[664]: WIRELESS.JS - INFO: trying... Aug 28 23:49:26 volumio4b sudo[1160]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 23:49:26 volumio4b sudo[1160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:26 volumio4b sudo[1160]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:26 volumio4b sudo[1163]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 23:49:26 volumio4b sudo[1163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:26 volumio4b sudo[1163]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:26 volumio4b wireless.js[664]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 28 23:49:26 volumio4b wireless.js[664]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet Aug 28 23:49:26 volumio4b wireless.js[664]: WIRELESS.JS - INFO: trying... Aug 28 23:49:26 volumio4b volumio[1117]: info: ------------------------------------------- Aug 28 23:49:26 volumio4b volumio[1117]: info: ----- Volumio3 ---- Aug 28 23:49:26 volumio4b volumio[1117]: info: ------------------------------------------- Aug 28 23:49:26 volumio4b volumio[1117]: info: ----- System startup ---- Aug 28 23:49:26 volumio4b volumio[1117]: info: ------------------------------------------- Aug 28 23:49:26 volumio4b systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 28 23:49:27 volumio4b sudo[1186]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 23:49:27 volumio4b sudo[1186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:27 volumio4b sudo[1186]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:27 volumio4b sudo[1190]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 23:49:27 volumio4b sudo[1190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:27 volumio4b sudo[1190]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:27 volumio4b wireless.js[664]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 28 23:49:27 volumio4b volumio[1117]: info: MYVOLUMIO Environment detected Aug 28 23:49:27 volumio4b wireless.js[664]: WIRELESS.JS - INFO: trying... Aug 28 23:49:27 volumio4b volumio[1117]: info: Plugin folders cleanup Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning into folder /volumio/app/plugins/ Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning category audio_interface Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning category miscellanea Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning category music_service Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning category plugins.json Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning category system_controller Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning category user_interface Aug 28 23:49:27 volumio4b volumio[1117]: info: Scanning into folder /data/plugins/ Aug 28 23:49:27 volumio4b volumio[1117]: info: Plugin folders cleanup completed Aug 28 23:49:27 volumio4b volumio[1117]: info: ------------------------------------------- Aug 28 23:49:27 volumio4b volumio[1117]: info: ----- Core plugins startup ---- Aug 28 23:49:27 volumio4b volumio[1117]: info: ------------------------------------------- Aug 28 23:49:27 volumio4b volumio[1117]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 23:49:27 volumio4b volumio[1117]: info: Adding plugin upnp to MyMusic Plugins Aug 28 23:49:27 volumio4b volumio[1117]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 23:49:27 volumio4b volumio[1117]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 23:49:27 volumio4b volumio[1117]: info: Loading plugins from folder /data/plugins/ Aug 28 23:49:27 volumio4b volumio[1117]: info: Loading plugin "system"... Aug 28 23:49:28 volumio4b volumio[1117]: info: Loading plugin "appearance"... Aug 28 23:49:28 volumio4b sudo[1208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 23:49:28 volumio4b sudo[1208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:28 volumio4b sudo[1208]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:28 volumio4b sudo[1212]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 23:49:28 volumio4b sudo[1212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:28 volumio4b sudo[1212]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:28 volumio4b wireless.js[664]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 28 23:49:28 volumio4b dhcpcd[709]: timed out Aug 28 23:49:28 volumio4b sh[699]: timed out Aug 28 23:49:28 volumio4b dhcpcd[709]: dhcpcd exited Aug 28 23:49:28 volumio4b sh[612]: ifup: failed to bring up eth0 Aug 28 23:49:28 volumio4b systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 28 23:49:28 volumio4b systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 28 23:49:28 volumio4b dhcpcd[790]: wlan0: leased 192.168.1.123 for 86400 seconds Aug 28 23:49:28 volumio4b avahi-daemon[646]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.123. Aug 28 23:49:28 volumio4b avahi-daemon[646]: New relevant interface wlan0.IPv4 for mDNS. Aug 28 23:49:28 volumio4b avahi-daemon[646]: Registering new address record for 192.168.1.123 on wlan0.IPv4. Aug 28 23:49:28 volumio4b dhcpcd[790]: wlan0: adding route to 192.168.1.0/24 Aug 28 23:49:28 volumio4b wireless.js[664]: WIRELESS.JS - INFO: trying... Aug 28 23:49:28 volumio4b dhcpcd[790]: wlan0: adding default route via 192.168.1.1 Aug 28 23:49:29 volumio4b sudo[1249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 23:49:29 volumio4b sudo[1249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:29 volumio4b sudo[1249]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:29 volumio4b systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 28 23:49:29 volumio4b sudo[1252]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 23:49:29 volumio4b systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 23:49:29 volumio4b sudo[1252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:29 volumio4b sudo[1252]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:29 volumio4b wireless.js[664]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.123, ipV6 is undefined Aug 28 23:49:29 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Connected to SSID: Vodafone-A76230587 Aug 28 23:49:29 volumio4b wireless.js[664]: WIRELESS.JS - INFO: It's done! AP Aug 28 23:49:29 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Restarting avahi-daemon... Aug 28 23:49:29 volumio4b systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 23:49:29 volumio4b sudo[1258]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Aug 28 23:49:29 volumio4b sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 23:49:29 volumio4b upmpdcli[1253]: Could not open config: /tmp/upmpdcli.conf Aug 28 23:49:29 volumio4b systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 23:49:29 volumio4b systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 23:49:29 volumio4b avahi-daemon[646]: Got SIGTERM, quitting. Aug 28 23:49:29 volumio4b avahi-daemon[646]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.123. Aug 28 23:49:29 volumio4b avahi-daemon[646]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 28 23:49:29 volumio4b systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 28 23:49:29 volumio4b avahi-daemon[646]: avahi-daemon 0.8 exiting. Aug 28 23:49:29 volumio4b systemd[1]: avahi-daemon.service: Deactivated successfully. Aug 28 23:49:29 volumio4b systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 28 23:49:29 volumio4b systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Process 646 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Successfully dropped root privileges. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: avahi-daemon 0.8 starting up. Aug 28 23:49:29 volumio4b systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Successfully called chroot(). Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Successfully dropped remaining capabilities. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Loading service file /services/volumio.service. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.123. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: New relevant interface wlan0.IPv4 for mDNS. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: New relevant interface lo.IPv4 for mDNS. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Network interface enumeration completed. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Registering new address record for 192.168.1.123 on wlan0.IPv4. Aug 28 23:49:29 volumio4b avahi-daemon[1260]: Registering new address record for 127.0.0.1 on lo.IPv4. Aug 28 23:49:29 volumio4b sudo[1258]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:29 volumio4b wireless.js[664]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 28 23:49:29 volumio4b volumio[1117]: info: Loading plugin "network"... Aug 28 23:49:29 volumio4b volumio[1117]: info: Refreshing Cached IP Addresses Aug 28 23:49:29 volumio4b sudo[1264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 23:49:29 volumio4b sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:29 volumio4b sudo[1264]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:29 volumio4b sudo[1266]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 23:49:29 volumio4b sudo[1266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:30 volumio4b sudo[1266]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "services"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "volumio5onboarding"... Aug 28 23:49:30 volumio4b sudo[1279]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 23:49:30 volumio4b sudo[1279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "alsa_controller"... Aug 28 23:49:30 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "wizard"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "networkfs"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Starting Udev Watcher for removable devices Aug 28 23:49:30 volumio4b avahi-daemon[1260]: Server startup complete. Host name is volumio4b.local. Local service cookie is 357411924. Aug 28 23:49:30 volumio4b volumio[1117]: info: Ignoring mount for partition: boot Aug 28 23:49:30 volumio4b volumio[1117]: info: Ignoring mount for partition: volumio Aug 28 23:49:30 volumio4b volumio[1117]: info: Ignoring mount for partition: volumio_data Aug 28 23:49:30 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "volumio_command_line_client"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "upnp"... Aug 28 23:49:30 volumio4b volumio[1117]: info: [1787953770243] Starting Upmpd Daemon Aug 28 23:49:30 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "my_music"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "mpd"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Plugin upnp_browser is not enabled Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "alarm-clock"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Plugin airplay_emulation is not enabled Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "last_100"... Aug 28 23:49:30 volumio4b volumio[1117]: info: Loading plugin "webradio"... Aug 28 23:49:30 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:30] [connect] Successful connection Aug 28 23:49:31 volumio4b avahi-daemon[1260]: Service "volumio4b" (/services/volumio.service) successfully established. Aug 28 23:49:31 volumio4b volumio[1117]: info: Loading plugin "i2s_dacs"... Aug 28 23:49:31 volumio4b volumio[1117]: info: I2S DAC not set, start Auto-detection Aug 28 23:49:31 volumio4b volumio[1117]: info: Loading plugin "volumiodiscovery"... Aug 28 23:49:31 volumio4b volumio[1117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 23:49:31 volumio4b volumio[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 23:49:31 volumio4b volumio[1117]: *** WARNING *** For more information see Aug 28 23:49:31 volumio4b volumio[1117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 23:49:31 volumio4b volumio[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 23:49:31 volumio4b node[1117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 23:49:31 volumio4b volumio[1117]: *** WARNING *** For more information see Aug 28 23:49:31 volumio4b node[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 23:49:31 volumio4b node[1117]: *** WARNING *** For more information see Aug 28 23:49:31 volumio4b node[1117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 23:49:31 volumio4b node[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 23:49:31 volumio4b node[1117]: *** WARNING *** For more information see Aug 28 23:49:31 volumio4b volumio[1117]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 23:49:31 volumio4b volumio[1117]: info: Discovery: Started advertising with name: volumio4b Aug 28 23:49:31 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 23:49:31 volumio4b volumio[1117]: info: Loading plugin "outputs"... Aug 28 23:49:31 volumio4b volumio[1117]: info: Loading plugin "albumart"... Aug 28 23:49:31 volumio4b volumio[1117]: info: Plugin example_plugin is not enabled Aug 28 23:49:31 volumio4b volumio[1117]: info: Loading plugin "inputs"... Aug 28 23:49:31 volumio4b volumio[1117]: info: Loading plugin "updater_comm"... Aug 28 23:49:32 volumio4b volumio[1117]: info: Plugin mpdemulation is not enabled Aug 28 23:49:32 volumio4b volumio[1117]: info: Loading plugin "rest_api"... Aug 28 23:49:32 volumio4b volumio[1117]: info: Loading plugin "websocket"... Aug 28 23:49:32 volumio4b volumio[1117]: info: Starting Socket.io Server version 1.7.4 Aug 28 23:49:32 volumio4b volumio[1117]: info: Loading i18n strings for locale en Aug 28 23:49:32 volumio4b volumio[1117]: Updating browse sources language Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::initPlayerControls Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 23:49:32 volumio4b volumio[1117]: Express server listening on port 3000 Aug 28 23:49:32 volumio4b volumio[1117]: [Metrics] WebUI: 6s 683.48ms Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreStateMachine::resetVolumioState Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreStateMachine::getcurrentVolume Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreStateMachine::pushState Aug 28 23:49:32 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::volumioPushState Aug 28 23:49:32 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:49:32 volumio4b systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 28 23:49:32 volumio4b volumio[1117]: info: Volumio Network Manager: Network status updated: 2 Aug 28 23:49:33 volumio4b volumio[1299]: Forking 3 albumart workers Aug 28 23:49:33 volumio4b volumio[1117]: verbose: New Socket.io Connection to 192.168.1.123:3000 from 192.168.1.122 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 28 23:49:33 volumio4b volumio-remote-updater[660]: [2026-08-28 23:49:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787953770 101 Aug 28 23:49:33 volumio4b sudo[1279]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:33 volumio4b volumio[1117]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Aug 28 23:49:33 volumio4b volumio[1117]: info: Reloading queue from file Aug 28 23:49:33 volumio4b volumio[1117]: info: CoreStateMachine::setRepeat null single undefined Aug 28 23:49:33 volumio4b volumio[1117]: info: CoreStateMachine::pushState Aug 28 23:49:33 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:33 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 23:49:33 volumio4b volumio[1117]: info: CoreCommandRouter::volumioPushState Aug 28 23:49:33 volumio4b volumio[1117]: info: CoreStateMachine::setRandom null Aug 28 23:49:33 volumio4b volumio[1117]: info: CoreStateMachine::pushState Aug 28 23:49:33 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:33 volumio4b volumio[1117]: info: CoreCommandRouter::volumioPushState Aug 28 23:49:33 volumio4b volumio[1117]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 28 23:49:33 volumio4b volumio[1117]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Aug 28 23:49:33 volumio4b volumio[1117]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 28 23:49:34 volumio4b volumio[1117]: info: Setting Device type: Raspberry PI Aug 28 23:49:34 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 28 23:49:34 volumio4b volumio[1117]: info: Discovery: adding 22a16a02-db6e-4436-8a7b-00f172c5cc03 Aug 28 23:49:34 volumio4b volumio[1117]: info: Discovery: Found device volumio4b Aug 28 23:49:34 volumio4b volumio[1117]: info: CoreCommandRouter::volumioGetState Aug 28 23:49:34 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:34 volumio4b volumio[1117]: info: CoreCommandRouter::volumioGetState Aug 28 23:49:34 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:34 volumio4b volumio[1117]: info: Completed loading Core Plugins Aug 28 23:49:34 volumio4b volumio[1117]: info: Preparing to generate the ALSA configuration file Aug 28 23:49:34 volumio4b volumio[1117]: info: Discovery: this is already registered, 22a16a02-db6e-4436-8a7b-00f172c5cc03 Aug 28 23:49:34 volumio4b volumio[1117]: info: Discovery: Found device volumio4b Aug 28 23:49:34 volumio4b volumio[1117]: info: CoreCommandRouter::volumioGetState Aug 28 23:49:34 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:34 volumio4b volumio[1117]: info: Discovery: adding 5dc4ca49-7678-41fa-804b-b25676e77780 Aug 28 23:49:34 volumio4b volumio[1117]: info: Discovery: Found device Volumio3b Aug 28 23:49:34 volumio4b volumio[1117]: info: Discovery: Connecting to remote: 192.168.1.122 Aug 28 23:49:34 volumio4b volumio[1117]: info: Asound.conf file unchanged, so no further update is needed Aug 28 23:49:34 volumio4b volumio[1117]: info: Output device has changed, restarting MPD Aug 28 23:49:35 volumio4b volumio[1117]: info: ___________ START PLUGINS ___________ Aug 28 23:49:35 volumio4b sudo[1357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 23:49:35 volumio4b sudo[1357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:35 volumio4b volumio[1117]: info: ControllerMpd::onStart: Initializing MPD Aug 28 23:49:35 volumio4b volumio[1117]: info: Creating MPD Configuration file Aug 28 23:49:35 volumio4b sudo[1355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 23:49:35 volumio4b sudo[1355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:35 volumio4b sudo[1355]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:35 volumio4b sudo[1360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 28 23:49:35 volumio4b sudo[1360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:35 volumio4b systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 23:49:35 volumio4b volumio[1117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 23:49:35 volumio4b volumio[1117]: info: [1787953775408] CoreMusicLibrary::Adding element Last_100 Aug 28 23:49:35 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 23:49:35 volumio4b volumio[1117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 23:49:35 volumio4b volumio[1117]: info: [1787953775413] CoreMusicLibrary::Adding element Webradio Aug 28 23:49:35 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 23:49:35 volumio4b sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 23:49:35 volumio4b sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:35 volumio4b sudo[1365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 23:49:35 volumio4b sudo[1365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:35 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 23:49:35 volumio4b volumio[1117]: info: Initializing BBC Radios Aug 28 23:49:35 volumio4b systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 23:49:35 volumio4b sudo[1360]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:35 volumio4b sudo[1365]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:35 volumio4b systemd[1]: mpd.socket: Deactivated successfully. Aug 28 23:49:35 volumio4b systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 23:49:35 volumio4b systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 23:49:35 volumio4b systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 23:49:36 volumio4b volumio5-onboarding[1368]: time=2026-08-28T23:49:36.085+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 28 23:49:36 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 23:49:36 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:49:36 volumio4b volumio[1117]: info: Cannot retrieve data for calling home Aug 28 23:49:37 volumio4b volumio[1117]: info: MPD Permissions set Aug 28 23:49:37 volumio4b volumio[1117]: info: MPD Permissions set Aug 28 23:49:37 volumio4b volumio[1316]: Starting albumart workers Aug 28 23:49:37 volumio4b volumio[1117]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 28 23:49:37 volumio4b volumio[1318]: Starting albumart workers Aug 28 23:49:38 volumio4b volumio[1117]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 28 23:49:38 volumio4b volumio[1117]: info: Received Get System Info Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 23:49:38 volumio4b volumio[1117]: info: Discovery: Getting this device information Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::volumioGetState Aug 28 23:49:38 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 23:49:38 volumio4b volumio5-onboarding[1368]: time=2026-08-28T23:49:38.022+02:00 level=INFO msg="system info for ce3c9f3261412528021be1847b6eef07" deviceName=volumio4b deviceVariant="" deviceModel= softwareVersion="" Aug 28 23:49:38 volumio4b volumio5-onboarding[1368]: time=2026-08-28T23:49:38.036+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 28 23:49:38 volumio4b volumio[1117]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1317]: Starting albumart workers Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 23:49:38 volumio4b volumio[1117]: info: Received Get System Info Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 23:49:38 volumio4b volumio[1117]: info: Discovery: Getting this device information Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::volumioGetState Aug 28 23:49:38 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:38 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 23:49:38 volumio4b volumio-remote-updater[660]: Test mode disabled Aug 28 23:49:38 volumio4b volumio-remote-updater[660]: Alpha mode disabled Aug 28 23:49:38 volumio4b volumio-remote-updater[660]: Alpha legacy test mode disabled Aug 28 23:49:39 volumio4b volumio[1117]: info: Discovery: Connected to remote: 192.168.1.122 Aug 28 23:49:39 volumio4b volumio[1117]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 23:49:39 volumio4b volumio[1117]: info: Received Get System Info Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 23:49:39 volumio4b volumio[1117]: info: Discovery: Getting this device information Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::volumioGetState Aug 28 23:49:39 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 23:49:39 volumio4b volumio5-onboarding[1368]: time=2026-08-28T23:49:39.844+02:00 level=INFO msg="enabling local network discovery" Aug 28 23:49:39 volumio4b volumio5-onboarding[1368]: time=2026-08-28T23:49:39.870+02:00 level=INFO msg="enabling BLE discovery" Aug 28 23:49:39 volumio4b volumio[1117]: info: CoreCommandRouter::volumioGetState Aug 28 23:49:39 volumio4b volumio[1117]: info: CorePlayQueue::getTrack 0 Aug 28 23:49:40 volumio4b sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 23:49:40 volumio4b sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:40 volumio4b sudo[1429]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 23:49:40 volumio4b sudo[1422]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:40 volumio4b sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:40 volumio4b sudo[1429]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:40 volumio4b volumio5-onboarding[1368]: time=2026-08-28T23:49:40.668+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 23:49:40 volumio4b sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 28 23:49:40 volumio4b sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:49:44 volumio4b systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 28 23:49:44 volumio4b systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 23:49:44 volumio4b systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 23:49:44 volumio4b sudo[1444]: pam_unix(sudo:session): session closed for user root Aug 28 23:49:44 volumio4b volumio[1117]: info: Upmpdcli Daemon Started Aug 28 23:49:58 volumio4b (udev-worker)[505]: pcmC5D0p: Spawned process '/bin/bash -c '/usr/local/bin/volumio usbattach'' [841] is taking longer than 59s to complete Aug 28 23:49:58 volumio4b (udev-worker)[507]: pcmC5D0c: Spawned process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' [843] is taking longer than 59s to complete Aug 28 23:49:58 volumio4b systemd-udevd[481]: pcmC5D0p: Worker [505] processing SEQNUM=2612 is taking a long time Aug 28 23:49:58 volumio4b systemd-udevd[481]: pcmC5D0c: Worker [507] processing SEQNUM=2613 is taking a long time Aug 28 23:50:06 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 23:50:06 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:06 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 23:50:06 volumio4b volumio[1117]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Aug 28 23:50:06 volumio4b volumio[1117]: info: ------------------------------------------- Aug 28 23:50:06 volumio4b volumio[1117]: info: ----- MyVolumio plugins startup ---- Aug 28 23:50:06 volumio4b volumio[1117]: info: ------------------------------------------- Aug 28 23:50:06 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 28 23:50:14 volumio4b volumio[1117]: info: Adding plugin bluetooth to MyMusic Plugins Aug 28 23:50:14 volumio4b volumio[1117]: info: Adding plugin multiroom to MyMusic Plugins Aug 28 23:50:14 volumio4b volumio[1117]: info: Adding plugin metavolumio to MyMusic Plugins Aug 28 23:50:14 volumio4b volumio[1117]: info: Adding plugin cd_controller to MyMusic Plugins Aug 28 23:50:14 volumio4b volumio[1117]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 28 23:50:14 volumio4b volumio[1117]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 28 23:50:14 volumio4b volumio[1117]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 28 23:50:14 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 28 23:50:18 volumio4b volumio[1117]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 28 23:50:18 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 28 23:50:18 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:18 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:18 volumio4b volumio[1117]: info: Starting MyVolumio Remote Streaming Endpoints Aug 28 23:50:18 volumio4b volumio[1117]: info: MyVolumio login type: Token Aug 28 23:50:18 volumio4b volumio[1117]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 28 23:50:18 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 28 23:50:22 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 28 23:50:22 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 28 23:50:22 volumio4b volumio[1117]: info: Streaming services startup Aug 28 23:50:22 volumio4b volumio[1117]: info: Starting Streaming Daemon Aug 28 23:50:22 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 28 23:50:22 volumio4b sudo[1523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 28 23:50:22 volumio4b sudo[1523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 23:50:22 volumio4b sudo[1523]: pam_unix(sudo:session): session closed for user root Aug 28 23:50:22 volumio4b volumio[1117]: error: Cannot start Volumio Streaming Daemon Aug 28 23:50:22 volumio4b volumio[1117]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 28 23:50:22 volumio4b volumio[1117]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 28 23:50:22 volumio4b volumio[1117]: error: MyVolumio Custom Token format not valid, refreshing it Aug 28 23:50:23 volumio4b volumio[1117]: info: MyVolumio login type: Token Aug 28 23:50:24 volumio4b volumio[1117]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 28 23:50:25 volumio4b volumio[1117]: info: MyVolumio token set successfully Aug 28 23:50:25 volumio4b volumio[1117]: info: MYVOLUMIO: Adding device Aug 28 23:50:25 volumio4b volumio[1117]: info: MYVOLUMIO: Evaluating Server Aug 28 23:50:26 volumio4b volumio[1117]: info: MyVolumio Plan changed: premium Aug 28 23:50:26 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 28 23:50:26 volumio4b volumio[1117]: info: Removing browser output: myVolumio user plan is not superstar Aug 28 23:50:26 volumio4b volumio[1117]: info: Removing audio output: Aug 28 23:50:26 volumio4b volumio[1117]: info: MYVOLUMIO: Adding device Aug 28 23:50:26 volumio4b volumio[1117]: info: MYVOLUMIO: Evaluating Server Aug 28 23:50:27 volumio4b volumio[1117]: info: Remote config written successfully Aug 28 23:50:27 volumio4b volumio[1117]: info: Starting Tunnel 1 Aug 28 23:50:27 volumio4b volumio[1117]: info: Starting Tunnel Connection Checker Aug 28 23:50:27 volumio4b volumio[1117]: info: Completed starting MyVolumio Plugin Aug 28 23:50:27 volumio4b volumio[1117]: info: Setting Geolocation for MyVolumio to eu4 Aug 28 23:50:27 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:27 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:27 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:27 volumio4b volumio[1117]: info: Setting Geolocation for MyVolumio to eu4 Aug 28 23:50:27 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:27 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:27 volumio4b volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 23:50:27 volumio4b volumio[1117]: info: MYVolumio Device enabled Aug 28 23:50:27 volumio4b volumio[1117]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 28 23:50:27 volumio4b volumio[1117]: [2026-08-28T21:50:27.382Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read properties of null (reading 'replace') Aug 28 23:50:27 volumio4b volumio[1117]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Aug 28 23:50:27 volumio4b volumio[1117]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Aug 28 23:50:27 volumio4b volumio[1117]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Aug 28 23:50:27 volumio4b volumio[1117]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Aug 28 23:50:27 volumio4b volumio[1117]: at /volumio/node_modules/v-conf/index.js:166:13 Aug 28 23:50:27 volumio4b volumio[1117]: at /volumio/node_modules/multimap/index.js:140:9 Aug 28 23:50:27 volumio4b volumio[1117]: at Array.forEach () Aug 28 23:50:27 volumio4b volumio[1117]: at /volumio/node_modules/multimap/index.js:139:13 Aug 28 23:50:27 volumio4b volumio[1117]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Aug 28 23:50:27 volumio4b volumio[1117]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Aug 28 23:50:27 volumio4b volumio[1117]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 23:50:27 volumio4b volumio[1117]: TypeError: Cannot read properties of null (reading 'replace') Aug 28 23:50:27 volumio4b volumio[1117]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Aug 28 23:50:27 volumio4b volumio[1117]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Aug 28 23:50:27 volumio4b volumio[1117]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Aug 28 23:50:27 volumio4b volumio[1117]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Aug 28 23:50:27 volumio4b volumio[1117]: at /volumio/node_modules/v-conf/index.js:166:13 Aug 28 23:50:27 volumio4b volumio[1117]: at /volumio/node_modules/multimap/index.js:140:9 Aug 28 23:50:27 volumio4b volumio[1117]: at Array.forEach () Aug 28 23:50:27 volumio4b volumio[1117]: at /volumio/node_modules/multimap/index.js:139:13 Aug 28 23:50:27 volumio4b volumio[1117]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Aug 28 23:50:27 volumio4b volumio[1117]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Aug 28 23:50:27 volumio4b volumio[1117]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 23:50:30 volumio4b sudo[1581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 23:49' Aug 28 23:50:30 volumio4b sudo[1581]: 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"