Jan 26 18:45:00 volumio-mc systemd-udevd[594]: Using default interface naming scheme 'v252'. Jan 26 18:45:00 volumio-mc systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 26 18:45:00 volumio-mc (udev-worker)[606]: Network interface NamePolicy= disabled on kernel command line. Jan 26 18:45:00 volumio-mc kernel: acpi-tad ACPI000E:00: Missing _PRW Jan 26 18:45:00 volumio-mc kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jan 26 18:45:00 volumio-mc systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Jan 26 18:45:00 volumio-mc systemd[1]: Found device sys-subsystem-net-devices-eth0.device - NetXtreme BCM57766 Gigabit Ethernet PCIe. Jan 26 18:45:00 volumio-mc kernel: intel_pch_thermal 0000:00:12.0: can't derive routing for PCI INT A Jan 26 18:45:00 volumio-mc kernel: intel_pch_thermal 0000:00:12.0: PCI INT A: not connected Jan 26 18:45:00 volumio-mc kernel: cryptd: max_cpu_qlen set to 1000 Jan 26 18:45:00 volumio-mc kernel: AES CTR mode by8 optimization enabled Jan 26 18:45:00 volumio-mc systemd[1]: Found device dev-disk-by\x2duuid-6218\x2d8677.device - TransMemory boot. Jan 26 18:45:00 volumio-mc kernel: dw-apb-uart.0: ttyS4 at MMIO 0x4000100000 (irq = 20, base_baud = 7500000) is a 16550A Jan 26 18:45:00 volumio-mc kernel: serial serial0: tty port ttyS4 registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: Core ver 2.22 Jan 26 18:45:00 volumio-mc kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 26 18:45:00 volumio-mc kernel: NET: Registered PF_BLUETOOTH protocol family Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI device and connection manager initialized Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI socket layer initialized Jan 26 18:45:00 volumio-mc kernel: Bluetooth: L2CAP socket layer initialized Jan 26 18:45:00 volumio-mc kernel: Bluetooth: SCO socket layer initialized Jan 26 18:45:00 volumio-mc systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-6218\x2d8677.service - File System Check on /dev/disk/by-uuid/6218-8677... Jan 26 18:45:00 volumio-mc kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 26 18:45:00 volumio-mc kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 26 18:45:00 volumio-mc systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 26 18:45:00 volumio-mc systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 26 18:45:00 volumio-mc systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 26 18:45:00 volumio-mc systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 26 18:45:00 volumio-mc systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 26 18:45:00 volumio-mc systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 26 18:45:00 volumio-mc systemd-fsck[785]: fsck.fat 4.2 (2021-01-31) Jan 26 18:45:00 volumio-mc systemd-fsck[785]: /dev/sda1: 21 files, 23296/93500 clusters Jan 26 18:45:00 volumio-mc systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-6218\x2d8677.service - File System Check on /dev/disk/by-uuid/6218-8677. Jan 26 18:45:00 volumio-mc kernel: usbcore: registered new interface driver brcmfmac Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART driver ver 2.3 Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol H4 registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol BCSP registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol LL registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol ATH3K registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol Intel registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol QCA registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol AG6XX registered Jan 26 18:45:00 volumio-mc kernel: Bluetooth: HCI UART protocol Marvell registered Jan 26 18:45:00 volumio-mc kernel: hci_uart_bcm serial0-0: Unexpected ACPI gpio_int_idx: -1 Jan 26 18:45:00 volumio-mc kernel: hci_uart_bcm serial0-0: Unexpected number of ACPI GPIOs: 0 Jan 26 18:45:00 volumio-mc kernel: hci_uart_bcm serial0-0: No reset resource, using default baud rate Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: enabling device (0000 -> 0002) Jan 26 18:45:00 volumio-mc kernel: usb 1-12: Warning! Unlikely big volume range (=2032), cval->res is probably wrong. Jan 26 18:45:00 volumio-mc kernel: usb 1-12: [10] FU [D50 III Playback Volume] ch = 1, val = -32512/0/16 Jan 26 18:45:00 volumio-mc kernel: usbcore: registered new interface driver snd-usb-audio Jan 26 18:45:00 volumio-mc kernel: usb 1-11: RTL8188EU rev D (TSMC) romver 0, 1T1R, TX queues 2, WiFi=1, BT=0, GPS=0, HI PA=0 Jan 26 18:45:00 volumio-mc kernel: usb 1-11: RTL8188EU MAC: 5c:a6:e6:85:67:a4 Jan 26 18:45:00 volumio-mc kernel: usb 1-11: rtl8xxxu: Loading firmware rtlwifi/rtl8188eufw.bin Jan 26 18:45:00 volumio-mc kernel: usb 1-11: Firmware revision 28.0 (signature 0x88e1) Jan 26 18:45:00 volumio-mc kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac4364b2-pcie for chip BCM4364/3 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: Direct firmware load for brcm/brcmfmac4364b2-pcie.apple,lanai-HRPN-m-7.7-X3.bin failed with error -2 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: Direct firmware load for brcm/brcmfmac4364b2-pcie.apple,lanai-HRPN-m-7.7.bin failed with error -2 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: Direct firmware load for brcm/brcmfmac4364b2-pcie.apple,lanai-HRPN-m.bin failed with error -2 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: Direct firmware load for brcm/brcmfmac4364b2-pcie.apple,lanai-HRPN.bin failed with error -2 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: Direct firmware load for brcm/brcmfmac4364b2-pcie.apple,lanai-X3.bin failed with error -2 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: Direct firmware load for brcm/brcmfmac4364b2-pcie.apple,lanai.bin failed with error -2 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: Direct firmware load for brcm/brcmfmac4364b2-pcie.bin failed with error -2 Jan 26 18:45:00 volumio-mc kernel: brcmfmac 0000:03:00.0: brcmf_pcie_setup: Dongle setup failed Jan 26 18:45:00 volumio-mc kernel: ieee80211 phy1: brcmf_fw_crashed: Firmware has halted or crashed Jan 26 18:45:00 volumio-mc systemd[1]: Mounting boot.mount - /boot... Jan 26 18:45:00 volumio-mc systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 26 18:45:00 volumio-mc systemd[1]: Mounting tmp.mount - /tmp... Jan 26 18:45:00 volumio-mc systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 26 18:45:00 volumio-mc systemd[1]: Mounting var-log.mount - /var/log... Jan 26 18:45:00 volumio-mc kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jan 26 18:45:00 volumio-mc systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Jan 26 18:45:00 volumio-mc systemd[1]: Mounted boot.mount - /boot. Jan 26 18:45:00 volumio-mc systemd[1]: Mounted tmp.mount - /tmp. Jan 26 18:45:00 volumio-mc systemd[1]: Mounted var-log.mount - /var/log. Jan 26 18:45:00 volumio-mc systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Jan 26 18:45:00 volumio-mc systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Jan 26 18:45:00 volumio-mc systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 26 18:45:00 volumio-mc systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Jan 26 18:45:00 volumio-mc (udev-worker)[604]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Jan 26 18:45:00 volumio-mc systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Jan 26 18:45:00 volumio-mc systemd-journald[564]: Runtime Journal (/run/log/journal/18dbc70a903ce4de96672ddb694d05da) is 3.7M, max 30.0M, 26.2M free. Jan 26 18:45:00 volumio-mc systemd[1]: Reached target local-fs.target - Local File Systems. Jan 26 18:45:00 volumio-mc systemd[1]: Started ifup@eth0.service - ifup for eth0. Jan 26 18:45:00 volumio-mc systemd-journald[564]: Received client request to flush runtime journal. Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 26 18:45:00 volumio-mc systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Jan 26 18:45:00 volumio-mc systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Jan 26 18:45:00 volumio-mc systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Jan 26 18:45:00 volumio-mc systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 26 18:45:00 volumio-mc systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 26 18:45:00 volumio-mc systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jan 26 18:45:00 volumio-mc systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Jan 26 18:45:00 volumio-mc systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 26 18:45:00 volumio-mc systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 26 18:45:00 volumio-mc systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 26 18:45:00 volumio-mc systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 26 18:45:00 volumio-mc systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jan 26 18:45:00 volumio-mc systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 26 18:45:00 volumio-mc systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 811 (systemd-binfmt) Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 26 18:45:00 volumio-mc systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Jan 26 18:45:00 volumio-mc systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 26 18:45:00 volumio-mc systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 26 18:45:00 volumio-mc systemd[1]: Received SIGRTMIN+20 from PID 422 (plymouthd). Jan 26 18:45:00 volumio-mc systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 26 18:45:00 volumio-mc systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 26 18:45:01 volumio-mc kernel: usb 1-11: rtl8188eu_rx_iqk_path_a: Path A RX IQK failed! Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: BCM: failed to write update baudrate (-16) Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: Failed to set baudrate Jan 26 18:45:01 volumio-mc kernel: usbcore: registered new interface driver rtl8xxxu Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: BCM: chip id 123 Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: BCM: features 0x0f Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: BCM4364B0 Maui Olympic GEN (MFG) Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: BCM (001.000.122) build 0343 Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: BCM: firmware Patch file not found, tried: Jan 26 18:45:01 volumio-mc kernel: Bluetooth: hci0: BCM: 'brcm/BCM.hcd' Jan 26 18:45:01 volumio-mc systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Jan 26 18:45:01 volumio-mc systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Jan 26 18:45:01 volumio-mc systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Jan 26 18:45:02 volumio-mc (udev-worker)[624]: Network interface NamePolicy= disabled on kernel command line. Jan 26 18:45:02 volumio-mc systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Jan 26 18:45:02 volumio-mc systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Jan 26 18:45:02 volumio-mc kernel: intel_pmc_core intel_pmc_core.0: initialized Jan 26 18:45:02 volumio-mc sh[840]: command failed: Operation not supported (-95) Jan 26 18:45:02 volumio-mc rfkill[842]: unblock set for type wifi Jan 26 18:45:02 volumio-mc systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Jan 26 18:45:02 volumio-mc systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - RTL8188EUS 802.11n Wireless Network Adapter. Jan 26 18:45:02 volumio-mc systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Jan 26 18:45:02 volumio-mc systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 26 18:45:02 volumio-mc systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Jan 26 18:45:02 volumio-mc systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Jan 26 18:45:02 volumio-mc sh[859]: command failed: Operation not supported (-95) Jan 26 18:45:02 volumio-mc systemd[1]: Starting rpcbind.service - RPC bind portmap service... Jan 26 18:45:02 volumio-mc systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 26 18:45:02 volumio-mc systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Jan 26 18:45:02 volumio-mc systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jan 26 18:45:02 volumio-mc systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 26 18:45:02 volumio-mc kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 26 18:45:02 volumio-mc systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target sysinit.target - System Initialization. Jan 26 18:45:02 volumio-mc systemd[1]: Started acpid.path - ACPI Events Check. Jan 26 18:45:02 volumio-mc systemd[1]: Started apt-daily.timer - Daily apt download activities. Jan 26 18:45:02 volumio-mc systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Jan 26 18:45:02 volumio-mc systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Jan 26 18:45:02 volumio-mc systemd[1]: e2scrub_all.timer: Not using persistent file timestamp Mon 2026-01-26 23:29:27 +07 as it is in the future. Jan 26 18:45:02 volumio-mc systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Jan 26 18:45:02 volumio-mc systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Jan 26 18:45:02 volumio-mc systemd[1]: Started man-db.timer - Daily man-db regeneration. Jan 26 18:45:02 volumio-mc systemd[1]: ntpsec-rotate-stats.timer: Not using persistent file timestamp Mon 2026-01-26 23:29:15 +07 as it is in the future. Jan 26 18:45:02 volumio-mc systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Jan 26 18:45:02 volumio-mc systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Jan 26 18:45:02 volumio-mc systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target paths.target - Path Units. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target timers.target - Timer Units. Jan 26 18:45:02 volumio-mc systemd[1]: Listening on acpid.socket - ACPID Listen Socket. Jan 26 18:45:02 volumio-mc systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Jan 26 18:45:02 volumio-mc systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target sockets.target - Socket Units. Jan 26 18:45:02 volumio-mc systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jan 26 18:45:02 volumio-mc systemd[1]: Reached target basic.target - Basic System. Jan 26 18:45:02 volumio-mc sh[872]: dhcpcd-9.4.1 starting Jan 26 18:45:02 volumio-mc dhcpcd[872]: dhcpcd-9.4.1 starting Jan 26 18:45:02 volumio-mc dhcpcd[878]: DUID 00:04:48:4c:ad:06:72:84:51:48:9d:8d:51:c2:0a:63:99:42 Jan 26 18:45:02 volumio-mc sh[872]: DUID 00:04:48:4c:ad:06:72:84:51:48:9d:8d:51:c2:0a:63:99:42 Jan 26 18:45:02 volumio-mc haveged[856]: haveged: command socket is listening at fd 3 Jan 26 18:45:02 volumio-mc kernel: RPC: Registered named UNIX socket transport module. Jan 26 18:45:02 volumio-mc kernel: RPC: Registered udp transport module. Jan 26 18:45:02 volumio-mc kernel: RPC: Registered tcp transport module. Jan 26 18:45:02 volumio-mc kernel: RPC: Registered tcp-with-tls transport module. Jan 26 18:45:02 volumio-mc kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 26 18:45:02 volumio-mc kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 26 18:45:02 volumio-mc systemd[1]: Started acpid.service - ACPI event daemon. Jan 26 18:45:02 volumio-mc systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Jan 26 18:45:02 volumio-mc systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Jan 26 18:45:02 volumio-mc systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jan 26 18:45:02 volumio-mc systemd[1]: Starting bluetooth.service - Bluetooth service... Jan 26 18:45:02 volumio-mc systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 26 18:45:02 volumio-mc systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Jan 26 18:45:02 volumio-mc systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Jan 26 18:45:02 volumio-mc systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Jan 26 18:45:02 volumio-mc systemd[1]: Started iptables.service - Volumio Iptables Module. Jan 26 18:45:02 volumio-mc systemd[1]: Starting systemd-logind.service - User Login Management... Jan 26 18:45:02 volumio-mc systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Jan 26 18:45:02 volumio-mc systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 18:45:02 volumio-mc systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Jan 26 18:45:02 volumio-mc systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Jan 26 18:45:02 volumio-mc systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Jan 26 18:45:02 volumio-mc systemd[1]: Starting wireless.service - Wireless Services... Jan 26 18:45:02 volumio-mc systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target rpc_pipefs.target. Jan 26 18:45:02 volumio-mc systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jan 26 18:45:02 volumio-mc systemd[1]: Reached target nfs-client.target - NFS client services. Jan 26 18:45:02 volumio-mc volumio_rfkill_unblock.sh[896]: Volumio WiFi Soft Blocked check script Jan 26 18:45:02 volumio-mc volumio_rfkill_unblock.sh[896]: Check if rfkill is available Jan 26 18:45:02 volumio-mc volumio_rfkill_unblock.sh[896]: Check if rfkill listed devices are already unblocked Jan 26 18:45:02 volumio-mc volumio_rfkill_unblock.sh[896]: Wi-Fi is already unblocked. Jan 26 18:45:02 volumio-mc systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Jan 26 18:45:02 volumio-mc systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Jan 26 18:45:02 volumio-mc systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target sound.target - Sound Card. Jan 26 18:45:02 volumio-mc systemd[1]: e2scrub_reap.service: Deactivated successfully. Jan 26 18:45:02 volumio-mc systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Jan 26 18:45:02 volumio-mc dhcpcd[905]: dhcpcd-9.4.1 starting Jan 26 18:45:02 volumio-mc haveged[856]: haveged: ver: 1.9.14; arch: x86; vend: GenuineIntel; build: (gcc 12.2.0 ITV); collect: 128K Jan 26 18:45:02 volumio-mc haveged[856]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 32010/53875 Jan 26 18:45:02 volumio-mc haveged[856]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00235 Jan 26 18:45:02 volumio-mc haveged[856]: haveged: fills: 0, generated: 0 Jan 26 18:45:02 volumio-mc dhcpcd[911]: dev: loaded udev Jan 26 18:45:02 volumio-mc dhcpcd[911]: DUID 00:04:48:4c:ad:06:72:84:51:48:9d:8d:51:c2:0a:63:99:42 Jan 26 18:45:02 volumio-mc thd[893]: Unable to parse trigger line: Jan 26 18:45:02 volumio-mc thd[893]: Unable to parse trigger line: Jan 26 18:45:02 volumio-mc systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Jan 26 18:45:02 volumio-mc systemd-logind[892]: New seat seat0. Jan 26 18:45:02 volumio-mc systemd-logind[892]: Watching system buttons on /dev/input/event2 (Power Button) Jan 26 18:45:02 volumio-mc systemd-logind[892]: Watching system buttons on /dev/input/event0 (Power Button) Jan 26 18:45:02 volumio-mc systemd-logind[892]: Watching system buttons on /dev/input/event1 (Sleep Button) Jan 26 18:45:02 volumio-mc systemd-logind[892]: Watching system buttons on /dev/input/event9 (Logitech USB Keyboard) Jan 26 18:45:02 volumio-mc systemd-logind[892]: Watching system buttons on /dev/input/event11 (Logitech USB Keyboard System Control) Jan 26 18:45:02 volumio-mc systemd-logind[892]: Watching system buttons on /dev/input/event6 (MI Dongle MI Wireless Mouse Consumer Control) Jan 26 18:45:02 volumio-mc systemd[1]: Started rpcbind.service - RPC bind portmap service. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 26 18:45:02 volumio-mc systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Jan 26 18:45:02 volumio-mc thd[893]: Device /dev/input/event8 not suitable. Jan 26 18:45:02 volumio-mc systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Jan 26 18:45:02 volumio-mc systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jan 26 18:45:02 volumio-mc avahi-daemon[887]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jan 26 18:45:02 volumio-mc avahi-daemon[887]: Successfully dropped root privileges. Jan 26 18:45:02 volumio-mc avahi-daemon[887]: avahi-daemon 0.8 starting up. Jan 26 18:45:02 volumio-mc systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Jan 26 18:45:02 volumio-mc systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Jan 26 18:45:02 volumio-mc sh[948]: wlan0=wlan0 Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event0 to thd using --add... Jan 26 18:45:02 volumio-mc acpid[882]: starting up with netlink and the input layer Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event1 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event10 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event11 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event12 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event13 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event14 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event15 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event2 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event3 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event4 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event5 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event6 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event7 to thd using --add... Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event8 to thd using --add... Jan 26 18:45:02 volumio-mc bluetoothd[888]: Bluetooth daemon 5.83 Jan 26 18:45:02 volumio-mc acpid[882]: 3 rules loaded Jan 26 18:45:02 volumio-mc acpid[882]: waiting for events: event logging is off Jan 26 18:45:02 volumio-mc thd[893]: Device /dev/input/event8 not suitable. Jan 26 18:45:02 volumio-mc th-udev-rebind.sh[944]: Rebinding /dev/input/event9 to thd using --add... Jan 26 18:45:03 volumio-mc systemd[1]: th-udev-rebind.service: Deactivated successfully. Jan 26 18:45:03 volumio-mc systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Jan 26 18:45:03 volumio-mc volumio-remote-updater[895]: Volumio OTA Client: 1.7.0 Jan 26 18:45:03 volumio-mc volumio-remote-updater[895]: Error: No active session Jan 26 18:45:03 volumio-mc (udev-worker)[599]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jan 26 18:45:03 volumio-mc systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 26 18:45:03 volumio-mc avahi-daemon[887]: Successfully called chroot(). Jan 26 18:45:03 volumio-mc avahi-daemon[887]: Successfully dropped remaining capabilities. Jan 26 18:45:03 volumio-mc bluetoothd[888]: Starting SDP server Jan 26 18:45:03 volumio-mc bluetoothd[888]: Excluding (cli) sap Jan 26 18:45:03 volumio-mc bluetoothd[888]: Excluding (cli) midi Jan 26 18:45:03 volumio-mc bluetoothd[888]: Excluding (cli) battery Jan 26 18:45:03 volumio-mc avahi-daemon[887]: Loading service file /services/volumio.service. Jan 26 18:45:03 volumio-mc upmpdcli[894]: Could not open config: /tmp/upmpdcli.conf Jan 26 18:45:03 volumio-mc loadcpufreq[942]: Loading cpufreq kernel modules...done (acpi-cpufreq). Jan 26 18:45:03 volumio-mc kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 26 18:45:03 volumio-mc kernel: Bluetooth: BNEP filters: protocol multicast Jan 26 18:45:03 volumio-mc kernel: Bluetooth: BNEP socket layer initialized Jan 26 18:45:03 volumio-mc dbus-daemon[889]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=888 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Jan 26 18:45:03 volumio-mc bluetoothd[888]: Bluetooth management interface 1.23 initialized Jan 26 18:45:03 volumio-mc bluetoothd[888]: Battery Provider Manager created Jan 26 18:45:03 volumio-mc kernel: Bluetooth: MGMT ver 1.23 Jan 26 18:45:03 volumio-mc kernel: NET: Registered PF_ALG protocol family Jan 26 18:45:03 volumio-mc bluetoothd[888]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Jan 26 18:45:03 volumio-mc bluetoothd[888]: bap: Operation not supported (95) Jan 26 18:45:04 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Jan 26 18:45:08 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:08] [info] Error getting remote endpoint: asio.system:9 (Bad file descriptor) Jan 26 18:45:08 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:08] [info] asio async_shutdown error: asio.system:9 (Bad file descriptor) Jan 26 18:45:08 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:08] [error] handle_connect error: Timer Expired Jan 26 18:45:08 volumio-mc kernel: tg3 0000:04:00.0 eth0: No firmware running Jan 26 18:45:08 volumio-mc sh[872]: eth0: waiting for carrier Jan 26 18:45:08 volumio-mc dhcpcd[911]: eth0: waiting for carrier Jan 26 18:45:08 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Jan 26 18:45:08 volumio-mc dhcpcd[878]: eth0: waiting for carrier Jan 26 18:45:08 volumio-mc dhcpcd[911]: wlan0: waiting for carrier Jan 26 18:45:08 volumio-mc avahi-daemon[887]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jan 26 18:45:08 volumio-mc avahi-daemon[887]: New relevant interface lo.IPv4 for mDNS. Jan 26 18:45:08 volumio-mc avahi-daemon[887]: Network interface enumeration completed. Jan 26 18:45:08 volumio-mc avahi-daemon[887]: Registering new address record for 127.0.0.1 on lo.IPv4. Jan 26 18:45:08 volumio-mc bluetoothd[888]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Jan 26 18:45:08 volumio-mc systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Jan 26 18:45:08 volumio-mc systemd[1]: systemd-rfkill.service: Deactivated successfully. Jan 26 18:45:08 volumio-mc systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 18:45:08 volumio-mc systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 18:45:08 volumio-mc systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Jan 26 18:45:08 volumio-mc dbus-daemon[889]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 26 18:45:08 volumio-mc systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Jan 26 18:45:08 volumio-mc systemd[1]: iptables.service: Deactivated successfully. Jan 26 18:45:08 volumio-mc systemd[1]: Started bluetooth.service - Bluetooth service. Jan 26 18:45:08 volumio-mc systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jan 26 18:45:08 volumio-mc systemd[1]: Started systemd-logind.service - User Login Management. Jan 26 18:45:08 volumio-mc systemd[1]: Starting bluealsa.service - BlueALSA service... Jan 26 18:45:08 volumio-mc systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jan 26 18:45:08 volumio-mc cpufrequtils[1066]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Jan 26 18:45:08 volumio-mc systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Jan 26 18:45:08 volumio-mc dbus-daemon[889]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 26 18:45:08 volumio-mc systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jan 26 18:45:08 volumio-mc systemd[1]: Started wpa_supplicant.service - WPA supplicant. Jan 26 18:45:08 volumio-mc wpa_supplicant[1024]: Successfully initialized wpa_supplicant Jan 26 18:45:08 volumio-mc sudo[1074]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 26 18:45:08 volumio-mc sudo[1074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 18:45:08 volumio-mc sudo[1074]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:08 volumio-mc sudo[1082]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 26 18:45:08 volumio-mc sudo[1082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 18:45:08 volumio-mc sudo[1082]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:08 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: Cleaning previous... Jan 26 18:45:08 volumio-mc sudo[1085]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 26 18:45:08 volumio-mc sudo[1085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 18:45:08 volumio-mc sudo[1085]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:08 volumio-mc bluealsa[1071]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Jan 26 18:45:08 volumio-mc bluealsa[1071]: main.c:663: Starting main dispatching loop Jan 26 18:45:08 volumio-mc systemd[1]: Started bluealsa.service - BlueALSA service. Jan 26 18:45:08 volumio-mc bluealsa[1071]: main.c:118: Acquired D-Bus service name: org.bluealsa Jan 26 18:45:08 volumio-mc bluealsa[1071]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Jan 26 18:45:08 volumio-mc systemd[1]: Reached target bluetooth.target - Bluetooth Support. Jan 26 18:45:08 volumio-mc bluealsa[1071]: bluez.c:153: Registering media application: /org/bluez/hci0 Jan 26 18:45:08 volumio-mc bluealsa[1071]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Jan 26 18:45:08 volumio-mc bluealsa[1071]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Jan 26 18:45:08 volumio-mc bluealsa[1071]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Jan 26 18:45:08 volumio-mc bluealsa[1071]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Jan 26 18:45:08 volumio-mc bluetoothd[888]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Jan 26 18:45:08 volumio-mc bluetoothd[888]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Jan 26 18:45:08 volumio-mc bluetoothd[888]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Jan 26 18:45:08 volumio-mc bluetoothd[888]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Jan 26 18:45:09 volumio-mc avahi-daemon[887]: Server startup complete. Host name is volumio-mc.local. Local service cookie is 4025670412. Jan 26 18:45:10 volumio-mc avahi-daemon[887]: Service "Volumio MC" (/services/volumio.service) successfully established. Jan 26 18:45:11 volumio-mc kernel: tg3 0000:04:00.0 eth0: Link is up at 1000 Mbps, full duplex Jan 26 18:45:11 volumio-mc kernel: tg3 0000:04:00.0 eth0: Flow control is on for TX and on for RX Jan 26 18:45:11 volumio-mc kernel: tg3 0000:04:00.0 eth0: EEE is disabled Jan 26 18:45:11 volumio-mc sh[872]: eth0: carrier acquired Jan 26 18:45:11 volumio-mc dhcpcd[911]: eth0: carrier acquired Jan 26 18:45:11 volumio-mc dhcpcd[878]: eth0: carrier acquired Jan 26 18:45:11 volumio-mc dhcpcd[878]: eth0: IAID b9:07:9b:f7 Jan 26 18:45:11 volumio-mc sh[872]: eth0: IAID b9:07:9b:f7 Jan 26 18:45:11 volumio-mc dhcpcd[911]: eth0: IAID b9:07:9b:f7 Jan 26 18:45:11 volumio-mc dhcpcd[911]: eth0: adding address fe80::5214:295c:3829:70ae Jan 26 18:45:11 volumio-mc dhcpcd[878]: eth0: adding address fe80::5214:295c:3829:70ae Jan 26 18:45:11 volumio-mc sh[872]: eth0: adding address fe80::5214:295c:3829:70ae Jan 26 18:45:11 volumio-mc dhcpcd[911]: ipv6_addaddr1: Permission denied Jan 26 18:45:11 volumio-mc sh[872]: ipv6_addaddr1: Permission denied Jan 26 18:45:11 volumio-mc dhcpcd[878]: ipv6_addaddr1: Permission denied Jan 26 18:45:11 volumio-mc dhcpcd[911]: eth0: soliciting an IPv6 router Jan 26 18:45:11 volumio-mc dhcpcd[878]: eth0: soliciting an IPv6 router Jan 26 18:45:11 volumio-mc sh[872]: eth0: soliciting an IPv6 router Jan 26 18:45:12 volumio-mc dhcpcd[878]: eth0: soliciting a DHCP lease Jan 26 18:45:12 volumio-mc sh[872]: eth0: soliciting a DHCP lease Jan 26 18:45:12 volumio-mc dhcpcd[878]: eth0: offered 192.168.1.29 from 192.168.1.1 Jan 26 18:45:12 volumio-mc sh[872]: eth0: offered 192.168.1.29 from 192.168.1.1 Jan 26 18:45:12 volumio-mc dhcpcd[878]: eth0: probing address 192.168.1.29/24 Jan 26 18:45:12 volumio-mc sh[872]: eth0: probing address 192.168.1.29/24 Jan 26 18:45:12 volumio-mc dhcpcd[911]: eth0: soliciting a DHCP lease Jan 26 18:45:13 volumio-mc dhcpcd[911]: eth0: offered 192.168.1.29 from 192.168.1.1 Jan 26 18:45:13 volumio-mc dhcpcd[911]: eth0: probing address 192.168.1.29/24 Jan 26 18:45:13 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:13] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 26 18:45:13 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:13] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 26 18:45:13 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:13] [error] handle_connect error: Connection refused Jan 26 18:45:13 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: UdevCoordinator: udev settle timeout or error: Error: spawnSync /bin/sh ETIMEDOUT Jan 26 18:45:13 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Jan 26 18:45:13 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Jan 26 18:45:13 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 5c:a6:e6:85:67:a4) Jan 26 18:45:13 volumio-mc sudo[1101]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 26 18:45:13 volumio-mc sudo[1101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:13 volumio-mc sudo[1101]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:13 volumio-mc sudo[1109]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 26 18:45:13 volumio-mc sudo[1109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:14 volumio-mc sudo[1109]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:14 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: VN Jan 26 18:45:14 volumio-mc sudo[1115]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set VN Jan 26 18:45:14 volumio-mc sudo[1115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:14 volumio-mc sudo[1115]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:14 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: VN Jan 26 18:45:14 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: refreshEthernetState: Corrected ethernet state: connected Jan 26 18:45:14 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Jan 26 18:45:14 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Jan 26 18:45:14 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Jan 26 18:45:14 volumio-mc sudo[1124]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 26 18:45:14 volumio-mc sudo[1124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 18:45:14 volumio-mc sudo[1124]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:14 volumio-mc sudo[1127]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 26 18:45:14 volumio-mc sudo[1127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 18:45:14 volumio-mc sudo[1127]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:14 volumio-mc wpa_supplicant[1130]: Successfully initialized wpa_supplicant Jan 26 18:45:15 volumio-mc wpa_supplicant[1131]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 26 18:45:15 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Jan 26 18:45:15 volumio-mc wireless.js[897]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jan 26 18:45:15 volumio-mc iwconfig[1146]: Error for wireless request "Set Power Management" (8B2C) : Jan 26 18:45:15 volumio-mc iwconfig[1146]: SET failed on device wlan0 ; Operation not supported. Jan 26 18:45:15 volumio-mc systemd[1]: Started wireless.service - Wireless Services. Jan 26 18:45:15 volumio-mc systemd[1]: Started volumio.service - Volumio Backend Module. Jan 26 18:45:15 volumio-mc systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Jan 26 18:45:15 volumio-mc systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Jan 26 18:45:15 volumio-mc systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Jan 26 18:45:15 volumio-mc systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jan 26 18:45:15 volumio-mc volumio-cpu-tweak[1151]: Setting RT Priority for mpd Jan 26 18:45:15 volumio-mc volumio-cpu-tweak[1190]: pid 35's current scheduling policy: SCHED_OTHER Jan 26 18:45:15 volumio-mc volumio-cpu-tweak[1190]: pid 35's current scheduling priority: 0 Jan 26 18:45:15 volumio-mc volumio-cpu-tweak[1151]: Setting MPD Affinity Jan 26 18:45:15 volumio-mc volumio-cpu-tweak[1197]: pid 3's current affinity mask: 3f Jan 26 18:45:15 volumio-mc volumio-cpu-tweak[1151]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 26 18:45:15 volumio-mc systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jan 26 18:45:16 volumio-mc soundcard-init.sh[1215]: Card 0 Chip Intel Kabylake HDMI Name HDA Intel PCH Jan 26 18:45:16 volumio-mc soundcard-init.sh[1238]: amixer: Unable to find simple control 'Master',0 Jan 26 18:45:16 volumio-mc soundcard-init.sh[1215]: Card 5 Chip USB Mixer Name D50 III Jan 26 18:45:16 volumio-mc systemd[1]: soundcard-init.service: Deactivated successfully. Jan 26 18:45:16 volumio-mc startx[1230]: X.Org X Server 1.21.1.7 Jan 26 18:45:16 volumio-mc startx[1230]: X Protocol Version 11, Revision 0 Jan 26 18:45:16 volumio-mc startx[1230]: Current Operating System: Linux volumio-mc 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Jan 26 18:45:16 volumio-mc startx[1230]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=3f06cc5a-6a9b-457e-a762-7ec23630efac bootpart=UUID=6218-8677 datapart=UUID=708c6c01-5270-4097-8fcb-d77680d13691 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Jan 26 18:45:16 volumio-mc startx[1230]: xorg-server 2:21.1.7-3+deb12u10 (https://www.debian.org/support) Jan 26 18:45:16 volumio-mc startx[1230]: Current version of pixman: 0.42.2 Jan 26 18:45:16 volumio-mc startx[1230]: Before reporting problems, check http://wiki.x.org Jan 26 18:45:16 volumio-mc startx[1230]: to make sure that you have the latest version. Jan 26 18:45:16 volumio-mc startx[1230]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 26 18:45:16 volumio-mc startx[1230]: (++) from command line, (!!) notice, (II) informational, Jan 26 18:45:16 volumio-mc startx[1230]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 26 18:45:16 volumio-mc startx[1230]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jan 26 18:45:16 2026 Jan 26 18:45:16 volumio-mc startx[1230]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 26 18:45:17 volumio-mc dhcpcd[911]: eth0: leased 192.168.1.29 for 86400 seconds Jan 26 18:45:17 volumio-mc avahi-daemon[887]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.29. Jan 26 18:45:17 volumio-mc avahi-daemon[887]: New relevant interface eth0.IPv4 for mDNS. Jan 26 18:45:17 volumio-mc dhcpcd[911]: eth0: adding route to 192.168.1.0/24 Jan 26 18:45:17 volumio-mc avahi-daemon[887]: Registering new address record for 192.168.1.29 on eth0.IPv4. Jan 26 18:45:17 volumio-mc dhcpcd[911]: eth0: adding default route via 192.168.1.1 Jan 26 18:45:18 volumio-mc dhcpcd[878]: eth0: leased 192.168.1.29 for 86400 seconds Jan 26 18:45:18 volumio-mc dhcpcd[878]: eth0: adding route to 192.168.1.0/24 Jan 26 18:45:18 volumio-mc sh[872]: eth0: leased 192.168.1.29 for 86400 seconds Jan 26 18:45:18 volumio-mc sh[872]: eth0: adding route to 192.168.1.0/24 Jan 26 18:45:18 volumio-mc sh[872]: eth0: adding default route via 192.168.1.1 Jan 26 18:45:18 volumio-mc dhcpcd[878]: eth0: adding default route via 192.168.1.1 Jan 26 18:45:19 volumio-mc sh[872]: forked to background, child pid 877 Jan 26 18:45:19 volumio-mc sh[1311]: eth0=eth0 Jan 26 18:45:20 volumio-mc volumio[1147]: info: ------------------------------------------- Jan 26 18:45:20 volumio-mc volumio[1147]: info: ----- Volumio3 ---- Jan 26 18:45:20 volumio-mc volumio[1147]: info: ------------------------------------------- Jan 26 18:45:20 volumio-mc volumio[1147]: info: ----- System startup ---- Jan 26 18:45:20 volumio-mc volumio[1147]: info: ------------------------------------------- Jan 26 18:45:20 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:20] [connect] Successful connection Jan 26 18:45:20 volumio-mc volumio[1147]: info: MYVOLUMIO Environment detected Jan 26 18:45:20 volumio-mc volumio[1147]: info: Plugin folders cleanup Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning into folder /volumio/app/plugins/ Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning category audio_interface Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning category miscellanea Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning category music_service Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning category plugins.json Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning category system_controller Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning category user_interface Jan 26 18:45:20 volumio-mc volumio[1147]: info: Scanning into folder /data/plugins/ Jan 26 18:45:20 volumio-mc volumio[1147]: info: Plugin folders cleanup completed Jan 26 18:45:20 volumio-mc volumio[1147]: info: ------------------------------------------- Jan 26 18:45:20 volumio-mc volumio[1147]: info: ----- Core plugins startup ---- Jan 26 18:45:20 volumio-mc volumio[1147]: info: ------------------------------------------- Jan 26 18:45:20 volumio-mc volumio[1147]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 18:45:20 volumio-mc volumio[1147]: info: Adding plugin upnp to MyMusic Plugins Jan 26 18:45:20 volumio-mc volumio[1147]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 18:45:20 volumio-mc volumio[1147]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 18:45:20 volumio-mc volumio[1147]: info: Loading plugins from folder /data/plugins/ Jan 26 18:45:20 volumio-mc volumio[1147]: info: Loading plugin "system"... Jan 26 18:45:20 volumio-mc volumio[1147]: info: Loading plugin "appearance"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "network"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Refreshing Cached IP Addresses Jan 26 18:45:21 volumio-mc sudo[1332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 18:45:21 volumio-mc sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:21 volumio-mc sudo[1334]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 18:45:21 volumio-mc sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:21 volumio-mc sudo[1332]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:21 volumio-mc sudo[1334]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "services"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "alsa_controller"... Jan 26 18:45:21 volumio-mc sudo[1341]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 26 18:45:21 volumio-mc sudo[1341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "wizard"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "networkfs"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Starting Udev Watcher for removable devices Jan 26 18:45:21 volumio-mc volumio[1147]: info: Ignoring mount for partition: boot Jan 26 18:45:21 volumio-mc volumio[1147]: info: Ignoring mount for partition: volumio Jan 26 18:45:21 volumio-mc volumio[1147]: info: Ignoring mount for partition: volumio_data Jan 26 18:45:21 volumio-mc volumio[1147]: info: Ignoring mount for partition: EFI Jan 26 18:45:21 volumio-mc volumio[1147]: info: Ignoring mount for partition: 78589299-d5af-4437-98bf-015936d14cc8 Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "volumio_command_line_client"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "upnp"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: [1769427921196] Starting Upmpd Daemon Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "my_music"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "mpd"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "upnp_browser"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Starting UPNP Browser Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "alarm-clock"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "airplay_emulation"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Starting Shairport Sync Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "last_100"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "webradio"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "i2s_dacs"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: I2S DAC not set, start Auto-detection Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "volumiodiscovery"... Jan 26 18:45:21 volumio-mc volumio[1147]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 18:45:21 volumio-mc volumio[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 18:45:21 volumio-mc volumio[1147]: *** WARNING *** For more information see Jan 26 18:45:21 volumio-mc volumio[1147]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 18:45:21 volumio-mc volumio[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 18:45:21 volumio-mc node[1147]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 18:45:21 volumio-mc volumio[1147]: *** WARNING *** For more information see Jan 26 18:45:21 volumio-mc node[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 18:45:21 volumio-mc node[1147]: *** WARNING *** For more information see Jan 26 18:45:21 volumio-mc node[1147]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 18:45:21 volumio-mc node[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 18:45:21 volumio-mc node[1147]: *** WARNING *** For more information see Jan 26 18:45:21 volumio-mc volumio[1147]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 18:45:21 volumio-mc volumio[1147]: info: Discovery: Started advertising with name: Volumio MC Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "outputs"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "albumart"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Plugin example_plugin is not enabled Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "inputs"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "updater_comm"... Jan 26 18:45:21 volumio-mc volumio[1380]: Forking 5 albumart workers Jan 26 18:45:21 volumio-mc volumio[1147]: info: Plugin mpdemulation is not enabled Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "rest_api"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading plugin "websocket"... Jan 26 18:45:21 volumio-mc volumio[1147]: info: Starting Socket.io Server version 1.7.4 Jan 26 18:45:21 volumio-mc volumio[1147]: info: Loading i18n strings for locale vi Jan 26 18:45:21 volumio-mc volumio[1147]: Updating browse sources language Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::initPlayerControls Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: Express server listening on port 3000 Jan 26 18:45:21 volumio-mc volumio[1147]: [Metrics] WebUI: 4s 33.74ms Jan 26 18:45:21 volumio-mc volumio[1147]: info: Setting Device type: x86 Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreStateMachine::resetVolumioState Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreStateMachine::getcurrentVolume Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 26 18:45:21 volumio-mc volumio[1147]: info: Completed loading Core Plugins Jan 26 18:45:21 volumio-mc volumio[1147]: info: Preparing to generate the ALSA configuration file Jan 26 18:45:21 volumio-mc volumio[1394]: Starting albumart workers Jan 26 18:45:21 volumio-mc volumio[1391]: Starting albumart workers Jan 26 18:45:21 volumio-mc volumio[1392]: Starting albumart workers Jan 26 18:45:21 volumio-mc volumio[1147]: info: Volumio Network Manager: Network status updated: 1 Jan 26 18:45:21 volumio-mc volumio[1147]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 26 18:45:21 volumio-mc volumio[1393]: Starting albumart workers Jan 26 18:45:21 volumio-mc volumio[1147]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 26 18:45:21 volumio-mc volumio[1390]: Starting albumart workers Jan 26 18:45:21 volumio-mc volumio-remote-updater[895]: [2026-01-26 18:45:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769427920 101 Jan 26 18:45:21 volumio-mc volumio[1147]: 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: 1 Jan 26 18:45:21 volumio-mc volumio[1147]: info: VolumeController:: Volume=undefined Mute =false Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreStateMachine::pushState Jan 26 18:45:21 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 26 18:45:21 volumio-mc volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 18:45:21 volumio-mc volumio[1147]: info: Reloading queue from file Jan 26 18:45:21 volumio-mc volumio[1147]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 26 18:45:21 volumio-mc volumio[1147]: info: Asound.conf file unchanged, so no further update is needed Jan 26 18:45:21 volumio-mc volumio[1147]: info: Output device has changed, restarting MPD Jan 26 18:45:21 volumio-mc sudo[1451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 18:45:21 volumio-mc sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:21 volumio-mc sudo[1451]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:21 volumio-mc volumio[1147]: info: Output device has changed, restarting Shairport Sync Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:21 volumio-mc sudo[1466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 18:45:21 volumio-mc sudo[1466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:21 volumio-mc volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 18:45:21 volumio-mc volumio[1147]: info: ___________ START PLUGINS ___________ Jan 26 18:45:21 volumio-mc volumio[1147]: info: ControllerMpd::onStart: Initializing MPD Jan 26 18:45:21 volumio-mc volumio[1147]: info: Creating MPD Configuration file Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 18:45:21 volumio-mc volumio[1147]: info: [1769427921905] CoreMusicLibrary::Adding element Máy chủ Media Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 18:45:21 volumio-mc volumio[1147]: info: UPNP Browser: Client initialized successfully Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:21 volumio-mc sudo[1492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 18:45:21 volumio-mc sudo[1492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:21 volumio-mc sudo[1492]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:21 volumio-mc sudo[1498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 18:45:21 volumio-mc sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:21 volumio-mc volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 18:45:21 volumio-mc volumio[1147]: info: [1769427921928] CoreMusicLibrary::Adding element Last_100 Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 18:45:21 volumio-mc volumio[1147]: info: [1769427921928] CoreMusicLibrary::Adding element Webradio Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 18:45:21 volumio-mc volumio[1147]: info: Initializing BBC Radios Jan 26 18:45:21 volumio-mc systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 18:45:21 volumio-mc systemd[1]: mpd.socket: Deactivated successfully. Jan 26 18:45:21 volumio-mc systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 18:45:21 volumio-mc systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 18:45:21 volumio-mc systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 18:45:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:21 volumio-mc volumio[1147]: info: Volumio Calling Home Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreStateMachine::setRepeat null single undefined Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreStateMachine::pushState Jan 26 18:45:22 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreStateMachine::setRandom null Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreStateMachine::pushState Jan 26 18:45:22 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 26 18:45:22 volumio-mc volumio[1147]: info: MPD Permissions set Jan 26 18:45:22 volumio-mc volumio[1147]: info: MPD Permissions set Jan 26 18:45:22 volumio-mc volumio[1147]: info: VolumeController:: Volume=undefined Mute =false Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreStateMachine::pushState Jan 26 18:45:22 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 26 18:45:22 volumio-mc volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:45:22 volumio-mc volumio[1147]: info: Starting Shairport Sync Jan 26 18:45:22 volumio-mc volumio[1147]: info: Starting Shairport Sync Jan 26 18:45:22 volumio-mc volumio[1147]: info: Starting Shairport Sync Jan 26 18:45:22 volumio-mc sudo[1567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 18:45:22 volumio-mc sudo[1565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 18:45:22 volumio-mc sudo[1565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:22 volumio-mc sudo[1567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:22 volumio-mc sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 18:45:22 volumio-mc sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetState Jan 26 18:45:22 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:22 volumio-mc sudo[1341]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:22 volumio-mc volumio[1147]: info: Volumio called home Jan 26 18:45:22 volumio-mc volumio[1147]: info: Discovery: adding a4c01791-b54d-4bc1-9fe3-b3f7c1e42660 Jan 26 18:45:22 volumio-mc volumio[1147]: info: Discovery: Found device Volumio MC Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetState Jan 26 18:45:22 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:22 volumio-mc volumio[1147]: info: Discovery: this is already registered, a4c01791-b54d-4bc1-9fe3-b3f7c1e42660 Jan 26 18:45:22 volumio-mc volumio[1147]: info: Discovery: Found device Volumio MC Jan 26 18:45:22 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetState Jan 26 18:45:22 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:23 volumio-mc systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Jan 26 18:45:23 volumio-mc systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 18:45:23 volumio-mc systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 18:45:23 volumio-mc upmpdcli[1622]: Could not open config: /tmp/upmpdcli.conf Jan 26 18:45:23 volumio-mc systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 18:45:23 volumio-mc systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 18:45:25 volumio-mc volumio[1147]: error: error Jan 26 18:45:30 volumio-mc volumio[1147]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 26 18:45:30 volumio-mc systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 26 18:45:31 volumio-mc sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 18:45:31 volumio-mc sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 18:45:31 volumio-mc sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:31 volumio-mc sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:31 volumio-mc sudo[1828]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:31 volumio-mc sudo[1830]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:31 volumio-mc sudo[1835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 18:45:31 volumio-mc sudo[1835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:45:31 volumio-mc volumio[1147]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetState Jan 26 18:45:32 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 18:45:32 volumio-mc volumio[1147]: info: Listing playlists Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 18:45:32 volumio-mc volumio[1147]: info: VolumeController::SetAlsaVolume0 Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreStateMachine::pushState Jan 26 18:45:32 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:45:32 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 26 18:45:32 volumio-mc volumio[1147]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 26 18:45:34 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:45:34 volumio-mc volumio[1147]: info: Received Get System Info Jan 26 18:45:34 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:45:34 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:45:34 volumio-mc volumio[1147]: info: Discovery: Getting this device information Jan 26 18:45:34 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetState Jan 26 18:45:34 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:34 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:45:38 volumio-mc systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 26 18:45:38 volumio-mc systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 26 18:45:38 volumio-mc systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 18:45:38 volumio-mc systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 18:45:38 volumio-mc sudo[1835]: pam_unix(sudo:session): session closed for user root Jan 26 18:45:38 volumio-mc volumio[1147]: info: Upmpdcli Daemon Started Jan 26 18:45:42 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:45:42 volumio-mc volumio[1147]: info: CURURI: music-library Jan 26 18:45:42 volumio-mc volumio[1147]: error: Failed to execute browseSource, failure: TypeError: Cannot read properties of undefined (reading 'then') Jan 26 18:45:42 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:45:42 volumio-mc volumio[1147]: info: CoreStateMachine::getQueue Jan 26 18:45:42 volumio-mc volumio[1147]: info: CorePlayQueue::getQueue Jan 26 18:45:51 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 18:45:51 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:45:51 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 18:45:51 volumio-mc volumio[1147]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Jan 26 18:45:51 volumio-mc volumio[1147]: info: ------------------------------------------- Jan 26 18:45:51 volumio-mc volumio[1147]: info: ----- MyVolumio plugins startup ---- Jan 26 18:45:51 volumio-mc volumio[1147]: info: ------------------------------------------- Jan 26 18:45:51 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 18:45:52 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 18:45:52 volumio-mc volumio[1147]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 26 18:45:52 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 26 18:45:52 volumio-mc volumio[1147]: info: Received Get System Version Jan 26 18:45:52 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 18:45:52 volumio-mc volumio[1147]: info: Received Get System Info Jan 26 18:45:52 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:45:52 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:45:52 volumio-mc volumio[1147]: info: Discovery: Getting this device information Jan 26 18:45:52 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetState Jan 26 18:45:52 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:45:52 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:45:55 volumio-mc kernel: applesmc applesmc.768: probe with driver applesmc failed with error -5 Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 26 18:46:00 volumio-mc volumio[1147]: info: Adding plugin bluetooth to MyMusic Plugins Jan 26 18:46:00 volumio-mc volumio[1147]: info: Adding plugin multiroom to MyMusic Plugins Jan 26 18:46:00 volumio-mc volumio[1147]: info: Adding plugin metavolumio to MyMusic Plugins Jan 26 18:46:00 volumio-mc systemd-udevd[594]: id: Worker [623] processing SEQNUM=4441 is taking a long time Jan 26 18:46:00 volumio-mc volumio[1147]: info: Adding plugin cd_controller to MyMusic Plugins Jan 26 18:46:00 volumio-mc volumio[1147]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 26 18:46:00 volumio-mc volumio[1147]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 26 18:46:00 volumio-mc volumio[1147]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 26 18:46:00 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 26 18:46:01 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 26 18:46:01 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 26 18:46:01 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:46:01 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:46:01 volumio-mc volumio[1147]: info: Starting MyVolumio Remote Streaming Endpoints Jan 26 18:46:01 volumio-mc volumio[1147]: info: MyVolumio not started Jan 26 18:46:01 volumio-mc volumio[1147]: info: Initializing device activation check Jan 26 18:46:01 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 26 18:46:01 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 26 18:46:01 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 26 18:46:01 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 26 18:46:01 volumio-mc volumio[1147]: info: Streaming services startup Jan 26 18:46:01 volumio-mc volumio[1147]: info: Starting Streaming Daemon Jan 26 18:46:01 volumio-mc sudo[1935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 18:46:01 volumio-mc volumio[1147]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 26 18:46:01 volumio-mc sudo[1935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:46:01 volumio-mc sudo[1935]: pam_unix(sudo:session): session closed for user root Jan 26 18:46:01 volumio-mc volumio[1147]: error: Cannot start Volumio Streaming Daemon Jan 26 18:46:01 volumio-mc volumio[1147]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 18:46:01 volumio-mc volumio[1147]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 18:46:12 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 26 18:46:12 volumio-mc volumio[1147]: info: UPNP Browser: No servers found, reinitializing and searching... Jan 26 18:46:14 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 26 18:46:14 volumio-mc volumio[1147]: info: UPNP Browser: No servers found, reinitializing and searching... Jan 26 18:46:15 volumio-mc volumio[1147]: info: UPNP Browser: Returning 0 server(s) after 3s wait Jan 26 18:46:15 volumio-mc volumio[1147]: info: Preload queue cleared Jan 26 18:46:15 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 26 18:46:15 volumio-mc volumio[1147]: info: UPNP Browser: No servers found, reinitializing and searching... Jan 26 18:46:16 volumio-mc volumio[1147]: error: error Jan 26 18:46:16 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 18:46:16 volumio-mc volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 26 18:46:16 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetState Jan 26 18:46:16 volumio-mc volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 26 18:46:17 volumio-mc volumio[1147]: info: UPNP Browser: Returning 0 server(s) after 3s wait Jan 26 18:46:17 volumio-mc volumio[1147]: info: Preload queue cleared Jan 26 18:46:17 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:46:17 volumio-mc volumio[1147]: info: CURURI: music-library Jan 26 18:46:17 volumio-mc volumio[1147]: error: Failed to execute browseSource, failure: TypeError: Cannot read properties of undefined (reading 'then') Jan 26 18:46:18 volumio-mc volumio[1147]: error: error Jan 26 18:46:18 volumio-mc volumio[1147]: info: UPNP Browser: Returning 0 server(s) after 3s wait Jan 26 18:46:18 volumio-mc volumio[1147]: info: Preload queue cleared Jan 26 18:46:19 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:46:19 volumio-mc volumio[1147]: info: CURURI: music-library Jan 26 18:46:19 volumio-mc volumio[1147]: error: Failed to execute browseSource, failure: TypeError: Cannot read properties of undefined (reading 'then') Jan 26 18:46:19 volumio-mc volumio[1147]: error: error Jan 26 18:46:19 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:46:19 volumio-mc volumio[1147]: info: CURURI: music-library Jan 26 18:46:19 volumio-mc volumio[1147]: error: Failed to execute browseSource, failure: TypeError: Cannot read properties of undefined (reading 'then') Jan 26 18:46:20 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:46:20 volumio-mc volumio[1147]: info: CURURI: playlists Jan 26 18:46:20 volumio-mc volumio[1147]: info: Listing playlists Jan 26 18:46:20 volumio-mc volumio[1147]: info: Preload queue cleared Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 18:46:21 volumio-mc volumio[1147]: info: Preload queue cleared Jan 26 18:46:21 volumio-mc volumio[1147]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 26 18:46:21 volumio-mc volumio[1147]: info: Completed starting MyVolumio Plugin Jan 26 18:46:21 volumio-mc volumio[1147]: [Metrics] CommandRouter: 60s 824.37ms Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::volumiosetStartupVolume Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:46:21 volumio-mc volumio[1147]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:46:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 18:46:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 18:46:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 18:46:22 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:46:22 volumio-mc volumio[1147]: info: CURURI: artists:// Jan 26 18:46:22 volumio-mc volumio[1147]: error: Failed to execute browseSource, failure: TypeError: Cannot read properties of undefined (reading 'sendCommand') Jan 26 18:46:23 volumio-mc volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:46:23 volumio-mc volumio[1147]: info: CURURI: albums:// Jan 26 18:46:23 volumio-mc volumio[1147]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:46:23 volumio-mc volumio[1147]: TypeError: Cannot read properties of undefined (reading 'sendCommand') Jan 26 18:46:23 volumio-mc volumio[1147]: at /volumio/app/plugins/music_service/mpd/index.js:2987:22 Jan 26 18:46:23 volumio-mc volumio[1147]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 26 18:46:23 volumio-mc volumio[1147]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:46:23 volumio-mc sudo[2010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 18:45' Jan 26 18:46:23 volumio-mc sudo[2010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"