-- Journal begins at Sun 2026-05-31 09:05:57 CEST, ends at Sun 2026-05-31 09:07:57 CEST. -- May 31 09:06:00 mpd kernel: sunxi_cedrus: module is from the staging directory, the quality is unknown, you have been warned. May 31 09:06:00 mpd kernel: cedrus 1c0e000.video-codec: Device registered as /dev/video0 May 31 09:06:00 mpd kernel: Registered IR keymap rc-empty May 31 09:06:00 mpd kernel: rc rc0: sunxi-ir as /devices/platform/soc/1c21800.ir/rc/rc0 May 31 09:06:00 mpd kernel: rc rc0: lirc_dev: driver sunxi-ir registered at minor = 0, raw IR receiver, no transmitter May 31 09:06:01 mpd kernel: input: sunxi-ir as /devices/platform/soc/1c21800.ir/rc/rc0/input1 May 31 09:06:01 mpd kernel: sunxi-ir 1c21800.ir: initialized sunXi IR driver May 31 09:06:01 mpd kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 31 09:06:01 mpd kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 31 09:06:01 mpd systemd[1]: Found device /dev/mmcblk0p1. May 31 09:06:01 mpd kernel: cfg80211: loaded regulatory.db is malformed or signature is missing/invalid May 31 09:06:01 mpd kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43362-sdio for chip BCM43362/1 May 31 09:06:01 mpd kernel: axp20x-adc axp20x-adc: DMA mask not set May 31 09:06:01 mpd kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43362-sdio.lemaker,bananapro.bin failed with error -2 May 31 09:06:01 mpd kernel: brcmfmac mmc1:0001:1: Falling back to sysfs fallback for: brcm/brcmfmac43362-sdio.lemaker,bananapro.bin May 31 09:06:01 mpd kernel: axp20x-battery-power-supply axp20x-battery-power-supply: DMA mask not set May 31 09:06:01 mpd systemd[1]: Found device /dev/ttyS0. May 31 09:06:01 mpd systemd[1]: Created slice system-lvm2\x2dpvscan.slice. May 31 09:06:02 mpd systemd[1]: Starting LVM event activation on device 8:1... May 31 09:06:02 mpd systemd[1]: Starting File System Check on /dev/mmcblk0p1... May 31 09:06:02 mpd systemd-udevd[484]: Using default interface naming scheme 'v247'. May 31 09:06:02 mpd systemd-udevd[484]: eth0: Failed to query device driver: Device or resource busy May 31 09:06:02 mpd systemd-udevd[484]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. May 31 09:06:02 mpd systemd-udevd[484]: ethtool: could not get ethtool features for eth0 May 31 09:06:02 mpd systemd-udevd[484]: eth0: Could not set offload features, ignoring: Device or resource busy May 31 09:06:02 mpd systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 31 09:06:02 mpd systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 31 09:06:02 mpd systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 31 09:06:02 mpd systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 31 09:06:02 mpd systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. May 31 09:06:02 mpd systemd[1]: Started File System Check Daemon to report status. May 31 09:06:02 mpd kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 May 31 09:06:02 mpd kernel: usbcore: registered new interface driver snd-usb-audio May 31 09:06:02 mpd lvm[536]: pvscan[536] PV /dev/sda1 online, VG Audio_vg is complete. May 31 09:06:03 mpd lvm[536]: pvscan[536] VG Audio_vg run autoactivation. May 31 09:06:03 mpd systemd-udevd[474]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. May 31 09:06:03 mpd systemd-fsck[579]: fsck.fat 4.2 (2021-01-31) May 31 09:06:03 mpd systemd-fsck[579]: /dev/mmcblk0p1: 251 files, 65501/245980 clusters May 31 09:06:03 mpd systemd[1]: Finished File System Check on /dev/mmcblk0p1. May 31 09:06:03 mpd systemd[1]: Mounting /boot... May 31 09:06:03 mpd systemd[1]: Mounted /boot. May 31 09:06:03 mpd systemd[1]: Found device /sys/subsystem/net/devices/eth0. May 31 09:06:03 mpd systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 31 09:06:04 mpd kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43362-sdio for chip BCM43362/1 May 31 09:06:04 mpd systemd-udevd[473]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 31 09:06:04 mpd systemd-udevd[478]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 31 09:06:04 mpd kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available May 31 09:06:04 mpd kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43362/1 wl0: Dec 5 2019 07:26:04 version 5.90.254 FWID 01-0 May 31 09:06:04 mpd systemd-udevd[477]: Using default interface naming scheme 'v247'. May 31 09:06:04 mpd systemd-udevd[477]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. May 31 09:06:05 mpd systemd[1]: Found device /dev/Audio_vg/lv_audio. May 31 09:06:05 mpd lvm[536]: 2 logical volume(s) in volume group "Audio_vg" now active May 31 09:06:05 mpd systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 31 09:06:05 mpd systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 31 09:06:05 mpd systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 31 09:06:05 mpd systemd[1]: Starting File System Check on /dev/Audio_vg/lv_audio... May 31 09:06:05 mpd systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 31 09:06:05 mpd systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. May 31 09:06:05 mpd systemd[1]: Starting Load/Save RF Kill Switch Status... May 31 09:06:05 mpd systemd[1]: Finished LVM event activation on device 8:1. May 31 09:06:05 mpd systemd[1]: Found device /dev/Audio_vg/backup01. May 31 09:06:05 mpd systemd[1]: Starting File System Check on /dev/Audio_vg/backup01... May 31 09:06:05 mpd systemd[1]: Started Load/Save RF Kill Switch Status. May 31 09:06:05 mpd systemd[1]: Found device /sys/subsystem/net/devices/wlan0. May 31 09:06:05 mpd systemd-fsck[719]: /dev/mapper/Audio_vg-backup01: clean, 231001/195559424 files, 278718233/782236672 blocks May 31 09:06:05 mpd systemd[1]: Finished File System Check on /dev/Audio_vg/backup01. May 31 09:06:05 mpd systemd[1]: Mounting /srv/backup... May 31 09:06:05 mpd systemd-fsck[714]: /dev/mapper/Audio_vg-lv_audio: clean, 12972/268435456 files, 349194105/2147483648 blocks May 31 09:06:05 mpd systemd[1]: Finished File System Check on /dev/Audio_vg/lv_audio. May 31 09:06:05 mpd systemd[1]: Mounting /audio... May 31 09:06:06 mpd kernel: EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. May 31 09:06:06 mpd systemd[1]: Mounted /srv/backup. May 31 09:06:07 mpd kernel: EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. May 31 09:06:07 mpd systemd[1]: Mounted /audio. May 31 09:06:07 mpd systemd[1]: Reached target Local File Systems. May 31 09:06:07 mpd systemd[1]: Started ifup for eth0. May 31 09:06:07 mpd systemd[1]: Started ifup for wlan0. May 31 09:06:07 mpd systemd[1]: Starting Preprocess NFS configuration... May 31 09:06:07 mpd systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 31 09:06:07 mpd systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. May 31 09:06:07 mpd systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 31 09:06:07 mpd systemd[1]: Starting Create Volatile Files and Directories... May 31 09:06:07 mpd systemd[1]: Received SIGRTMIN+20 from PID 328 (plymouthd). May 31 09:06:07 mpd systemd[1]: Finished Tell Plymouth To Write Out Runtime Data. May 31 09:06:07 mpd systemd[1]: nfs-config.service: Succeeded. May 31 09:06:07 mpd systemd[1]: Finished Preprocess NFS configuration. May 31 09:06:07 mpd systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 31 09:06:07 mpd systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 31 09:06:07 mpd systemd[1]: Reached target NFS client services. May 31 09:06:07 mpd systemd[1]: Finished Create Volatile Files and Directories. May 31 09:06:07 mpd systemd[1]: Started Entropy Daemon based on the HAVEGE algorithm. May 31 09:06:07 mpd systemd[1]: Starting RPC bind portmap service... May 31 09:06:07 mpd systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 31 09:06:08 mpd systemd[1]: Finished Update UTMP about System Boot/Shutdown. May 31 09:06:08 mpd systemd[1]: Reached target System Initialization. May 31 09:06:08 mpd systemd[1]: Started Daily apt download activities. May 31 09:06:08 mpd systemd[1]: Started Daily apt upgrade and clean activities. May 31 09:06:08 mpd systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. May 31 09:06:08 mpd systemd[1]: Started Daily exim4-base housekeeping. May 31 09:06:08 mpd systemd[1]: Started Daily man-db regeneration. May 31 09:06:08 mpd systemd[1]: Started Daily Cleanup of Temporary Directories. May 31 09:06:08 mpd systemd[1]: Reached target Timers. May 31 09:06:08 mpd systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 31 09:06:08 mpd systemd[1]: Listening on D-Bus System Message Bus Socket. May 31 09:06:08 mpd systemd[1]: Listening on triggerhappy.socket. May 31 09:06:08 mpd systemd[1]: Reached target Sockets. May 31 09:06:08 mpd systemd[1]: Reached target Basic System. May 31 09:06:08 mpd systemd[1]: Condition check resulted in Manage Sound Card State (restore and store) being skipped. May 31 09:06:08 mpd systemd[1]: Starting Save/Restore Sound Card State... May 31 09:06:08 mpd systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 31 09:06:08 mpd systemd[1]: Started D-Bus System Message Bus. May 31 09:06:08 mpd kernel: phy_rtl8211e_led_fixup in May 31 09:06:08 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: PHY [stmmac-0:01] driver [RTL8211E Gigabit Ethernet] (irq=POLL) May 31 09:06:08 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0 May 31 09:06:08 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: No Safety Features support found May 31 09:06:08 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: RX IPC Checksum Offload disabled May 31 09:06:08 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: No MAC Management Counters available May 31 09:06:08 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: PTP not supported by HW May 31 09:06:08 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: configuring for phy/rgmii-id link mode May 31 09:06:08 mpd systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... May 31 09:06:08 mpd systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 31 09:06:08 mpd systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 31 09:06:08 mpd systemd[1]: Starting DHCP Client Daemon... May 31 09:06:08 mpd avahi-daemon[774]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). May 31 09:06:08 mpd avahi-daemon[774]: Successfully dropped root privileges. May 31 09:06:08 mpd avahi-daemon[774]: avahi-daemon 0.8 starting up. May 31 09:06:08 mpd systemd[1]: Started Volumio Iptables Module. May 31 09:06:08 mpd systemd[1]: Starting Initialize hardware monitoring sensors... May 31 09:06:08 mpd systemd[1]: Starting Self Monitoring and Reporting Technology (SMART) Daemon... May 31 09:06:08 mpd dbus-daemon[775]: dbus[775]: Unknown group "bluetooth" in message bus configuration file May 31 09:06:08 mpd systemd[1]: Starting User Login Management... May 31 09:06:08 mpd systemd[1]: Starting triggerhappy global hotkey daemon... May 31 09:06:08 mpd systemd[1]: Starting Disk Manager... May 31 09:06:08 mpd systemd[1]: Started UPnP Renderer front-end to MPD. May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: May 31 09:06:08 mpd thd[815]: Unable to parse trigger line: May 31 09:06:08 mpd thd[815]: Found socket passed from systemd May 31 09:06:08 mpd systemd[1]: Started volumio-remote-updater.service. May 31 09:06:09 mpd systemd[1]: Starting Volumio Time Update Utility... May 31 09:06:09 mpd avahi-daemon[774]: Successfully called chroot(). May 31 09:06:09 mpd avahi-daemon[774]: Successfully dropped remaining capabilities. May 31 09:06:09 mpd avahi-daemon[774]: Loading service file /services/volumio.service. May 31 09:06:09 mpd avahi-daemon[774]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 31 09:06:09 mpd avahi-daemon[774]: New relevant interface lo.IPv4 for mDNS. May 31 09:06:09 mpd avahi-daemon[774]: Network interface enumeration completed. May 31 09:06:09 mpd avahi-daemon[774]: Registering new address record for 127.0.0.1 on lo.IPv4. May 31 09:06:09 mpd systemd[1]: Started Volumio Log Rotation Service. May 31 09:06:09 mpd systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 31 09:06:09 mpd systemd[1]: Starting Wireless Services... May 31 09:06:09 mpd volumio-time-update[833]: volumio-time-update-util: Fetching time from Volumio... May 31 09:06:09 mpd systemd[1]: Starting WPA supplicant... May 31 09:06:09 mpd dhcpcd[789]: no interfaces have a carrier May 31 09:06:09 mpd dhcpcd[789]: no interfaces have a carrier May 31 09:06:09 mpd udisksd[821]: udisks daemon version 2.9.2 starting May 31 09:06:09 mpd systemd[1]: Started RPC bind portmap service. May 31 09:06:09 mpd dhcpcd[789]: forked to background, child pid 855 May 31 09:06:09 mpd dhcpcd[789]: forked to background, child pid 855 May 31 09:06:09 mpd dhcpcd[855]: eth0: waiting for carrier May 31 09:06:09 mpd dhcpcd[855]: wlan0: waiting for carrier May 31 09:06:09 mpd systemd[1]: Started triggerhappy global hotkey daemon. May 31 09:06:09 mpd systemd[1]: Finished Save/Restore Sound Card State. May 31 09:06:09 mpd systemd[1]: Started DHCP Client Daemon. May 31 09:06:09 mpd smartd[804]: smartd 7.2 2020-12-30 r5155 [armv7l-linux-5.15.93-sunxi] (local build) May 31 09:06:09 mpd smartd[804]: Copyright (C) 2002-20, Bruce Allen, Christian Franke, www.smartmontools.org May 31 09:06:09 mpd smartd[804]: Opened configuration file /etc/smartd.conf May 31 09:06:09 mpd smartd[804]: Drive: DEVICESCAN, implied '-a' Directive on line 21 of file /etc/smartd.conf May 31 09:06:09 mpd smartd[804]: Configuration file /etc/smartd.conf was parsed, found DEVICESCAN, scanning devices May 31 09:06:09 mpd sensors[857]: axp20x_battery-isa-0000 May 31 09:06:09 mpd sensors[857]: Adapter: ISA adapter May 31 09:06:09 mpd sensors[857]: in0: 0.00 V May 31 09:06:09 mpd sensors[857]: curr1: 0.00 A May 31 09:06:09 mpd volumio-time-update[833]: volumio-time-update-util: Date not found in response May 31 09:06:09 mpd volumio-time-update[833]: volumio-time-update-util: Retrying in 5 seconds... May 31 09:06:09 mpd avahi-daemon[774]: Server startup complete. Host name is mpd.local. Local service cookie is 3002337922. May 31 09:06:10 mpd systemd[1]: Finished Initialize hardware monitoring sensors. May 31 09:06:10 mpd smartd[804]: Device: /dev/sda, type changed from 'scsi' to 'sat' May 31 09:06:10 mpd smartd[804]: Device: /dev/sda [SAT], opened May 31 09:06:10 mpd smartd[804]: Device: /dev/sda [SAT], HGST HUH721212ALE600, S/N:AAG1Z5VH, WWN:5-000cca-278c0e5b2, FW:LEGNT3D0, 12.0 TB May 31 09:06:10 mpd haveged[770]: haveged: command socket is listening at fd 3 May 31 09:06:10 mpd sh[872]: wlan0=wlan0 May 31 09:06:10 mpd udisksd[821]: failed to load module mdraid: libbd_mdraid.so.2: cannot open shared object file: No such file or directory May 31 09:06:10 mpd volumio-remote-updater[826]: Error: No active session May 31 09:06:10 mpd volumio-remote-updater[826]: [2026-05-31 09:06:10] [info] asio async_connect error: system:111 (Connection refused) May 31 09:06:10 mpd volumio-remote-updater[826]: [2026-05-31 09:06:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 31 09:06:10 mpd volumio-remote-updater[826]: [2026-05-31 09:06:10] [error] handle_connect error: Underlying Transport Error May 31 09:06:10 mpd wpa_supplicant[849]: Successfully initialized wpa_supplicant May 31 09:06:10 mpd sh[880]: eth0=eth0 May 31 09:06:10 mpd systemd[1]: systemd-rfkill.service: Succeeded. May 31 09:06:10 mpd udisksd[821]: Failed to load the 'mdraid' libblockdev plugin May 31 09:06:10 mpd dbus-daemon[775]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.1' (uid=0 pid=821 comm="/usr/libexec/udisks2/udisksd ") May 31 09:06:10 mpd systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 31 09:06:10 mpd systemd[1]: Started WPA supplicant. May 31 09:06:10 mpd systemd[1]: Reached target Remote File Systems (Pre). May 31 09:06:10 mpd systemd[1]: Reached target Remote File Systems. May 31 09:06:10 mpd systemd[1]: Reached target RPC Port Mapper. May 31 09:06:10 mpd systemd[1]: Reached target Sound Card. May 31 09:06:10 mpd systemd[1]: Started Regular background program processing daemon. May 31 09:06:10 mpd systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 31 09:06:10 mpd cron[883]: (CRON) INFO (pidfile fd = 3) May 31 09:06:10 mpd systemd[1]: Starting Authorization Manager... May 31 09:06:10 mpd avahi-daemon[774]: Service "MPD" (/services/volumio.service) successfully established. May 31 09:06:10 mpd cron[883]: (CRON) INFO (Running @reboot jobs) May 31 09:06:10 mpd smartd[804]: Device: /dev/sda [SAT], found in smartd database: HGST Ultrastar DC HC520 (He12) May 31 09:06:11 mpd smartd[804]: Device: /dev/sda [SAT], is SMART capable. Adding to "monitor" list. May 31 09:06:11 mpd smartd[804]: Device: /dev/sda [SAT], state read from /var/lib/smartmontools/smartd.HGST_HUH721212ALE600-AAG1Z5VH.ata.state May 31 09:06:11 mpd smartd[804]: Monitoring 1 ATA/SATA, 0 SCSI/SAS and 0 NVMe devices May 31 09:06:11 mpd volumio[824]: Could not open config: /tmp/upmpdcli.conf May 31 09:06:11 mpd systemd-logind[812]: New seat seat0. May 31 09:06:11 mpd systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 31 09:06:11 mpd systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 31 09:06:11 mpd systemd-logind[812]: Watching system buttons on /dev/input/event0 (axp20x-pek) May 31 09:06:11 mpd systemd[1]: Started User Login Management. May 31 09:06:11 mpd polkitd[885]: started daemon version 0.105 using authority implementation `local' version `0.105' May 31 09:06:11 mpd dbus-daemon[775]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 31 09:06:11 mpd systemd[1]: Started Authorization Manager. May 31 09:06:11 mpd smartd[804]: Device: /dev/sda [SAT], 257 Offline uncorrectable sectors May 31 09:06:11 mpd smartd[804]: Device: /dev/sda [SAT], state written to /var/lib/smartmontools/smartd.HGST_HUH721212ALE600-AAG1Z5VH.ata.state May 31 09:06:11 mpd systemd[1]: Started Self Monitoring and Reporting Technology (SMART) Daemon. May 31 09:06:11 mpd systemd[1]: iptables.service: Succeeded. May 31 09:06:12 mpd loadcpufreq[884]: Loading cpufreq kernel modules...done (none). May 31 09:06:12 mpd systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 31 09:06:12 mpd systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 31 09:06:12 mpd kernel: sun7i-dwmac 1c50000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 31 09:06:12 mpd dhcpcd[855]: eth0: carrier acquired May 31 09:06:12 mpd dhcpcd[855]: DUID 00:01:00:01:30:9d:cf:46:02:52:0a:81:dd:75 May 31 09:06:12 mpd dhcpcd[855]: eth0: IAID 0a:81:dd:75 May 31 09:06:12 mpd dhcpcd[855]: eth0: adding address fe80::52:aff:fe81:dd75 May 31 09:06:12 mpd dhcpcd[855]: ipv6_addaddr1: Permission denied May 31 09:06:12 mpd dhcpcd[855]: eth0: probing address 192.168.65.20/24 May 31 09:06:12 mpd cpufrequtils[931]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...done. May 31 09:06:12 mpd systemd[1]: Started LSB: set CPUFreq kernel parameters. May 31 09:06:12 mpd dhcpcd[855]: eth0: soliciting an IPv6 router May 31 09:06:13 mpd haveged[770]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K May 31 09:06:13 mpd haveged[770]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15006/57790 May 31 09:06:13 mpd haveged[770]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9966 May 31 09:06:13 mpd haveged[770]: haveged: fills: 0, generated: 0 May 31 09:06:14 mpd systemd[1]: e2scrub_reap.service: Succeeded. May 31 09:06:14 mpd systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. May 31 09:06:14 mpd systemd[1]: Started Disk Manager. May 31 09:06:14 mpd udisksd[821]: Acquired the name org.freedesktop.UDisks2 on the system message bus May 31 09:06:14 mpd volumio-time-update[833]: volumio-time-update-util: Fetching time from Volumio... May 31 09:06:15 mpd volumio-time-update[833]: volumio-time-update-util: Date not found in response May 31 09:06:15 mpd volumio-time-update[833]: volumio-time-update-util: Retrying in 5 seconds... May 31 09:06:15 mpd volumio-remote-updater[826]: [2026-05-31 09:06:15] [info] asio async_connect error: system:111 (Connection refused) May 31 09:06:15 mpd volumio-remote-updater[826]: [2026-05-31 09:06:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 31 09:06:15 mpd volumio-remote-updater[826]: [2026-05-31 09:06:15] [error] handle_connect error: Underlying Transport Error May 31 09:06:16 mpd kernel: vcc3v0: disabling May 31 09:06:16 mpd kernel: vcc5v0: disabling May 31 09:06:16 mpd wireless.js[840]: Cleaning previous... May 31 09:06:17 mpd sudo[977]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 31 09:06:17 mpd sudo[977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:06:17 mpd sudo[977]: pam_unix(sudo:session): session closed for user root May 31 09:06:17 mpd sudo[979]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 31 09:06:17 mpd sudo[979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:06:17 mpd dhcpcd[855]: eth0: using static address 192.168.65.20/24 May 31 09:06:17 mpd dhcpcd[855]: eth0: adding route to 192.168.65.0/24 May 31 09:06:17 mpd dhcpcd[855]: eth0: adding default route via 192.168.65.1 May 31 09:06:17 mpd sudo[979]: pam_unix(sudo:session): session closed for user root May 31 09:06:17 mpd avahi-daemon[774]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.65.20. May 31 09:06:17 mpd avahi-daemon[774]: New relevant interface eth0.IPv4 for mDNS. May 31 09:06:17 mpd avahi-daemon[774]: Registering new address record for 192.168.65.20 on eth0.IPv4. May 31 09:06:18 mpd wireless.js[840]: Stopped aP May 31 09:06:18 mpd sudo[1004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 31 09:06:18 mpd sudo[1004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:18 mpd sudo[1004]: pam_unix(sudo:session): session closed for user root May 31 09:06:18 mpd sudo[1006]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 31 09:06:18 mpd sudo[1006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:18 mpd sudo[1006]: pam_unix(sudo:session): session closed for user root May 31 09:06:18 mpd sudo[1013]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 31 09:06:18 mpd sudo[1013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:18 mpd sudo[1013]: pam_unix(sudo:session): session closed for user root May 31 09:06:18 mpd sudo[1015]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 31 09:06:18 mpd sudo[1015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:19 mpd sudo[1015]: pam_unix(sudo:session): session closed for user root May 31 09:06:19 mpd wireless.js[840]: SETTING APPROPRIATE REG DOMAIN: AT May 31 09:06:19 mpd sudo[1020]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 31 09:06:19 mpd sudo[1020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:19 mpd sudo[1020]: pam_unix(sudo:session): session closed for user root May 31 09:06:19 mpd sudo[1022]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set AT May 31 09:06:19 mpd sudo[1022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:19 mpd sudo[1022]: pam_unix(sudo:session): session closed for user root May 31 09:06:19 mpd wireless.js[840]: SUCCESSFULLY SET NEW REGDOMAIN: AT May 31 09:06:19 mpd wireless.js[840]: Start wireless flow May 31 09:06:19 mpd wireless.js[840]: Stopped hotspot (if there).. May 31 09:06:19 mpd sudo[1027]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 31 09:06:19 mpd sudo[1027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:06:19 mpd sudo[1027]: pam_unix(sudo:session): session closed for user root May 31 09:06:19 mpd sudo[1029]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 31 09:06:19 mpd sudo[1029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:06:20 mpd volumio-time-update[833]: volumio-time-update-util: Fetching time from Volumio... May 31 09:06:20 mpd volumio-time-update[833]: volumio-time-update-util: Setting system time to: 2026-05-31 09:06:45 May 31 09:06:20 mpd sudo[1029]: pam_unix(sudo:session): session closed for user root May 31 09:06:20 mpd sudo[1036]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-05-31 09:06:45 May 31 09:06:20 mpd sudo[1036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:06:20 mpd wireless.js[840]: DHCP IP May 31 09:06:20 mpd wireless.js[840]: Start ap May 31 09:06:20 mpd wpa_supplicant[1037]: Successfully initialized wpa_supplicant May 31 09:06:20 mpd sudo[1039]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 31 09:06:20 mpd dbus-daemon[775]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1038 comm="timedatectl set-time 2026-05-31 09:06:45 ") May 31 09:06:20 mpd sudo[1039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:06:20 mpd dhcpcd[1040]: sending commands to master dhcpcd process May 31 09:06:20 mpd dhcpcd[855]: control command: /sbin/dhcpcd May 31 09:06:20 mpd sudo[1039]: pam_unix(sudo:session): session closed for user root May 31 09:06:20 mpd systemd[1]: Starting Time & Date Service... May 31 09:06:20 mpd dbus-daemon[775]: [system] Successfully activated service 'org.freedesktop.timedate1' May 31 09:06:20 mpd systemd[1]: Started Time & Date Service. May 31 09:06:45 mpd systemd-timedated[1042]: Changed local time to Sun May 31 09:06:45 2026 May 31 09:06:45 mpd sudo[1036]: pam_unix(sudo:session): session closed for user root May 31 09:06:45 mpd volumio-time-update[833]: volumio-time-update-util: System time updated successfully. May 31 09:06:45 mpd systemd[1]: Finished Volumio Time Update Utility. May 31 09:06:45 mpd wireless.js[840]: trying... May 31 09:06:45 mpd wpa_supplicant[1044]: wlan0: Trying to associate with SSID 'WRT240' May 31 09:06:45 mpd sudo[1046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:45 mpd sudo[1046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:45 mpd sudo[1046]: pam_unix(sudo:session): session closed for user root May 31 09:06:46 mpd wireless.js[840]: trying... May 31 09:06:46 mpd sudo[1049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:46 mpd sudo[1049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:46 mpd sudo[1049]: pam_unix(sudo:session): session closed for user root May 31 09:06:46 mpd wpa_supplicant[1044]: wlan0: Associated with e4:18:6b:04:74:38 May 31 09:06:46 mpd wpa_supplicant[1044]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 31 09:06:46 mpd wpa_supplicant[1044]: wlan0: WPA: Key negotiation completed with e4:18:6b:04:74:38 [PTK=CCMP GTK=CCMP] May 31 09:06:46 mpd wpa_supplicant[1044]: wlan0: CTRL-EVENT-CONNECTED - Connection to e4:18:6b:04:74:38 completed [id=0 id_str=] May 31 09:06:46 mpd dhcpcd[855]: wlan0: carrier acquired May 31 09:06:46 mpd wpa_supplicant[1044]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AT May 31 09:06:46 mpd dhcpcd[855]: wlan0: IAID 16:e9:a4:c8 May 31 09:06:46 mpd dhcpcd[855]: wlan0: adding address fe80::9a3b:16ff:fee9:a4c8 May 31 09:06:46 mpd dhcpcd[855]: ipv6_addaddr1: Permission denied May 31 09:06:46 mpd dhcpcd[855]: wlan0: probing address 192.168.200.10/24 May 31 09:06:46 mpd volumio-remote-updater[826]: [2026-05-31 09:06:46] [info] asio async_connect error: system:111 (Connection refused) May 31 09:06:46 mpd volumio-remote-updater[826]: [2026-05-31 09:06:46] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 31 09:06:46 mpd volumio-remote-updater[826]: [2026-05-31 09:06:46] [error] handle_connect error: Underlying Transport Error May 31 09:06:47 mpd dhcpcd[855]: wlan0: soliciting an IPv6 router May 31 09:06:47 mpd wireless.js[840]: trying... May 31 09:06:47 mpd sudo[1054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:47 mpd sudo[1054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:47 mpd sudo[1054]: pam_unix(sudo:session): session closed for user root May 31 09:06:48 mpd wireless.js[840]: trying... May 31 09:06:48 mpd sudo[1057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:48 mpd sudo[1057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:48 mpd sudo[1057]: pam_unix(sudo:session): session closed for user root May 31 09:06:49 mpd wireless.js[840]: trying... May 31 09:06:49 mpd sudo[1060]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:49 mpd sudo[1060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:49 mpd sudo[1060]: pam_unix(sudo:session): session closed for user root May 31 09:06:50 mpd wireless.js[840]: trying... May 31 09:06:50 mpd sudo[1063]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:50 mpd sudo[1063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:50 mpd sudo[1063]: pam_unix(sudo:session): session closed for user root May 31 09:06:51 mpd wireless.js[840]: trying... May 31 09:06:51 mpd sudo[1066]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:51 mpd sudo[1066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:51 mpd sudo[1066]: pam_unix(sudo:session): session closed for user root May 31 09:06:51 mpd dhcpcd[855]: wlan0: using static address 192.168.200.10/24 May 31 09:06:51 mpd avahi-daemon[774]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.200.10. May 31 09:06:51 mpd dhcpcd[855]: wlan0: adding route to 192.168.200.0/24 May 31 09:06:51 mpd dhcpcd[855]: wlan0: adding default route via 192.168.200.1 May 31 09:06:51 mpd avahi-daemon[774]: New relevant interface wlan0.IPv4 for mDNS. May 31 09:06:51 mpd avahi-daemon[774]: Registering new address record for 192.168.200.10 on wlan0.IPv4. May 31 09:06:52 mpd wireless.js[840]: trying... May 31 09:06:52 mpd sudo[1087]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 09:06:52 mpd sudo[1087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:06:52 mpd sudo[1087]: pam_unix(sudo:session): session closed for user root May 31 09:06:52 mpd wireless.js[840]: Connected to: ----WRT240 May 31 09:06:52 mpd wireless.js[840]: ---- May 31 09:06:52 mpd sudo[1090]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:06:52 mpd sudo[1090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:06:52 mpd sudo[1090]: pam_unix(sudo:session): session closed for user root May 31 09:06:52 mpd wireless.js[840]: ... joined AP, wlan0 IPv4 is 192.168.200.10, ipV6 is undefined May 31 09:06:52 mpd wireless.js[840]: It's done! AP May 31 09:06:52 mpd systemd[1]: Started Wireless Services. May 31 09:06:52 mpd systemd[1]: Started Volumio Backend Module. May 31 09:06:52 mpd systemd[1]: Started Volumio Cpu Tweaker. May 31 09:06:52 mpd volumio-cpu-tweak[1096]: Setting RT Priority for mpd May 31 09:06:53 mpd volumio-cpu-tweak[1107]: chrt: cannot obtain the list of tasks: No such file or directory May 31 09:06:53 mpd volumio-cpu-tweak[1096]: Not enough cores to set MPD affinity May 31 09:06:53 mpd volumio-cpu-tweak[1096]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 31 09:06:53 mpd volumio-cpu-tweak[1096]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 31 09:06:53 mpd systemd[1]: volumio_cpu_tweak.service: Succeeded. May 31 09:06:56 mpd volumio-remote-updater[826]: [2026-05-31 09:06:56] [info] asio async_connect error: system:111 (Connection refused) May 31 09:06:56 mpd volumio-remote-updater[826]: [2026-05-31 09:06:56] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 31 09:06:56 mpd volumio-remote-updater[826]: [2026-05-31 09:06:56] [error] handle_connect error: Underlying Transport Error May 31 09:06:59 mpd volumio[1095]: info: ------------------------------------------- May 31 09:06:59 mpd volumio[1095]: info: ----- Volumio3 ---- May 31 09:06:59 mpd volumio[1095]: info: ------------------------------------------- May 31 09:06:59 mpd volumio[1095]: info: ----- System startup ---- May 31 09:06:59 mpd volumio[1095]: info: ------------------------------------------- May 31 09:07:00 mpd systemd[1]: systemd-fsckd.service: Succeeded. May 31 09:07:02 mpd volumio[1095]: info: MYVOLUMIO Environment detected May 31 09:07:02 mpd volumio[1095]: info: Plugin folders cleanup May 31 09:07:02 mpd volumio[1095]: info: Scanning into folder /volumio/app/plugins/ May 31 09:07:02 mpd volumio[1095]: info: Scanning category audio_interface May 31 09:07:02 mpd volumio[1095]: info: Scanning category miscellanea May 31 09:07:02 mpd volumio[1095]: info: Scanning category music_service May 31 09:07:02 mpd volumio[1095]: info: Scanning category plugins.json May 31 09:07:02 mpd volumio[1095]: info: Scanning category system_controller May 31 09:07:02 mpd volumio[1095]: info: Scanning category user_interface May 31 09:07:02 mpd volumio[1095]: info: Scanning into folder /data/plugins/ May 31 09:07:02 mpd volumio[1095]: info: Scanning category system_hardware May 31 09:07:02 mpd volumio[1095]: info: Scanning category user_interface May 31 09:07:02 mpd volumio[1095]: info: Plugin folders cleanup completed May 31 09:07:02 mpd volumio[1095]: info: ------------------------------------------- May 31 09:07:02 mpd volumio[1095]: info: ----- Core plugins startup ---- May 31 09:07:02 mpd volumio[1095]: info: ------------------------------------------- May 31 09:07:02 mpd volumio[1095]: info: Loading plugins from folder /volumio/app/plugins/ May 31 09:07:02 mpd volumio[1095]: info: Adding plugin upnp to MyMusic Plugins May 31 09:07:02 mpd volumio[1095]: info: Adding plugin airplay_emulation to MyMusic Plugins May 31 09:07:02 mpd volumio[1095]: info: Adding plugin upnp_browser to MyMusic Plugins May 31 09:07:02 mpd volumio[1095]: info: Loading plugins from folder /data/plugins/ May 31 09:07:02 mpd volumio[1095]: info: Loading plugin "system"... May 31 09:07:03 mpd volumio[1095]: info: Loading plugin "appearance"... May 31 09:07:06 mpd volumio-remote-updater[826]: [2026-05-31 09:07:06] [connect] Successful connection May 31 09:07:06 mpd volumio[1095]: info: Loading plugin "network"... May 31 09:07:07 mpd volumio[1095]: info: Refreshing Cached IP Addresses May 31 09:07:07 mpd sudo[1125]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:07:07 mpd sudo[1125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:07 mpd sudo[1125]: pam_unix(sudo:session): session closed for user root May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "services"... May 31 09:07:07 mpd sudo[1127]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:07:07 mpd sudo[1127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "alsa_controller"... May 31 09:07:07 mpd sudo[1127]: pam_unix(sudo:session): session closed for user root May 31 09:07:07 mpd sudo[1136]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 09:07:07 mpd sudo[1136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:07 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "wizard"... May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "networkfs"... May 31 09:07:07 mpd volumio[1095]: info: Starting Udev Watcher for removable devices May 31 09:07:07 mpd volumio[1095]: info: Ignoring mount for partition: boot May 31 09:07:07 mpd volumio[1095]: info: Ignoring mount for partition: volumio May 31 09:07:07 mpd volumio[1095]: info: Ignoring mount for partition: volumio_data May 31 09:07:07 mpd volumio[1095]: lsblk: /dev/sda May 31 09:07:07 mpd volumio[1095]: sda1 May 31 09:07:07 mpd volumio[1095]: sda1: not a block device May 31 09:07:07 mpd volumio[1095]: error: Failed USB identification: Error: Command failed: /bin/lsblk -rno tran "/dev/$(lsblk -rno PKNAME /dev/sda1)" May 31 09:07:07 mpd volumio[1095]: lsblk: /dev/sda May 31 09:07:07 mpd volumio[1095]: sda1 May 31 09:07:07 mpd volumio[1095]: sda1: not a block device May 31 09:07:07 mpd volumio[1095]: info: Ignoring mount for partition: Eb5F0b-TE4d-T6u4-Zsqt-yqe7-iARk-7a5wmX May 31 09:07:07 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "volumio_command_line_client"... May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "upnp"... May 31 09:07:07 mpd volumio[1095]: info: [1780211227949] Starting Upmpd Daemon May 31 09:07:07 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "my_music"... May 31 09:07:07 mpd volumio[1095]: info: Loading plugin "mpd"... May 31 09:07:08 mpd sudo[1136]: pam_unix(sudo:session): session closed for user root May 31 09:07:09 mpd volumio[1095]: info: Loading plugin "upnp_browser"... May 31 09:07:10 mpd volumio[1095]: info: Loading plugin "alarm-clock"... May 31 09:07:10 mpd volumio[1095]: info: Loading plugin "airplay_emulation"... May 31 09:07:10 mpd volumio[1095]: info: Starting Shairport Sync May 31 09:07:10 mpd volumio[1095]: info: Loading plugin "last_100"... May 31 09:07:10 mpd volumio[1095]: info: Loading plugin "webradio"... May 31 09:07:11 mpd volumio[1095]: info: Loading plugin "i2s_dacs"... May 31 09:07:11 mpd volumio[1095]: info: I2S DAC not set, start Auto-detection May 31 09:07:11 mpd volumio[1095]: info: Loading plugin "volumiodiscovery"... May 31 09:07:11 mpd volumio[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 09:07:11 mpd node[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 09:07:11 mpd volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 09:07:11 mpd node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 09:07:11 mpd volumio[1095]: *** WARNING *** For more information see May 31 09:07:11 mpd node[1095]: *** WARNING *** For more information see May 31 09:07:11 mpd volumio[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 09:07:11 mpd node[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 09:07:11 mpd volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 09:07:11 mpd node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 09:07:11 mpd volumio[1095]: *** WARNING *** For more information see May 31 09:07:11 mpd node[1095]: *** WARNING *** For more information see May 31 09:07:11 mpd volumio[1095]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 09:07:11 mpd volumio[1095]: info: Discovery: Started advertising with name: MPD May 31 09:07:11 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 09:07:11 mpd volumio[1095]: info: Loading plugin "now_playing"... May 31 09:07:15 mpd systemd[1]: systemd-timedated.service: Succeeded. May 31 09:07:18 mpd volumio[1095]: info: Loading plugin "outputs"... May 31 09:07:18 mpd volumio[1095]: info: Loading plugin "albumart"... May 31 09:07:18 mpd volumio[1095]: info: Plugin example_plugin is not enabled May 31 09:07:18 mpd volumio[1095]: info: Loading plugin "inputs"... May 31 09:07:18 mpd volumio[1095]: info: Loading plugin "updater_comm"... May 31 09:07:19 mpd volumio[1095]: info: Plugin mpdemulation is not enabled May 31 09:07:19 mpd volumio[1095]: info: Loading plugin "rest_api"... May 31 09:07:19 mpd volumio[1095]: info: Loading plugin "websocket"... May 31 09:07:19 mpd volumio[1095]: info: Starting Socket.io Server version 2.3.0 May 31 09:07:19 mpd volumio[1095]: info: Plugin mpd_oled is not enabled May 31 09:07:19 mpd volumio[1095]: info: Loading plugin "Systeminfo"... May 31 09:07:20 mpd volumio[1171]: Forking 1 albumart workers May 31 09:07:21 mpd volumio[1095]: info: Loading i18n strings for locale ru May 31 09:07:21 mpd volumio[1095]: Updating browse sources language May 31 09:07:21 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:07:21 mpd volumio-remote-updater[826]: [2026-05-31 09:07:21] [connect] Successful connection May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::initPlayerControls May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:22 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 09:07:22 mpd volumio[1095]: Express server listening on port 3000 May 31 09:07:22 mpd volumio[1095]: [Metrics] WebUI: 25s 807.33ms May 31 09:07:23 mpd volumio[1095]: info: CoreStateMachine::resetVolumioState May 31 09:07:23 mpd volumio[1095]: info: CoreStateMachine::getcurrentVolume May 31 09:07:23 mpd volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume May 31 09:07:23 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:07:23 mpd volumio[1095]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 31 09:07:23 mpd sudo[1195]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:07:23 mpd volumio[1095]: info: Completed loading Core Plugins May 31 09:07:23 mpd volumio[1095]: info: Preparing to generate the ALSA configuration file May 31 09:07:23 mpd sudo[1195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:23 mpd sudo[1195]: pam_unix(sudo:session): session closed for user root May 31 09:07:23 mpd sudo[1197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:07:23 mpd sudo[1197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:23 mpd sudo[1197]: pam_unix(sudo:session): session closed for user root May 31 09:07:23 mpd volumio[1095]: info: Volumio Network Manager: Network status updated: 3 May 31 09:07:23 mpd volumio[1095]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 09:07:23 mpd volumio[1095]: info: Reading ALSA contributions from plugins. May 31 09:07:24 mpd volumio[1178]: Starting albumart workers May 31 09:07:24 mpd volumio-remote-updater[826]: [2026-05-31 09:07:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1780211241 101 May 31 09:07:24 mpd volumio[1095]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 31 09:07:24 mpd volumio[1095]: info: CoreStateMachine::pushState May 31 09:07:24 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:24 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 09:07:24 mpd volumio[1095]: info: CoreCommandRouter::volumioPushState May 31 09:07:24 mpd volumio[1095]: info: CoreStateMachine::updateTrackBlock May 31 09:07:24 mpd volumio[1095]: info: CorePlayQueue::getTrackBlock May 31 09:07:24 mpd volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume May 31 09:07:24 mpd volumio[1095]: info: Reloading queue from file May 31 09:07:24 mpd volumio[1095]: info: CoreStateMachine::setRepeat null single undefined May 31 09:07:24 mpd volumio[1095]: info: CoreStateMachine::pushState May 31 09:07:24 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:24 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 09:07:24 mpd volumio[1095]: info: CoreCommandRouter::volumioPushState May 31 09:07:24 mpd volumio[1095]: info: CoreStateMachine::setRandom true May 31 09:07:24 mpd volumio[1095]: info: CoreStateMachine::pushState May 31 09:07:24 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:24 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 09:07:24 mpd volumio[1095]: info: CoreCommandRouter::volumioPushState May 31 09:07:25 mpd volumio[1095]: info: CoreStateMachine::pushState May 31 09:07:25 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:25 mpd volumio[1095]: info: CoreCommandRouter::volumioPushState May 31 09:07:25 mpd volumio[1095]: info: Asound.conf file unchanged, so no further update is needed May 31 09:07:25 mpd volumio[1095]: info: Output device has changed, restarting MPD May 31 09:07:25 mpd sudo[1214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 09:07:25 mpd sudo[1214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:25 mpd volumio[1095]: info: Output device has changed, restarting Shairport Sync May 31 09:07:25 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:25 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:07:25 mpd systemd[1]: Started UPnP Renderer front-end to MPD. May 31 09:07:25 mpd sudo[1219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 09:07:25 mpd sudo[1214]: pam_unix(sudo:session): session closed for user root May 31 09:07:25 mpd sudo[1219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:25 mpd sudo[1217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 09:07:25 mpd sudo[1217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:25 mpd volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 09:07:25 mpd sudo[1217]: pam_unix(sudo:session): session closed for user root May 31 09:07:25 mpd volumio[1095]: info: ___________ START PLUGINS ___________ May 31 09:07:25 mpd volumio[1095]: info: ControllerMpd::onStart: Initializing MPD May 31 09:07:25 mpd volumio[1095]: info: Creating MPD Configuration file May 31 09:07:25 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 09:07:25 mpd volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 09:07:25 mpd systemd[1]: Listening on mpd.socket. May 31 09:07:25 mpd volumio[1095]: info: [1780211245817] CoreMusicLibrary::Adding element Медиа-серверы May 31 09:07:25 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:07:25 mpd sudo[1227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 09:07:25 mpd sudo[1227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:26 mpd sudo[1227]: pam_unix(sudo:session): session closed for user root May 31 09:07:26 mpd sudo[1230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 09:07:26 mpd sudo[1230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:07:26 mpd volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:07:26 mpd volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 09:07:26 mpd volumio[1095]: info: [1780211246571] CoreMusicLibrary::Adding element Last_100 May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 09:07:26 mpd volumio[1095]: info: [1780211246585] CoreMusicLibrary::Adding element Webradio May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:07:26 mpd volumio[1095]: info: Initializing BBC Radios May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:07:26 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:07:26 mpd volumio[1095]: info: [now-playing] ConfigUpdater: config is up to date. May 31 09:07:26 mpd volumio[1095]: info: Cannot retrieve data for calling home May 31 09:07:27 mpd volumio[1095]: info: [now-playing] App is listening on port 4004. May 31 09:07:27 mpd volumio[1095]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 31 09:07:27 mpd volumio[1095]: info: Discovery: adding 52074c46-8580-4179-a168-f1e3f3c2cb8d May 31 09:07:27 mpd volumio[1095]: info: Discovery: Found device MPD May 31 09:07:27 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:27 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:27 mpd volumio[1095]: info: Discovery: this is already registered, 52074c46-8580-4179-a168-f1e3f3c2cb8d May 31 09:07:27 mpd volumio[1095]: info: Discovery: Found device MPD May 31 09:07:27 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:27 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:27 mpd volumio[1095]: info: Discovery: this is already registered, 52074c46-8580-4179-a168-f1e3f3c2cb8d May 31 09:07:27 mpd volumio[1095]: info: Discovery: Found device MPD May 31 09:07:27 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:27 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:27 mpd volumio[1095]: info: MPD Permissions set May 31 09:07:27 mpd volumio[1095]: info: MPD Permissions set May 31 09:07:27 mpd volumio[1095]: info: Upmpdcli Daemon Started May 31 09:07:27 mpd volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd systemd-udevd[484]: pcmC5D0p: Spawned process '/bin/bash -c '/usr/local/bin/volumio usbattach'' [655] is taking longer than 59s to complete May 31 09:07:28 mpd systemd-udevd[466]: pcmC5D0p: Worker [484] processing SEQNUM=2625 is taking a long time May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:07:28 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:28 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:28 mpd volumio[1095]: info: Starting Shairport Sync May 31 09:07:28 mpd volumio[1095]: info: Starting Shairport Sync May 31 09:07:28 mpd volumio[1095]: info: Starting Shairport Sync May 31 09:07:28 mpd sudo[1285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 09:07:28 mpd sudo[1285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:29 mpd sudo[1289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 09:07:29 mpd sudo[1289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:29 mpd sudo[1287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 09:07:29 mpd sudo[1287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:39 mpd volumio[1095]: verbose: New Socket.io Connection to 192.168.65.20:3000 from 192.168.65.122 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:39 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:39 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:39 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:39 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:39 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:39 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 09:07:39 mpd volumio[1095]: verbose: New Socket.io Connection to 192.168.65.20:3000 from 192.168.65.122 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:39 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:39 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 31 09:07:39 mpd sudo[1325]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 31 09:07:39 mpd sudo[1325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:39 mpd sudo[1325]: pam_unix(sudo:session): session closed for user root May 31 09:07:39 mpd sudo[1330]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 31 09:07:39 mpd sudo[1330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:39 mpd sudo[1330]: pam_unix(sudo:session): session closed for user root May 31 09:07:39 mpd sudo[1337]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 31 09:07:39 mpd sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:39 mpd sudo[1342]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 31 09:07:39 mpd sudo[1337]: pam_unix(sudo:session): session closed for user root May 31 09:07:39 mpd sudo[1342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:39 mpd sudo[1348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:07:39 mpd sudo[1348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:39 mpd sudo[1348]: pam_unix(sudo:session): session closed for user root May 31 09:07:39 mpd volumio[1095]: verbose: New Socket.io Connection to 192.168.65.20:3000 from 192.168.65.122 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 31 09:07:39 mpd sudo[1342]: pam_unix(sudo:session): session closed for user root May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 09:07:39 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 09:07:40 mpd sudo[1352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:07:40 mpd sudo[1352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:40 mpd sudo[1352]: pam_unix(sudo:session): session closed for user root May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:40 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:40 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:40 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:40 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:40 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:40 mpd volumio[1095]: verbose: New Socket.io Connection to 192.168.65.20:3000 from 192.168.65.122 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 09:07:40 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 09:07:43 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 09:07:43 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 09:07:45 mpd sudo[1357]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:07:45 mpd sudo[1357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:45 mpd sudo[1357]: pam_unix(sudo:session): session closed for user root May 31 09:07:45 mpd sudo[1359]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:07:45 mpd sudo[1359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:45 mpd sudo[1359]: pam_unix(sudo:session): session closed for user root May 31 09:07:45 mpd volumio[1095]: verbose: New Socket.io Connection to 192.168.65.20 from 192.168.65.122 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::volumioGetVisibleSources May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:45 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 09:07:45 mpd volumio[1095]: info: Received Get System Info May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:45 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:45 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:45 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:45 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:45 mpd volumio[1095]: info: Listing playlists May 31 09:07:46 mpd sudo[1363]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:07:46 mpd sudo[1363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:46 mpd sudo[1363]: pam_unix(sudo:session): session closed for user root May 31 09:07:46 mpd sudo[1365]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:07:46 mpd sudo[1365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:07:46 mpd sudo[1365]: pam_unix(sudo:session): session closed for user root May 31 09:07:46 mpd volumio[1095]: verbose: New Socket.io Connection to 192.168.65.20 from 192.168.65.122 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::volumioGetVisibleSources May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:46 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 09:07:46 mpd volumio[1095]: info: Received Get System Info May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:46 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:46 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:46 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:46 mpd volumio[1095]: info: Listing playlists May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 09:07:46 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 09:07:47 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:47 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:47 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 09:07:47 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:07:47 mpd volumio[1095]: info: Received Get System Info May 31 09:07:47 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:47 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:47 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:47 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:47 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:47 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:48 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 31 09:07:49 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:07:49 mpd volumio[1095]: info: Received Get System Info May 31 09:07:49 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:49 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:49 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:49 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:49 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:49 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:49 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 31 09:07:50 mpd volumio[1095]: info: CoreCommandRouter::volumioGetQueue May 31 09:07:50 mpd volumio[1095]: info: CoreStateMachine::getQueue May 31 09:07:50 mpd volumio[1095]: info: CorePlayQueue::getQueue May 31 09:07:50 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:07:50 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:07:50 mpd volumio[1095]: info: Discovery: Getting this device information May 31 09:07:50 mpd volumio[1095]: info: CoreCommandRouter::volumioGetState May 31 09:07:50 mpd volumio[1095]: info: CorePlayQueue::getTrack 0 May 31 09:07:50 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:07:50 mpd volumio[1095]: verbose: New Socket.io Connection to 192.168.65.20:3000 from 192.168.65.122 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 31 09:07:50 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 09:07:50 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 09:07:55 mpd volumio[1095]: Searching plugin music_service/mpd May 31 09:07:55 mpd volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 31 09:07:55 mpd volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:07:55 mpd volumio[1095]: TypeError: Cannot read property 'then' of undefined May 31 09:07:55 mpd volumio[1095]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1601:17) May 31 09:07:55 mpd volumio[1095]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 31 09:07:55 mpd volumio[1095]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:477:37) May 31 09:07:55 mpd volumio[1095]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) May 31 09:07:55 mpd volumio[1095]: at Socket.emit (events.js:400:28) May 31 09:07:55 mpd volumio[1095]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 31 09:07:55 mpd volumio[1095]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 31 09:07:55 mpd volumio[1095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:07:57 mpd sudo[1391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 09:06 May 31 09:07:57 mpd sudo[1391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 11 (bullseye)" NAME="Debian GNU/Linux" VERSION_ID="11" VERSION="11 (bullseye)" VERSION_CODENAME=bullseye ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/"