Oct 25 21:39:00 volumioes9038pro systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounting boot.mount - /boot... Oct 25 21:39:00 volumioes9038pro systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounted boot.mount - /boot. Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target local-fs.target - Local File Systems. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started ifup@eth0.service - ifup for eth0. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting networking.service - Raise network interfaces... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Oct 25 21:39:00 volumioes9038pro 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). Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 25 21:39:00 volumioes9038pro systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 773 (systemd-binfmt) Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Oct 25 21:39:00 volumioes9038pro kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Oct 25 21:39:00 volumioes9038pro systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Oct 25 21:39:00 volumioes9038pro dhcpcd[807]: dhcpcd-9.4.1 starting Oct 25 21:39:00 volumioes9038pro sh[807]: dhcpcd-9.4.1 starting Oct 25 21:39:00 volumioes9038pro dhcpcd[820]: DUID 00:01:00:01:30:7a:b6:90:d8:3a:dd:a8:99:0f Oct 25 21:39:00 volumioes9038pro sh[807]: DUID 00:01:00:01:30:7a:b6:90:d8:3a:dd:a8:99:0f Oct 25 21:39:00 volumioes9038pro sh[807]: dhcp_vendor: No such process Oct 25 21:39:00 volumioes9038pro dhcpcd[820]: dhcp_vendor: No such process Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting rpcbind.service - RPC bind portmap service... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 25 21:39:00 volumioes9038pro systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target rpc_pipefs.target. Oct 25 21:39:00 volumioes9038pro 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). Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target nfs-client.target - NFS client services. Oct 25 21:39:00 volumioes9038pro kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 25 21:39:00 volumioes9038pro haveged[814]: haveged: command socket is listening at fd 3 Oct 25 21:39:00 volumioes9038pro kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 25 21:39:00 volumioes9038pro systemd[1]: Received SIGRTMIN+20 from PID 289 (plymouthd). Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target sysinit.target - System Initialization. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started apt-daily.timer - Daily apt download activities. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started man-db.timer - Daily man-db regeneration. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target timers.target - Timer Units. Oct 25 21:39:00 volumioes9038pro systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Oct 25 21:39:00 volumioes9038pro systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target sockets.target - Socket Units. Oct 25 21:39:00 volumioes9038pro 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). Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target basic.target - Basic System. Oct 25 21:39:00 volumioes9038pro 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). Oct 25 21:39:00 volumioes9038pro kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Oct 25 21:39:00 volumioes9038pro kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Oct 25 21:39:00 volumioes9038pro dhcpcd[820]: eth0: waiting for carrier Oct 25 21:39:00 volumioes9038pro sh[807]: eth0: waiting for carrier Oct 25 21:39:00 volumioes9038pro kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting bluetooth.service - Bluetooth service... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Oct 25 21:39:00 volumioes9038pro 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). Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting glamor-test.service - Check for glamor... Oct 25 21:39:00 volumioes9038pro systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Oct 25 21:39:00 volumioes9038pro systemd[1]: Started iptables.service - Volumio Iptables Module. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting rp1-test.service - Check for RP1 displays for Xorg... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting systemd-logind.service - User Login Management... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Oct 25 21:39:00 volumioes9038pro systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Oct 25 21:39:00 volumioes9038pro systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting wireless.service - Wireless Services... Oct 25 21:39:00 volumioes9038pro systemd[1]: Started rpcbind.service - RPC bind portmap service. Oct 25 21:39:00 volumioes9038pro thd[906]: Unable to parse trigger line: Oct 25 21:39:00 volumioes9038pro thd[906]: Unable to parse trigger line: Oct 25 21:39:00 volumioes9038pro volumio_rfkill_unblock.sh[913]: Volumio WiFi Soft Blocked check script Oct 25 21:39:00 volumioes9038pro volumio_rfkill_unblock.sh[913]: Check if rfkill is available Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Successfully dropped root privileges. Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: avahi-daemon 0.8 starting up. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Oct 25 21:39:00 volumioes9038pro 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). Oct 25 21:39:00 volumioes9038pro volumio_rfkill_unblock.sh[913]: Check if rfkill listed devices are already unblocked Oct 25 21:39:00 volumioes9038pro alsactl[933]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished networking.service - Raise network interfaces. Oct 25 21:39:00 volumioes9038pro alsactl[933]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 25 21:39:00 volumioes9038pro systemd[1]: e2scrub_reap.service: Deactivated successfully. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target sound.target - Sound Card. Oct 25 21:39:00 volumioes9038pro sh[951]: wlan0=wlan0 Oct 25 21:39:00 volumioes9038pro systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Oct 25 21:39:00 volumioes9038pro volumio_rfkill_unblock.sh[913]: Wi-Fi is already unblocked. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Oct 25 21:39:00 volumioes9038pro rpi-eeprom-update[902]: WARNING: flashrom not found. Setting RPI_EEPROM_USE_FLASHROM to 0 Oct 25 21:39:00 volumioes9038pro th-udev-rebind.sh[953]: Rebinding /dev/input/event0 to thd using --add... Oct 25 21:39:00 volumioes9038pro systemd-logind[903]: New seat seat0. Oct 25 21:39:00 volumioes9038pro th-udev-rebind.sh[953]: Rebinding /dev/input/event1 to thd using --add... Oct 25 21:39:00 volumioes9038pro systemd-logind[903]: Watching system buttons on /dev/input/event0 (pwr_button) Oct 25 21:39:00 volumioes9038pro systemd-logind[903]: Watching system buttons on /dev/input/event2 (vc4-hdmi-0) Oct 25 21:39:00 volumioes9038pro systemd-logind[903]: Watching system buttons on /dev/input/event4 (vc4-hdmi-1) Oct 25 21:39:00 volumioes9038pro th-udev-rebind.sh[953]: Rebinding /dev/input/event2 to thd using --add... Oct 25 21:39:00 volumioes9038pro th-udev-rebind.sh[953]: Rebinding /dev/input/event3 to thd using --add... Oct 25 21:39:00 volumioes9038pro th-udev-rebind.sh[953]: Rebinding /dev/input/event4 to thd using --add... Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Bluetooth daemon 5.83 Oct 25 21:39:00 volumioes9038pro th-udev-rebind.sh[953]: Rebinding /dev/input/event5 to thd using --add... Oct 25 21:39:00 volumioes9038pro dhcpcd[955]: dhcpcd-9.4.1 starting Oct 25 21:39:00 volumioes9038pro systemd[1]: th-udev-rebind.service: Deactivated successfully. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Oct 25 21:39:00 volumioes9038pro dhcpcd[971]: dev: loaded udev Oct 25 21:39:00 volumioes9038pro dhcpcd[971]: DUID 00:01:00:01:30:7a:b6:90:d8:3a:dd:a8:99:0f Oct 25 21:39:00 volumioes9038pro dhcpcd[971]: dhcp_vendor: No such process Oct 25 21:39:00 volumioes9038pro dhcpcd[955]: dhcp_vendor: No such process Oct 25 21:39:00 volumioes9038pro systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 25 21:39:00 volumioes9038pro dbus-daemon[897]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Successfully called chroot(). Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Successfully dropped remaining capabilities. Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Starting SDP server Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Excluding (cli) sap Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Excluding (cli) midi Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Excluding (cli) battery Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Loading service file /services/volumio.service. Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: New relevant interface lo.IPv4 for mDNS. Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Network interface enumeration completed. Oct 25 21:39:00 volumioes9038pro avahi-daemon[895]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished glamor-test.service - Check for glamor. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started systemd-logind.service - User Login Management. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started bluetooth.service - Bluetooth service. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting bluealsa.service - BlueALSA service... Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: BNEP filters: protocol multicast Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: BNEP socket layer initialized Oct 25 21:39:00 volumioes9038pro dbus-daemon[897]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=896 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Bluetooth management interface 1.23 initialized Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 25 21:39:00 volumioes9038pro systemd[1]: iptables.service: Deactivated successfully. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished rp1-test.service - Check for RP1 displays for Xorg. Oct 25 21:39:00 volumioes9038pro dhcpcd[971]: eth0: waiting for carrier Oct 25 21:39:00 volumioes9038pro dhcpcd[971]: wlan0: waiting for carrier Oct 25 21:39:00 volumioes9038pro loadcpufreq[924]: Loading cpufreq kernel modules...done (none). Oct 25 21:39:00 volumioes9038pro systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: hci0: BCM: features 0x2f Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Oct 25 21:39:00 volumioes9038pro wpa_supplicant[1000]: Successfully initialized wpa_supplicant Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Battery Provider Manager created Oct 25 21:39:00 volumioes9038pro kernel: Bluetooth: MGMT ver 1.23 Oct 25 21:39:00 volumioes9038pro systemd[1]: Started wpa_supplicant.service - WPA supplicant. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target network.target - Network. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target network-online.target - Network is Online. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Oct 25 21:39:00 volumioes9038pro volumio-remote-updater[911]: Volumio OTA Client: 1.7.0 Oct 25 21:39:00 volumioes9038pro volumio-remote-updater[911]: Error: No active session Oct 25 21:39:00 volumioes9038pro volumio-remote-updater[911]: [2025-10-25 21:39:00] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 25 21:39:00 volumioes9038pro volumio-remote-updater[911]: [2025-10-25 21:39:00] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 25 21:39:00 volumioes9038pro volumio-remote-updater[911]: [2025-10-25 21:39:00] [error] handle_connect error: Connection refused Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting nmbd.service - Samba NMB Daemon... Oct 25 21:39:00 volumioes9038pro dbus-daemon[897]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting ntpsec.service - Network Time Service... Oct 25 21:39:00 volumioes9038pro kernel: NET: Registered PF_ALG protocol family Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Oct 25 21:39:00 volumioes9038pro sm-notify[1078]: Version 2.6.2 starting Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 25 21:39:00 volumioes9038pro systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 25 21:39:00 volumioes9038pro systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 25 21:39:00 volumioes9038pro systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: bap: Operation not supported (95) Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Oct 25 21:39:00 volumioes9038pro cpufrequtils[1060]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 25 21:39:00 volumioes9038pro upmpdcli[908]: Could not open config: /tmp/upmpdcli.conf Oct 25 21:39:00 volumioes9038pro systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 25 21:39:00 volumioes9038pro systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 25 21:39:00 volumioes9038pro welcome[1095]: Resolved ip:[0] Oct 25 21:39:00 volumioes9038pro ntpd[1099]: INIT: ntpd ntpsec-1.2.2: Starting Oct 25 21:39:00 volumioes9038pro ntpd[1099]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 25 21:39:00 volumioes9038pro systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Oct 25 21:39:00 volumioes9038pro ntp-systemd-wrapper[1099]: 2025-10-25T21:39:00 ntpd[1099]: INIT: ntpd ntpsec-1.2.2: Starting Oct 25 21:39:00 volumioes9038pro ntp-systemd-wrapper[1099]: 2025-10-25T21:39:00 ntpd[1099]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 25 21:39:00 volumioes9038pro systemd[1]: Started ntpsec.service - Network Time Service. Oct 25 21:39:00 volumioes9038pro rpi-eeprom-update[902]: BOOTLOADER: up to date Oct 25 21:39:00 volumioes9038pro rpi-eeprom-update[902]: CURRENT: Thu May 8 14:13:17 UTC 2025 (1746713597) Oct 25 21:39:00 volumioes9038pro rpi-eeprom-update[902]: LATEST: Thu May 8 14:13:17 UTC 2025 (1746713597) Oct 25 21:39:00 volumioes9038pro rpi-eeprom-update[902]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2712/default) Oct 25 21:39:00 volumioes9038pro rpi-eeprom-update[902]: Use raspi-config to change the release. Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: precision = 0.370 usec (-21) Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: successfully locked into RAM Oct 25 21:39:00 volumioes9038pro ifplugd(eth0)[1143]: ifplugd 0.28 initializing. Oct 25 21:39:00 volumioes9038pro ifplugd(eth0)[1143]: Using interface eth0/D8:3A:DD:A8:99:0F with driver (version: 6.12.47-v8+) Oct 25 21:39:00 volumioes9038pro ifplugd(eth0)[1143]: Using detection mode: SIOCETHTOOL Oct 25 21:39:00 volumioes9038pro ifplugd(eth0)[1143]: Initialization complete, link beat not detected. Oct 25 21:39:00 volumioes9038pro ntpd[1120]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Oct 25 21:39:00 volumioes9038pro ntpd[1120]: CONFIG: restrict nopeer ignored Oct 25 21:39:00 volumioes9038pro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 25 21:39:00 volumioes9038pro ntpd[1120]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 25 21:39:00 volumioes9038pro ntpd[1120]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: Using SO_TIMESTAMPNS(ns) Oct 25 21:39:00 volumioes9038pro ntpd[1120]: IO: Listen and drop on 0 v6wildcard [::]:123 Oct 25 21:39:00 volumioes9038pro ntpd[1120]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 25 21:39:00 volumioes9038pro ntpd[1120]: IO: Listen normally on 2 lo 127.0.0.1:123 Oct 25 21:39:00 volumioes9038pro ntpd[1120]: IO: Listening on routing socket on fd #19 for interface updates Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: This system has a 32-bit time_t. Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Oct 25 21:39:00 volumioes9038pro ntpd[1120]: INIT: Running with OpenSSL 3.0.17 1 Jul 2025, 30000110 Oct 25 21:39:00 volumioes9038pro ntpd[1120]: NTSc: Using system default root certificates. Oct 25 21:39:00 volumioes9038pro haveged[814]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Oct 25 21:39:00 volumioes9038pro haveged[814]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356 Oct 25 21:39:00 volumioes9038pro haveged[814]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9994 Oct 25 21:39:00 volumioes9038pro haveged[814]: haveged: fills: 0, generated: 0 Oct 25 21:39:00 volumioes9038pro ifplugd[1064]: Network Interface Plugging Daemon...start eth0...done. Oct 25 21:39:00 volumioes9038pro systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Oct 25 21:39:00 volumioes9038pro ntpd[1120]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: main.c:663: Starting main dispatching loop Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: main.c:118: Acquired D-Bus service name: org.bluealsa Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Oct 25 21:39:00 volumioes9038pro systemd[1]: Started bluealsa.service - BlueALSA service. Oct 25 21:39:00 volumioes9038pro systemd[1]: Reached target bluetooth.target - Bluetooth Support. Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: bluez.c:153: Registering media application: /org/bluez/hci0 Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Oct 25 21:39:00 volumioes9038pro bluealsa[1015]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 25 21:39:00 volumioes9038pro bluetoothd[896]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 25 21:39:01 volumioes9038pro systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Oct 25 21:39:01 volumioes9038pro systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Oct 25 21:39:01 volumioes9038pro avahi-daemon[895]: Server startup complete. Host name is volumioes9038pro.local. Local service cookie is 1625457400. Oct 25 21:39:01 volumioes9038pro nmbd[1177]: [2025/10/25 21:39:01.321184, 0] ../../source3/nmbd/nmbd.c:901(main) Oct 25 21:39:01 volumioes9038pro nmbd[1177]: nmbd version 4.17.8-Raspbian started. Oct 25 21:39:01 volumioes9038pro nmbd[1177]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 25 21:39:01 volumioes9038pro nmbd[1177]: [2025/10/25 21:39:01.326187, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 25 21:39:01 volumioes9038pro nmbd[1177]: started asyncdns process 1180 Oct 25 21:39:01 volumioes9038pro nmbd[1177]: [2025/10/25 21:39:01.326939, 0] ../../lib/util/become_daemon.c:150(daemon_status) Oct 25 21:39:01 volumioes9038pro nmbd[1177]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 25 21:39:01 volumioes9038pro nmbd[1177]: [2025/10/25 21:39:01.326973, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Oct 25 21:39:01 volumioes9038pro nmbd[1177]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 25 21:39:01 volumioes9038pro wireless.js[914]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Oct 25 21:39:01 volumioes9038pro wireless.js[914]: WIRELESS.JS: Wired network status changed to: ---connected--- Oct 25 21:39:01 volumioes9038pro wireless.js[914]: WIRELESS.JS: Wireless.js initializing wireless flow Oct 25 21:39:01 volumioes9038pro wireless.js[914]: WIRELESS.JS: Cleaning previous... Oct 25 21:39:01 volumioes9038pro sudo[1195]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Oct 25 21:39:01 volumioes9038pro sudo[1195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 25 21:39:01 volumioes9038pro sudo[1195]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:01 volumioes9038pro sudo[1197]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Oct 25 21:39:01 volumioes9038pro sudo[1197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 25 21:39:01 volumioes9038pro ntpd[1120]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:01 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 25 21:39:01 volumioes9038pro ntpd[1120]: DNS: dns_check: DNS error: -11, System error Oct 25 21:39:01 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Oct 25 21:39:01 volumioes9038pro sudo[1197]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:02 volumioes9038pro wireless.js[914]: WIRELESS.JS: Stopped aP Oct 25 21:39:02 volumioes9038pro sudo[1206]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 21:39:02 volumioes9038pro sudo[1206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:02 volumioes9038pro sudo[1206]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:02 volumioes9038pro kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 25 21:39:02 volumioes9038pro sudo[1208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 25 21:39:02 volumioes9038pro sudo[1208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:02 volumioes9038pro sudo[1208]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:02 volumioes9038pro sudo[1216]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 21:39:02 volumioes9038pro sudo[1216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:02 volumioes9038pro sudo[1216]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:02 volumioes9038pro sudo[1218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 25 21:39:02 volumioes9038pro sudo[1218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:02 volumioes9038pro avahi-daemon[895]: Service "VolumioES9038Pro" (/services/volumio.service) successfully established. Oct 25 21:39:02 volumioes9038pro ntpd[1120]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:02 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 25 21:39:02 volumioes9038pro ntpd[1120]: DNS: dns_check: DNS error: -11, System error Oct 25 21:39:02 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Oct 25 21:39:03 volumioes9038pro ntpd[1120]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:03 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 25 21:39:03 volumioes9038pro ntpd[1120]: DNS: dns_check: DNS error: -11, System error Oct 25 21:39:03 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Oct 25 21:39:04 volumioes9038pro dhcpcd[971]: eth0: carrier acquired Oct 25 21:39:04 volumioes9038pro dhcpcd[820]: eth0: carrier acquired Oct 25 21:39:04 volumioes9038pro sh[807]: eth0: carrier acquired Oct 25 21:39:04 volumioes9038pro kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Oct 25 21:39:04 volumioes9038pro dhcpcd[820]: eth0: IAID dd:a8:99:0f Oct 25 21:39:04 volumioes9038pro sh[807]: eth0: IAID dd:a8:99:0f Oct 25 21:39:04 volumioes9038pro dhcpcd[971]: eth0: IAID dd:a8:99:0f Oct 25 21:39:04 volumioes9038pro dhcpcd[971]: eth0: adding address fe80::80ce:1067:5862:a6f4 Oct 25 21:39:04 volumioes9038pro sh[807]: eth0: adding address fe80::80ce:1067:5862:a6f4 Oct 25 21:39:04 volumioes9038pro dhcpcd[820]: eth0: adding address fe80::80ce:1067:5862:a6f4 Oct 25 21:39:04 volumioes9038pro sh[807]: ipv6_addaddr1: Permission denied Oct 25 21:39:04 volumioes9038pro dhcpcd[820]: ipv6_addaddr1: Permission denied Oct 25 21:39:04 volumioes9038pro dhcpcd[971]: ipv6_addaddr1: Permission denied Oct 25 21:39:04 volumioes9038pro systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 25 21:39:04 volumioes9038pro sudo[1218]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:04 volumioes9038pro wireless.js[914]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: PL Oct 25 21:39:04 volumioes9038pro sudo[1246]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 25 21:39:04 volumioes9038pro sudo[1246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:04 volumioes9038pro sudo[1246]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:04 volumioes9038pro sudo[1248]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set PL Oct 25 21:39:04 volumioes9038pro sudo[1248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:04 volumioes9038pro sudo[1248]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:04 volumioes9038pro wireless.js[914]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: PL Oct 25 21:39:04 volumioes9038pro wireless.js[914]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Oct 25 21:39:04 volumioes9038pro wireless.js[914]: WIRELESS.JS: Notified systemd about wireless ready Oct 25 21:39:04 volumioes9038pro kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 25 21:39:04 volumioes9038pro systemd[1]: Started wireless.service - Wireless Services. Oct 25 21:39:04 volumioes9038pro systemd[1]: Started volumio.service - Volumio Backend Module. Oct 25 21:39:04 volumioes9038pro systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Oct 25 21:39:04 volumioes9038pro systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 25 21:39:04 volumioes9038pro ntpd[1120]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:04 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 25 21:39:04 volumioes9038pro ntpd[1120]: DNS: dns_check: DNS error: -11, System error Oct 25 21:39:04 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Oct 25 21:39:04 volumioes9038pro systemd[1]: Received SIGRTMIN+21 from PID 289 (plymouthd). Oct 25 21:39:04 volumioes9038pro volumio-cpu-tweak[1258]: Setting RT Priority for mpd Oct 25 21:39:04 volumioes9038pro volumio-cpu-tweak[1273]: pid 35's current scheduling policy: SCHED_OTHER Oct 25 21:39:04 volumioes9038pro volumio-cpu-tweak[1273]: pid 35's current scheduling priority: 0 Oct 25 21:39:04 volumioes9038pro volumio-cpu-tweak[1258]: Setting MPD Affinity Oct 25 21:39:04 volumioes9038pro volumio-cpu-tweak[1274]: pid 3's current affinity mask: f Oct 25 21:39:04 volumioes9038pro volumio-cpu-tweak[1258]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 25 21:39:04 volumioes9038pro volumio-cpu-tweak[1258]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Oct 25 21:39:04 volumioes9038pro systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 25 21:39:04 volumioes9038pro systemd[1]: Received SIGRTMIN+21 from PID 289 (plymouthd). Oct 25 21:39:04 volumioes9038pro systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 25 21:39:04 volumioes9038pro systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Oct 25 21:39:04 volumioes9038pro systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Oct 25 21:39:04 volumioes9038pro systemd[1]: Reached target getty.target - Login Prompts. Oct 25 21:39:04 volumioes9038pro ifplugd(eth0)[1143]: Link beat detected. Oct 25 21:39:05 volumioes9038pro dhcpcd[971]: eth0: soliciting an IPv6 router Oct 25 21:39:05 volumioes9038pro ifplugd(eth0)[1143]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 25 21:39:05 volumioes9038pro ifplugd(eth0)[1143]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 25 21:39:05 volumioes9038pro dhcpcd[820]: eth0: soliciting an IPv6 router Oct 25 21:39:05 volumioes9038pro sh[807]: eth0: soliciting an IPv6 router Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ------------------------------------------- Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ----- Volumio3 ---- Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ------------------------------------------- Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ----- System startup ---- Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ------------------------------------------- Oct 25 21:39:05 volumioes9038pro dhcpcd[971]: eth0: soliciting a DHCP lease Oct 25 21:39:05 volumioes9038pro volumio-remote-updater[911]: [2025-10-25 21:39:05] [connect] Successful connection Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: MYVOLUMIO Environment detected Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Plugin folders cleanup Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning into folder /volumio/app/plugins/ Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning category audio_interface Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning category miscellanea Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning category music_service Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning category plugins.json Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning category system_controller Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning category user_interface Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning into folder /data/plugins/ Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Scanning category user_interface Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Plugin folders cleanup completed Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ------------------------------------------- Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ----- Core plugins startup ---- Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: ------------------------------------------- Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Loading plugins from folder /volumio/app/plugins/ Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Adding plugin upnp to MyMusic Plugins Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Loading plugins from folder /data/plugins/ Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Loading plugin "system"... Oct 25 21:39:05 volumioes9038pro volumio[1256]: info: Loading plugin "appearance"... Oct 25 21:39:06 volumioes9038pro dhcpcd[971]: eth0: offered 192.168.88.116 from 192.168.88.1 Oct 25 21:39:06 volumioes9038pro dhcpcd[971]: eth0: probing address 192.168.88.116/24 Oct 25 21:39:06 volumioes9038pro dhcpcd[820]: eth0: soliciting a DHCP lease Oct 25 21:39:06 volumioes9038pro sh[807]: eth0: soliciting a DHCP lease Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "network"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Refreshing Cached IP Addresses Oct 25 21:39:06 volumioes9038pro sudo[1308]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 21:39:06 volumioes9038pro sudo[1308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:06 volumioes9038pro sudo[1310]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 21:39:06 volumioes9038pro sudo[1310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:06 volumioes9038pro sudo[1308]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:06 volumioes9038pro sudo[1310]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:06 volumioes9038pro dhcpcd[820]: eth0: offered 192.168.88.116 from 192.168.88.1 Oct 25 21:39:06 volumioes9038pro sh[807]: eth0: offered 192.168.88.116 from 192.168.88.1 Oct 25 21:39:06 volumioes9038pro dhcpcd[820]: eth0: probing address 192.168.88.116/24 Oct 25 21:39:06 volumioes9038pro sh[807]: eth0: probing address 192.168.88.116/24 Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "services"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "alsa_controller"... Oct 25 21:39:06 volumioes9038pro sudo[1316]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 25 21:39:06 volumioes9038pro sudo[1316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "wizard"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "networkfs"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Starting Udev Watcher for removable devices Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Ignoring mount for partition: boot Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Ignoring mount for partition: volumio Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Ignoring mount for partition: volumio_data Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "volumio_command_line_client"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "upnp"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: [1761421146131] Starting Upmpd Daemon Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "my_music"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "mpd"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "upnp_browser"... Oct 25 21:39:06 volumioes9038pro bash[1090]: setdatetime-helper: all HTTPS Date fallbacks failed Oct 25 21:39:06 volumioes9038pro systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 25 21:39:06 volumioes9038pro systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "alarm-clock"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "airplay_emulation"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Starting Shairport Sync Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "last_100"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "webradio"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "i2s_dacs"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: I2S DAC not set, start Auto-detection Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "volumiodiscovery"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 25 21:39:06 volumioes9038pro volumio[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 25 21:39:06 volumioes9038pro volumio[1256]: *** WARNING *** For more information see Oct 25 21:39:06 volumioes9038pro volumio[1256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 25 21:39:06 volumioes9038pro volumio[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 25 21:39:06 volumioes9038pro volumio[1256]: *** WARNING *** For more information see Oct 25 21:39:06 volumioes9038pro node[1256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 25 21:39:06 volumioes9038pro node[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 25 21:39:06 volumioes9038pro node[1256]: *** WARNING *** For more information see Oct 25 21:39:06 volumioes9038pro node[1256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 25 21:39:06 volumioes9038pro node[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 25 21:39:06 volumioes9038pro node[1256]: *** WARNING *** For more information see Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Discovery: Started advertising with name: VolumioES9038Pro Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "outputs"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "albumart"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Plugin example_plugin is not enabled Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "inputs"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "updater_comm"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Plugin mpdemulation is not enabled Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "rest_api"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "websocket"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Starting Socket.io Server version 1.7.4 Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading plugin "touch_display"... Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Applying required configuration parameters for plugin touch_display Oct 25 21:39:06 volumioes9038pro volumio[1342]: Forking 3 albumart workers Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Loading i18n strings for locale pl Oct 25 21:39:06 volumioes9038pro volumio[1256]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 25 21:39:06 volumioes9038pro volumio[1256]: Updating browse sources language Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::initPlayerControls Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: Express server listening on port 3000 Oct 25 21:39:06 volumioes9038pro volumio[1256]: [Metrics] WebUI: 1s 607.41ms Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreStateMachine::resetVolumioState Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreStateMachine::getcurrentVolume Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Cannot read play queue from file Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Volumio Network Manager: Network status updated: 0 Oct 25 21:39:06 volumioes9038pro volumio-remote-updater[911]: [2025-10-25 21:39:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1761421145 101 Oct 25 21:39:06 volumioes9038pro volumio[1256]: 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 Oct 25 21:39:06 volumioes9038pro volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreStateMachine::setRepeat null single undefined Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreStateMachine::setRandom null Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Setting Device type: Raspberry PI Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Completed loading Core Plugins Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Preparing to generate the ALSA configuration file Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Asound.conf file unchanged, so no further update is needed Oct 25 21:39:06 volumioes9038pro volumio[1256]: info: Output device has changed, restarting MPD Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Output device has changed, restarting Shairport Sync Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:07 volumioes9038pro sudo[1396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 21:39:07 volumioes9038pro sudo[1397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 21:39:07 volumioes9038pro sudo[1396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: ___________ START PLUGINS ___________ Oct 25 21:39:07 volumioes9038pro sudo[1396]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: ControllerMpd::onStart: Initializing MPD Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Creating MPD Configuration file Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: [1761421147032] CoreMusicLibrary::Adding element Serwery Mediów Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 21:39:07 volumioes9038pro sudo[1405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 21:39:07 volumioes9038pro sudo[1405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro sudo[1407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 21:39:07 volumioes9038pro sudo[1407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:07 volumioes9038pro sudo[1405]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: [1761421147070] CoreMusicLibrary::Adding element Last_100 Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: [1761421147071] CoreMusicLibrary::Adding element Webradio Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Initializing BBC Radios Oct 25 21:39:07 volumioes9038pro systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 25 21:39:07 volumioes9038pro systemd[1]: Starting mpd.service - Music Player Daemon... Oct 25 21:39:07 volumioes9038pro systemd[1]: mpd.service: Deactivated successfully. Oct 25 21:39:07 volumioes9038pro systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 25 21:39:07 volumioes9038pro systemd[1]: mpd.socket: Deactivated successfully. Oct 25 21:39:07 volumioes9038pro systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 25 21:39:07 volumioes9038pro systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 25 21:39:07 volumioes9038pro systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 25 21:39:07 volumioes9038pro systemd[1]: Starting mpd.service - Music Player Daemon... Oct 25 21:39:07 volumioes9038pro volumio[1352]: Starting albumart workers Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Loading i18n strings for locale pl Oct 25 21:39:07 volumioes9038pro volumio[1353]: Starting albumart workers Oct 25 21:39:07 volumioes9038pro volumio[1256]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Volumio Calling Home Oct 25 21:39:07 volumioes9038pro sudo[1429]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 21:39:07 volumioes9038pro sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 25 21:39:07 volumioes9038pro sudo[1440]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 25 21:39:07 volumioes9038pro sudo[1429]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:07 volumioes9038pro volumio[1354]: Starting albumart workers Oct 25 21:39:07 volumioes9038pro sudo[1443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 25 21:39:07 volumioes9038pro sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro sudo[1445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 25 21:39:07 volumioes9038pro sudo[1445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro sudo[1449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 25 21:39:07 volumioes9038pro sudo[1449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro systemd[1]: Reloading. Oct 25 21:39:07 volumioes9038pro volumio[1256]: Unhandled rejection Error: No sockets available, cannot start. Oct 25 21:39:07 volumioes9038pro volumio[1256]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 25 21:39:07 volumioes9038pro volumio[1256]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 25 21:39:07 volumioes9038pro volumio[1256]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: touch_display: No backlight interface detected. Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: MPD Permissions set Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: MPD Permissions set Oct 25 21:39:07 volumioes9038pro volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Discovery: adding beabab03-76ab-4f29-98c2-2924c066708a Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Discovery: Found device VolumioES9038Pro Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Starting Shairport Sync Oct 25 21:39:07 volumioes9038pro sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 25 21:39:07 volumioes9038pro sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Starting Shairport Sync Oct 25 21:39:07 volumioes9038pro sudo[1502]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: Starting Shairport Sync Oct 25 21:39:07 volumioes9038pro sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 21:39:07 volumioes9038pro sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro sudo[1507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 21:39:07 volumioes9038pro sudo[1507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 21:39:07 volumioes9038pro sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 25 21:39:07 volumioes9038pro systemd[1]: Reloading. Oct 25 21:39:07 volumioes9038pro sudo[1445]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:07 volumioes9038pro volumio[1256]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 25 21:39:08 volumioes9038pro sudo[1449]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: touch_display: systemctl daemon-reload succeeded. Oct 25 21:39:08 volumioes9038pro sudo[1531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 25 21:39:08 volumioes9038pro sudo[1531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:08 volumioes9038pro sudo[1531]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro volumio[1256]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Oct 25 21:39:08 volumioes9038pro volumio[1256]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Oct 25 21:39:08 volumioes9038pro systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 25 21:39:08 volumioes9038pro systemd[1]: shairport-sync.service: Deactivated successfully. Oct 25 21:39:08 volumioes9038pro systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 25 21:39:08 volumioes9038pro systemd[1]: shairport-sync.service: Consumed 1.610s CPU time. Oct 25 21:39:08 volumioes9038pro sudo[1443]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 25 21:39:08 volumioes9038pro systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 25 21:39:08 volumioes9038pro sudo[1507]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro sudo[1505]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro sudo[1509]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: Shairport-Sync Started Oct 25 21:39:08 volumioes9038pro volumio[1256]: Error adding Membership: Error: addMembership EINVAL Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: Shairport-Sync Started Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: Shairport-Sync Started Oct 25 21:39:08 volumioes9038pro mpd[1441]: 2025-10-25T21:39:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 21:39:08 volumioes9038pro systemd[1]: Started mpd.service - Music Player Daemon. Oct 25 21:39:08 volumioes9038pro sudo[1407]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro sudo[1397]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: Completed starting Core Plugins Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: ------------------------------------------- Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: ----- MyVolumio plugins startup ---- Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: ------------------------------------------- Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 25 21:39:08 volumioes9038pro volumio[1256]: info: MPD running with PID1441 Oct 25 21:39:08 volumioes9038pro volumio[1256]: ,establishing connection Oct 25 21:39:08 volumioes9038pro volumio[1256]: error: MPD error: The expression evaluated to a falsy value: Oct 25 21:39:08 volumioes9038pro volumio[1256]: assert.ok(self.idling) Oct 25 21:39:08 volumioes9038pro volumio[1256]: error: The expression evaluated to a falsy value: Oct 25 21:39:08 volumioes9038pro volumio[1256]: assert.ok(self.idling) Oct 25 21:39:08 volumioes9038pro volumio[1256]: error: MPD error: The expression evaluated to a falsy value: Oct 25 21:39:08 volumioes9038pro volumio[1256]: assert.ok(self.idling) Oct 25 21:39:08 volumioes9038pro volumio[1256]: error: The expression evaluated to a falsy value: Oct 25 21:39:08 volumioes9038pro volumio[1256]: assert.ok(self.idling) Oct 25 21:39:08 volumioes9038pro volumio[1256]: error: updateQueue error: null Oct 25 21:39:08 volumioes9038pro sudo[1316]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:10 volumioes9038pro dhcpcd[971]: eth0: leased 192.168.88.116 for 900 seconds Oct 25 21:39:10 volumioes9038pro avahi-daemon[895]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.88.116. Oct 25 21:39:10 volumioes9038pro dhcpcd[971]: eth0: adding route to 192.168.88.0/24 Oct 25 21:39:10 volumioes9038pro dhcpcd[971]: eth0: adding default route via 192.168.88.1 Oct 25 21:39:10 volumioes9038pro avahi-daemon[895]: New relevant interface eth0.IPv4 for mDNS. Oct 25 21:39:10 volumioes9038pro avahi-daemon[895]: Registering new address record for 192.168.88.116 on eth0.IPv4. Oct 25 21:39:10 volumioes9038pro systemd[1]: welcome.service: Deactivated successfully. Oct 25 21:39:10 volumioes9038pro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 25 21:39:10 volumioes9038pro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 25 21:39:10 volumioes9038pro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 25 21:39:10 volumioes9038pro welcome[1571]: Resolved ip:[1] 192.168.88.116 Oct 25 21:39:10 volumioes9038pro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 25 21:39:10 volumioes9038pro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 25 21:39:10 volumioes9038pro systemd[1]: Started nmbd.service - Samba NMB Daemon. Oct 25 21:39:10 volumioes9038pro systemd[1]: Starting winbind.service - Samba Winbind Daemon... Oct 25 21:39:10 volumioes9038pro winbindd[1579]: [2025/10/25 21:39:10.801579, 0] ../../source3/winbindd/winbindd.c:1440(main) Oct 25 21:39:10 volumioes9038pro winbindd[1579]: winbindd version 4.17.8-Raspbian started. Oct 25 21:39:10 volumioes9038pro winbindd[1579]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 25 21:39:10 volumioes9038pro winbindd[1579]: [2025/10/25 21:39:10.805319, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Oct 25 21:39:10 volumioes9038pro winbindd[1579]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 25 21:39:10 volumioes9038pro systemd[1]: Started winbind.service - Samba Winbind Daemon. Oct 25 21:39:10 volumioes9038pro systemd[1]: Starting smbd.service - Samba SMB Daemon... Oct 25 21:39:10 volumioes9038pro smbd[1596]: [2025/10/25 21:39:10.947538, 0] ../../source3/smbd/server.c:1741(main) Oct 25 21:39:10 volumioes9038pro smbd[1596]: smbd version 4.17.8-Raspbian started. Oct 25 21:39:10 volumioes9038pro smbd[1596]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 25 21:39:11 volumioes9038pro systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 25 21:39:11 volumioes9038pro systemd[1]: Reached target multi-user.target - Multi-User System. Oct 25 21:39:11 volumioes9038pro systemd[1]: Reached target graphical.target - Graphical Interface. Oct 25 21:39:11 volumioes9038pro systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 25 21:39:11 volumioes9038pro systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 25 21:39:11 volumioes9038pro systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 25 21:39:11 volumioes9038pro systemd[1]: Startup finished in 10.606s (kernel) + 12.477s (userspace) = 23.084s. Oct 25 21:39:11 volumioes9038pro dhcpcd[820]: eth0: leased 192.168.88.116 for 900 seconds Oct 25 21:39:11 volumioes9038pro sh[807]: eth0: leased 192.168.88.116 for 900 seconds Oct 25 21:39:11 volumioes9038pro dhcpcd[820]: eth0: adding route to 192.168.88.0/24 Oct 25 21:39:11 volumioes9038pro dhcpcd[820]: eth0: adding default route via 192.168.88.1 Oct 25 21:39:11 volumioes9038pro sh[807]: eth0: adding route to 192.168.88.0/24 Oct 25 21:39:11 volumioes9038pro sh[807]: eth0: adding default route via 192.168.88.1 Oct 25 21:39:11 volumioes9038pro sh[807]: forked to background, child pid 816 Oct 25 21:39:11 volumioes9038pro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 25 21:39:11 volumioes9038pro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 25 21:39:11 volumioes9038pro systemd[1]: welcome.service: Deactivated successfully. Oct 25 21:39:11 volumioes9038pro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 25 21:39:11 volumioes9038pro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 25 21:39:11 volumioes9038pro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 25 21:39:11 volumioes9038pro welcome[1629]: Resolved ip:[1] 192.168.88.116 Oct 25 21:39:11 volumioes9038pro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 25 21:39:11 volumioes9038pro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 25 21:39:11 volumioes9038pro ifplugd(eth0)[1143]: client: ifup: interface eth0 already configured Oct 25 21:39:11 volumioes9038pro sh[1657]: eth0=eth0 Oct 25 21:39:11 volumioes9038pro sudo[1659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 25 21:39:11 volumioes9038pro sudo[1659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:11 volumioes9038pro sudo[1662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 25 21:39:11 volumioes9038pro sudo[1662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:11 volumioes9038pro systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 25 21:39:11 volumioes9038pro mpd_monitor.sh[1663]: MPD Monitor Service: Starting MPD Monitor Service Oct 25 21:39:11 volumioes9038pro sudo[1659]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:11 volumioes9038pro volumio[1256]: info: Successfully started MPD Monitor Oct 25 21:39:11 volumioes9038pro systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 25 21:39:11 volumioes9038pro systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 25 21:39:11 volumioes9038pro systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 25 21:39:11 volumioes9038pro systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 25 21:39:11 volumioes9038pro sudo[1662]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:11 volumioes9038pro mpd_monitor.sh[1667]: MPD Monitor Service: Starting MPD Monitor Service Oct 25 21:39:11 volumioes9038pro volumio[1256]: info: Successfully started MPD Monitor Oct 25 21:39:11 volumioes9038pro ifplugd(eth0)[1143]: Program executed successfully. Oct 25 21:39:11 volumioes9038pro volumio[1256]: info: Discovery: this is already registered, beabab03-76ab-4f29-98c2-2924c066708a Oct 25 21:39:11 volumioes9038pro volumio[1256]: info: Discovery: Found device VolumioES9038Pro Oct 25 21:39:11 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:11 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:12 volumioes9038pro ntpd[1120]: IO: Listen normally on 3 eth0 192.168.88.116:123 Oct 25 21:39:12 volumioes9038pro ntpd[1120]: IO: new interface(s) found: waking up resolver Oct 25 21:39:12 volumioes9038pro ntpd[1120]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:13 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 25 21:39:13 volumioes9038pro ntpd[1120]: DNS: Pool taking: 194.146.251.114 Oct 25 21:39:13 volumioes9038pro ntpd[1120]: DNS: Pool taking: 85.115.212.251 Oct 25 21:39:13 volumioes9038pro ntpd[1120]: DNS: Pool taking: 213.222.217.11 Oct 25 21:39:13 volumioes9038pro ntpd[1120]: DNS: Pool taking: 89.250.197.242 Oct 25 21:39:13 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 25 21:39:13 volumioes9038pro volumio[1256]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 25 21:39:13 volumioes9038pro ntpd[1120]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 46.174.178.150 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 162.159.200.1 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 162.159.200.123 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 5.185.77.40 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 2a03:7580:1:217::10 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 2001:6a0:0:31::2 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 2606:4700:f1::1 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 2001:41d0:601:1100::649c Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 213.222.217.10 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool skipping: 213.222.217.11 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 51.68.141.5 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: Pool taking: 46.171.0.154 Oct 25 21:39:14 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 25 21:39:15 volumioes9038pro ntpd[1120]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 25 21:39:16 volumioes9038pro ntpd[1120]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 25 21:39:16 volumioes9038pro ntpd[1120]: DNS: Pool taking: 193.70.94.182 Oct 25 21:39:16 volumioes9038pro ntpd[1120]: DNS: Pool taking: 193.59.134.156 Oct 25 21:39:16 volumioes9038pro ntpd[1120]: DNS: Pool taking: 194.146.251.100 Oct 25 21:39:16 volumioes9038pro ntpd[1120]: DNS: Pool skipping: 51.68.141.5 Oct 25 21:39:16 volumioes9038pro ntpd[1120]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Volumio Network Manager: Network status updated: 1 Oct 25 21:39:16 volumioes9038pro sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 21:39:16 volumioes9038pro sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:16 volumioes9038pro sudo[1690]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:16 volumioes9038pro sudo[1692]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 21:39:16 volumioes9038pro sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:16 volumioes9038pro sudo[1692]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:16 volumioes9038pro sudo[1695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 25 21:39:16 volumioes9038pro sudo[1695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:16 volumioes9038pro systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 25 21:39:16 volumioes9038pro sudo[1695]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Upmpdcli Daemon Started Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Adding plugin bluetooth to MyMusic Plugins Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Adding plugin multiroom to MyMusic Plugins Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Adding plugin metavolumio to MyMusic Plugins Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Adding plugin cd_controller to MyMusic Plugins Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 25 21:39:16 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: Starting MyVolumio Remote Streaming Endpoints Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: MyVolumio login type: Token Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: Streaming services startup Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: Starting Streaming Daemon Oct 25 21:39:17 volumioes9038pro sudo[1716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 21:39:17 volumioes9038pro sudo[1716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 25 21:39:17 volumioes9038pro sudo[1716]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:17 volumioes9038pro volumio[1256]: error: Cannot start Volumio Streaming Daemon Oct 25 21:39:17 volumioes9038pro volumio[1256]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 25 21:39:17 volumioes9038pro volumio[1256]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.062&uuid=f8ce326471a5a6f9d21d457658dcf699" http://updates.volumio.org/downloader-v1/track-device Oct 25 21:39:17 volumioes9038pro volumio[1256]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 25 21:39:17 volumioes9038pro volumio[1256]: Dload Upload Total Spent Left Speed Oct 25 21:39:17 volumioes9038pro volumio[1256]: [132B blob data] Oct 25 21:39:17 volumioes9038pro volumio[1256]: retrying in 5 seconds, trial 0 Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: Volumio Calling Home Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: Volumio called home Oct 25 21:39:17 volumioes9038pro volumio[1256]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 25 21:39:18 volumioes9038pro volumio[1256]: info: MyVolumio token set successfully Oct 25 21:39:18 volumioes9038pro volumio[1256]: info: MYVOLUMIO: Adding device Oct 25 21:39:18 volumioes9038pro volumio[1256]: info: MYVOLUMIO: Evaluating Server Oct 25 21:39:42 volumioes9038pro ntpd[1120]: CLOCK: time stepped by 23.714111 Oct 25 21:39:42 volumioes9038pro ntpd[1120]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: MyVolumio Plan changed: premium Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Removing browser output: myVolumio user plan is not superstar Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Removing audio output: Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: MYVOLUMIO: Adding device Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: MYVOLUMIO: Evaluating Server Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Remote config written successfully Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Starting Tunnel 1 Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Starting Tunnel Connection Checker Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Completed starting MyVolumio Plugin Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: MYVolumio Device enabled Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: MyVolumio status changed Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Streaming services startup Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: Starting Streaming Daemon Oct 25 21:39:42 volumioes9038pro sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 25 21:39:42 volumioes9038pro sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 25 21:39:42 volumioes9038pro sudo[1759]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:42 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 25 21:39:42 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 25 21:39:43 volumioes9038pro sudo[1763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 25 21:39:43 volumioes9038pro sudo[1763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro sudo[1763]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: MultiRoom plugin initialized Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: STOPPING SNAPCLIENT Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: Snap server stop Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: STOPPING volumioStreaming Oct 25 21:39:43 volumioes9038pro sudo[1780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 25 21:39:43 volumioes9038pro sudo[1780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 25 21:39:43 volumioes9038pro sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro sudo[1787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 25 21:39:43 volumioes9038pro sudo[1787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 25 21:39:43 volumioes9038pro sudo[1787]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:43 volumioes9038pro sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 25 21:39:43 volumioes9038pro sudo[1786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 25 21:39:43 volumioes9038pro sudo[1780]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 25 21:39:43 volumioes9038pro sudo[1782]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 25 21:39:43 volumioes9038pro sudo[1786]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Preparing to generate the ALSA configuration file Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Reading ALSA contributions from plugins. Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Setting Geolocation for MyVolumio to eu9 Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Setting Geolocation for MyVolumio to eu9 Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: Removed streaming files Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: volumioStreaming STOPPED Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: SNAPSERVER STOPPED Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: SNAPCLIENT STOPPED Oct 25 21:39:43 volumioes9038pro volumio[1256]: error: Cannot start Volumio Streaming Daemon Oct 25 21:39:43 volumioes9038pro volumio[1256]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 25 21:39:43 volumioes9038pro volumio[1256]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Asound.conf file written Oct 25 21:39:43 volumioes9038pro sudo[1793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 25 21:39:43 volumioes9038pro sudo[1793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro sudo[1793]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:43 volumioes9038pro volumio[1256]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 25 21:39:43 volumioes9038pro volumio[1256]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Output device has changed, restarting MPD Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Output device has changed, restarting Shairport Sync Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:43 volumioes9038pro sudo[1799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 21:39:43 volumioes9038pro sudo[1799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro sudo[1801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 21:39:43 volumioes9038pro sudo[1799]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:43 volumioes9038pro sudo[1801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 25 21:39:43 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 25 21:39:43 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 25 21:39:43 volumioes9038pro systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 25 21:39:43 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 25 21:39:43 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 25 21:39:43 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output for this device Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding audio output: Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding audio output: Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding METAVOLUMIO REST API Endpoints Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Preparing CD Folders Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding CD REST API Endpoints Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Starting UDEV Watcher for CD Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: Detecting CD presence with UDEV Oct 25 21:39:43 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 25 21:39:43 volumioes9038pro bluetoothd[896]: Path / reserved for Adv Monitor app :1.18 Oct 25 21:39:43 volumioes9038pro bluetoothd[896]: Adv Monitor app :1.18 disconnected from D-Bus Oct 25 21:39:43 volumioes9038pro systemd[1]: mpd.service: Deactivated successfully. Oct 25 21:39:43 volumioes9038pro systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 25 21:39:43 volumioes9038pro systemd[1]: mpd.service: Consumed 1.207s CPU time. Oct 25 21:39:43 volumioes9038pro systemd[1]: mpd.socket: Deactivated successfully. Oct 25 21:39:43 volumioes9038pro systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 25 21:39:43 volumioes9038pro systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 25 21:39:43 volumioes9038pro systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 25 21:39:43 volumioes9038pro systemd[1]: Starting mpd.service - Music Player Daemon... Oct 25 21:39:43 volumioes9038pro sudo[1822]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 21:39:43 volumioes9038pro sudo[1822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 25 21:39:43 volumioes9038pro sudo[1822]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:44 volumioes9038pro mpd[1824]: 2025-10-25T21:39:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 21:39:44 volumioes9038pro systemd[1]: Started mpd.service - Music Player Daemon. Oct 25 21:39:44 volumioes9038pro sudo[1801]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumio[1256]: warn: [cd-plugin] cdspeedctl: device or media not ready Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Adding inputs REST Endpoints Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Scanning Audio Inputs Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Checking against Known Cards name Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Adding Server instance for streaming Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 25 21:39:48 volumioes9038pro volumio[1256]: error: Hi Res Audio Failed Login: Missing Login Data Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 25 21:39:48 volumioes9038pro sudo[1831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 21:39:48 volumioes9038pro sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 25 21:39:48 volumioes9038pro sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 21:39:48 volumioes9038pro sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Stopping AccessToken refresher cron Oct 25 21:39:48 volumioes9038pro sudo[1831]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: AccessToken refresher cron started Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Stopping AccessToken refresher cron for QOBUZ Oct 25 21:39:48 volumioes9038pro sudo[1838]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: AccessToken refresher cron started for QOBUZ Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Adding TIDAL REST API Endpoints Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Adding QOBUZ REST API Endpoints Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Updating MyVolumio device info Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:48 volumioes9038pro sudo[1841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Updating MyVolumio device info Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:48 volumioes9038pro sudo[1841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: MRS: Getting audio outputs on start Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: MRS: Requesting all other devices output Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Successfully Added MyVolumio device Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Successfully Added MyVolumio device Oct 25 21:39:48 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: MPD Permissions set Oct 25 21:39:48 volumioes9038pro sudo[1845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 25 21:39:48 volumioes9038pro sudo[1845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 25 21:39:48 volumioes9038pro sudo[1841]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:48 volumioes9038pro systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 25 21:39:48 volumioes9038pro sudo[1845]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumiobt[1856]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 25 21:39:48 volumioes9038pro sudo[1857]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 25 21:39:48 volumioes9038pro sudo[1857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro volumio[1256]: error: updateQueue error: null Oct 25 21:39:48 volumioes9038pro sudo[1857]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioStop Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreStateMachine::stop Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 21:39:48 volumioes9038pro volumio[1256]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 21:39:48 volumioes9038pro sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 25 21:39:48 volumioes9038pro sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro sudo[1861]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumiobt[1863]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 25 21:39:48 volumioes9038pro bluetoothd[896]: Adv Monitor app :1.19 disconnected from D-Bus Oct 25 21:39:48 volumioes9038pro volumiobt[1866]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Executing endpoint qc_getconfig Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [176B blob data] Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [157B blob data] Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [157B blob data] Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [157B blob data] Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [113B blob data] Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [bluetoothctl]> discoverable on Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [bluetoothctl]> pairable on Oct 25 21:39:48 volumioes9038pro bluetoothd[896]: Path / reserved for Adv Monitor app :1.20 Oct 25 21:39:48 volumioes9038pro bluetoothd[896]: Adv Monitor app :1.20 disconnected from D-Bus Oct 25 21:39:48 volumioes9038pro volumiobt[1867]: [bluetoothctl]> Oct 25 21:39:48 volumioes9038pro volumiobt[1871]: INFO [BTSTART] Registering Bluetooth agent... Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.696 [1843.1843] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Starting Shairport Sync Oct 25 21:39:48 volumioes9038pro volumiobt[1872]: [NEW] Media /org/bluez/hci0 Oct 25 21:39:48 volumioes9038pro volumiobt[1872]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 25 21:39:48 volumioes9038pro volumiobt[1872]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 25 21:39:48 volumioes9038pro volumiobt[1872]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 25 21:39:48 volumioes9038pro sudo[1874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 21:39:48 volumioes9038pro sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro bluetoothd[896]: Adv Monitor app :1.21 disconnected from D-Bus Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 25 21:39:48 volumioes9038pro systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 25 21:39:48 volumioes9038pro systemd[1]: shairport-sync.service: Deactivated successfully. Oct 25 21:39:48 volumioes9038pro systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 25 21:39:48 volumioes9038pro systemd[1]: shairport-sync.service: Consumed 1.526s CPU time. Oct 25 21:39:48 volumioes9038pro volumiobt[1876]: No agent is registered Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.730 [1843.1843] INFO VolumeManager: [0x16f9368]: Setting new playback volume: 75 Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.730 [1843.1843] INFO VolumeManager: [0x16f9368]: Setting new mute state: 0 Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.730 [1843.1843] INFO QobuzConnect: [0x16f9d38]: Client initialized! Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.730 [1843.1843] INFO SampleApp: Starting Avahi advertising, name: VolumioES9038Pro, service name: _qobuz-connect._tcp Oct 25 21:39:48 volumioes9038pro volumiobt[1876]: [NEW] Media /org/bluez/hci0 Oct 25 21:39:48 volumioes9038pro volumiobt[1876]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 25 21:39:48 volumioes9038pro volumiobt[1876]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 25 21:39:48 volumioes9038pro volumiobt[1876]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 25 21:39:48 volumioes9038pro sudo[1878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 25 21:39:48 volumioes9038pro sudo[1878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:48 volumioes9038pro bluetoothd[896]: Adv Monitor app :1.22 disconnected from D-Bus Oct 25 21:39:48 volumioes9038pro volumiobt[1881]: INFO [BTSTART] Agent registered successfully. Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.739 [1843.1843] INFO LocalConfigManager: [0x16f8c48]: Starting Local Configuration server Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.739 [1843.1843] INFO SampleApp: Starting Local configuration server Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.739 [1843.1843] INFO SampleApp: Connected to UNIX socket client 0x16e3818 Oct 25 21:39:48 volumioes9038pro volumiobt[1884]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 25 21:39:48 volumioes9038pro systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 25 21:39:48 volumioes9038pro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 25 21:39:48 volumioes9038pro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 25 21:39:48 volumioes9038pro sudo[1874]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Shairport-Sync Started Oct 25 21:39:48 volumioes9038pro systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Oct 25 21:39:48 volumioes9038pro sudo[1878]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Remote SSH Started Oct 25 21:39:48 volumioes9038pro autossh[1896]: port set to 0, monitoring disabled Oct 25 21:39:48 volumioes9038pro autossh[1896]: starting ssh (count 1) Oct 25 21:39:48 volumioes9038pro autossh[1896]: ssh child pid is 1903 Oct 25 21:39:48 volumioes9038pro volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:48 volumioes9038pro volumio[1256]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gpYJ4ZnQuEWTT2ByhbGrirkU8652.f8ce326471a5a6f9d21d457658dcf699.state.status' Oct 25 21:39:48 volumioes9038pro qobuz-connect[1843]: 20251025 21:39:48.846 [1843.1843] INFO SampleApp: Playback volume changed: 75 Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Successfully Updated MyVolumio device Oct 25 21:39:48 volumioes9038pro volumio[1256]: info: Successfully Updated MyVolumio device Oct 25 21:39:48 volumioes9038pro volumiossh-tunnel[1903]: Warning: Permanently added '[eu9.myvolumio.org]:2222' (RSA) to the list of known hosts. Oct 25 21:39:48 volumioes9038pro volumiobt[1885]: INFO:a2dp-agent:Connecting to system bus. Oct 25 21:39:48 volumioes9038pro volumiobt[1885]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Oct 25 21:39:48 volumioes9038pro volumiobt[1885]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Oct 25 21:39:48 volumioes9038pro volumiobt[1885]: INFO:a2dp-agent:Registering agent. Oct 25 21:39:48 volumioes9038pro volumiobt[1885]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Oct 25 21:39:48 volumioes9038pro volumiobt[1885]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Oct 25 21:39:50 volumioes9038pro upmpdcli[1919]: writing RSA key Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: TidalConnect service stoped! Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 25 21:39:51 volumioes9038pro sudo[1932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 25 21:39:51 volumioes9038pro sudo[1932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:51 volumioes9038pro sudo[1936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 25 21:39:51 volumioes9038pro sudo[1936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:39:51 volumioes9038pro systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 25 21:39:51 volumioes9038pro sudo[1932]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:51 volumioes9038pro systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 25 21:39:51 volumioes9038pro systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 25 21:39:51 volumioes9038pro systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 25 21:39:51 volumioes9038pro systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 25 21:39:51 volumioes9038pro mpd_monitor.sh[1940]: MPD Monitor Service: Starting MPD Monitor Service Oct 25 21:39:51 volumioes9038pro sudo[1936]: pam_unix(sudo:session): session closed for user root Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: Successfully started MPD Monitor Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: Executing endpoint tc_getconfig Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 25 21:39:51 volumioes9038pro vtcs[1938]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 25 21:39:51 volumioes9038pro vtcs[1938]: STARTED TidalConnect services. Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: Executing endpoint tc_connect Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: Connecting to TidalConnect Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::servicePushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output update for this device Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:51 volumioes9038pro volumio[1256]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 25 21:39:51 volumioes9038pro volumio[1256]: verbose: CURRENT POSITION 0 Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::syncState stateService stop Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output update for this device Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: No code Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output update for this device Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::servicePushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output update for this device Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CorePlayQueue::getTrack 0 Oct 25 21:39:51 volumioes9038pro volumio[1256]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 25 21:39:51 volumioes9038pro volumio[1256]: verbose: CURRENT POSITION 0 Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::syncState stateService stop Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::syncState currentStatus stop Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output update for this device Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: No code Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreStateMachine::pushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioPushState Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output update for this device Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: MRS: Pushing multiroomSync output Oct 25 21:39:51 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:51 volumioes9038pro volumio[1256]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gpYJ4ZnQuEWTT2ByhbGrirkU8652.f8ce326471a5a6f9d21d457658dcf699.state.status' Oct 25 21:39:51 volumioes9038pro volumio[1256]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gpYJ4ZnQuEWTT2ByhbGrirkU8652.f8ce326471a5a6f9d21d457658dcf699.state.status' Oct 25 21:39:51 volumioes9038pro volumio[1256]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gpYJ4ZnQuEWTT2ByhbGrirkU8652.f8ce326471a5a6f9d21d457658dcf699.state.status' Oct 25 21:39:51 volumioes9038pro volumio[1256]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gpYJ4ZnQuEWTT2ByhbGrirkU8652.f8ce326471a5a6f9d21d457658dcf699.state.status' Oct 25 21:39:53 volumioes9038pro systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 25 21:39:54 volumioes9038pro systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 25 21:39:54 volumioes9038pro volumio[1256]: info: TidalConnect service started! Oct 25 21:39:54 volumioes9038pro volumio[1256]: [Metrics] CommandRouter: 25s 491.48ms Oct 25 21:39:54 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumiosetStartupVolume Oct 25 21:39:54 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:39:54 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:39:54 volumioes9038pro volumio[1256]: info: CoreCommandRouter::Close All Modals sent Oct 25 21:39:54 volumioes9038pro volumio[1256]: info: CoreCommandRouter::Close All Modals sent Oct 25 21:39:55 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 25 21:39:55 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 21:39:55 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 25 21:39:55 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 21:39:55 volumioes9038pro volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 25 21:39:55 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:58 volumioes9038pro volumio[1256]: verbose: New Socket.io Connection to 192.168.88.116 from 192.168.88.110 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: Received Get System Info Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: Discovery: Getting this device information Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetState Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: Listing playlists Oct 25 21:39:58 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::volumioGetBrowseSources Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 21:40:00 volumioes9038pro volumio-remote-updater[911]: Test mode disabled Oct 25 21:40:00 volumioes9038pro volumio-remote-updater[911]: Alpha mode disabled Oct 25 21:40:00 volumioes9038pro volumio-remote-updater[911]: Alpha legacy test mode disabled Oct 25 21:40:00 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 25 21:40:01 volumioes9038pro volumio[1256]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 25 21:40:01 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 25 21:40:01 volumioes9038pro volumio[1256]: info: BOOT COMPLETED Oct 25 21:40:02 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 21:40:02 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: Enabling plugin touch_display Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: Loading plugin "touch_display"... Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: Applying required configuration parameters for plugin touch_display Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: PLUGIN START: touch_display Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: Loading i18n strings for locale pl Oct 25 21:40:05 volumioes9038pro volumio[1256]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 21:40:05 volumioes9038pro sudo[1988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: touch_display: No backlight interface detected. Oct 25 21:40:05 volumioes9038pro sudo[1988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:05 volumioes9038pro sudo[1990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 25 21:40:05 volumioes9038pro sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:05 volumioes9038pro sudo[1993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 25 21:40:05 volumioes9038pro sudo[1993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 25 21:40:05 volumioes9038pro sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 25 21:40:05 volumioes9038pro sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:05 volumioes9038pro sudo[1997]: pam_unix(sudo:session): session closed for user root Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 25 21:40:05 volumioes9038pro systemd[1]: Reloading. Oct 25 21:40:05 volumioes9038pro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 25 21:40:05 volumioes9038pro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 25 21:40:05 volumioes9038pro sudo[1993]: pam_unix(sudo:session): session closed for user root Oct 25 21:40:05 volumioes9038pro systemd[1]: Reloading. Oct 25 21:40:05 volumioes9038pro volumio[1256]: info: touch_display: systemctl daemon-reload succeeded. Oct 25 21:40:05 volumioes9038pro sudo[2020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 25 21:40:05 volumioes9038pro sudo[2020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:05 volumioes9038pro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 25 21:40:05 volumioes9038pro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 25 21:40:06 volumioes9038pro sudo[1990]: pam_unix(sudo:session): session closed for user root Oct 25 21:40:06 volumioes9038pro volumio[1256]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 25 21:40:06 volumioes9038pro sudo[2020]: pam_unix(sudo:session): session closed for user root Oct 25 21:40:06 volumioes9038pro volumio[1256]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Oct 25 21:40:06 volumioes9038pro volumio[1256]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Oct 25 21:40:06 volumioes9038pro volumio[1256]: info: Error: Error Oct 25 21:40:06 volumioes9038pro sudo[1988]: pam_unix(sudo:session): session closed for user root Oct 25 21:40:06 volumioes9038pro volumio[1256]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 25 21:40:14 volumioes9038pro volumio[1256]: info: Starting Uninstall of plugin user_interface - touch_display Oct 25 21:40:14 volumioes9038pro volumio[1256]: info: Uninstalling plugin touch_display Oct 25 21:40:14 volumioes9038pro sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 25 21:40:14 volumioes9038pro sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:14 volumioes9038pro sudo[2059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Oct 25 21:40:14 volumioes9038pro sudo[2057]: pam_unix(sudo:session): session closed for user root Oct 25 21:40:14 volumioes9038pro sudo[2059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:14 volumioes9038pro volumio[1256]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 25 21:40:14 volumioes9038pro volumio[1256]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 21:40:14 volumioes9038pro volumio[1256]: TypeError: Cannot read properties of undefined (reading 'split') Oct 25 21:40:14 volumioes9038pro volumio[1256]: at /data/plugins/user_interface/touch_display/index.js:1255:37 Oct 25 21:40:14 volumioes9038pro volumio[1256]: at ChildProcess.exithandler (node:child_process:413:7) Oct 25 21:40:14 volumioes9038pro volumio[1256]: at ChildProcess.emit (node:events:514:28) Oct 25 21:40:14 volumioes9038pro volumio[1256]: at maybeClose (node:internal/child_process:1105:16) Oct 25 21:40:14 volumioes9038pro volumio[1256]: at Socket. (node:internal/child_process:457:11) Oct 25 21:40:14 volumioes9038pro volumio[1256]: at Socket.emit (node:events:514:28) Oct 25 21:40:14 volumioes9038pro volumio[1256]: at Pipe. (node:net:337:12) Oct 25 21:40:14 volumioes9038pro volumio[1256]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 21:40:14 volumioes9038pro sudo[2063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Oct 25 21:40:14 volumioes9038pro sudo[2063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:14 volumioes9038pro sudo[2065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Oct 25 21:40:14 volumioes9038pro sudo[2065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 25 21:40:14 volumioes9038pro systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 25 21:40:14 volumioes9038pro sudo[2065]: pam_unix(sudo:session): session closed for user root Oct 25 21:40:14 volumioes9038pro systemd[1]: Reloading. Oct 25 21:40:14 volumioes9038pro sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-25 21:39' Oct 25 21:40:14 volumioes9038pro sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"