May 30 20:22:00 volumio systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). May 30 20:22:00 volumio systemd[1]: Starting wireless.service - Wireless Services... May 30 20:22:00 volumio systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. May 30 20:22:00 volumio kernel: Bluetooth: hci0: Intel BT fw patch 0x32 completed & activated May 30 20:22:00 volumio avahi-daemon[880]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 30 20:22:00 volumio avahi-daemon[880]: Successfully dropped root privileges. May 30 20:22:00 volumio avahi-daemon[880]: avahi-daemon 0.8 starting up. May 30 20:22:00 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. May 30 20:22:00 volumio systemd[1]: Started rpcbind.service - RPC bind portmap service. May 30 20:22:00 volumio systemd[1]: e2scrub_reap.service: Deactivated successfully. May 30 20:22:00 volumio systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. May 30 20:22:00 volumio systemd[1]: Reached target rpc_pipefs.target. May 30 20:22:00 volumio systemd[1]: Reached target rpcbind.target - RPC Port Mapper. May 30 20:22:00 volumio alsactl[911]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 30 20:22:00 volumio dhcpcd[887]: dhcpcd-9.4.1 starting May 30 20:22:00 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... May 30 20:22:00 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 30 20:22:00 volumio 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). May 30 20:22:00 volumio systemd[1]: Reached target nfs-client.target - NFS client services. May 30 20:22:00 volumio systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 30 20:22:00 volumio systemd[1]: Reached target remote-fs.target - Remote File Systems. May 30 20:22:00 volumio systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... May 30 20:22:00 volumio 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). May 30 20:22:00 volumio dhcpcd[915]: dev: loaded udev May 30 20:22:00 volumio systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... May 30 20:22:00 volumio dhcpcd[915]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 May 30 20:22:00 volumio systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. May 30 20:22:00 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... May 30 20:22:00 volumio systemd[1]: Reached target sound.target - Sound Card. May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event0 to thd using --add... May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event1 to thd using --add... May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event10 to thd using --add... May 30 20:22:00 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - Wireless 3165. May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event11 to thd using --add... May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event12 to thd using --add... May 30 20:22:00 volumio systemd-logind[892]: New seat seat0. May 30 20:22:00 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. May 30 20:22:00 volumio haveged[864]: haveged: ver: 1.9.14; arch: x86; vend: GenuineIntel; build: (gcc 12.2.0 ITV); collect: 128K May 30 20:22:00 volumio haveged[864]: haveged: cpu: (L4 VC); data: 24K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 32010/53875 May 30 20:22:00 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 30 20:22:00 volumio haveged[864]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0022 May 30 20:22:00 volumio haveged[864]: haveged: fills: 0, generated: 0 May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event2 to thd using --add... May 30 20:22:00 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event3 to thd using --add... May 30 20:22:00 volumio systemd-logind[892]: Watching system buttons on /dev/input/event0 (Lid Switch) May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event4 to thd using --add... May 30 20:22:00 volumio systemd-logind[892]: Watching system buttons on /dev/input/event1 (Power Button) May 30 20:22:00 volumio systemd-logind[892]: Watching system buttons on /dev/input/event11 (Intel Virtual Buttons) May 30 20:22:00 volumio systemd-logind[892]: Watching system buttons on /dev/input/event9 (Intel HID events) May 30 20:22:00 volumio systemd-logind[892]: Watching system buttons on /dev/input/event10 (Intel HID 5 button array) May 30 20:22:00 volumio systemd-logind[892]: Watching system buttons on /dev/input/event2 (AT Translated Set 2 keyboard) May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event5 to thd using --add... May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event6 to thd using --add... May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event7 to thd using --add... May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event8 to thd using --add... May 30 20:22:00 volumio th-udev-rebind.sh[920]: Rebinding /dev/input/event9 to thd using --add... May 30 20:22:00 volumio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. May 30 20:22:00 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 May 30 20:22:00 volumio systemd[1]: th-udev-rebind.service: Deactivated successfully. May 30 20:22:00 volumio systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. May 30 20:22:00 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 30 20:22:00 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 30 20:22:00 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring May 30 20:22:00 volumio bluetoothd[912]: Bluetooth daemon 5.83 May 30 20:22:00 volumio systemd[1]: Finished networking.service - Raise network interfaces. May 30 20:22:00 volumio sh[1040]: wlan0=wlan0 May 30 20:22:00 volumio systemd[1]: Started dbus.service - D-Bus System Message Bus. May 30 20:22:00 volumio dhcpcd[915]: wlan0: waiting for carrier May 30 20:22:00 volumio dhcpcd[915]: wlan0: carrier acquired May 30 20:22:00 volumio avahi-daemon[880]: Successfully called chroot(). May 30 20:22:00 volumio avahi-daemon[880]: Successfully dropped remaining capabilities. May 30 20:22:00 volumio dhcpcd[915]: wlan0: IAID 67:b5:09:0f May 30 20:22:00 volumio dhcpcd[915]: wlan0: adding address fe80::3c1d:9206:7572:e75c May 30 20:22:00 volumio avahi-daemon[880]: No service file found in /etc/avahi/services. May 30 20:22:00 volumio dhcpcd[915]: ipv6_addaddr1: Permission denied May 30 20:22:00 volumio avahi-daemon[880]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 30 20:22:00 volumio systemd[1]: Starting wpa_supplicant.service - WPA supplicant... May 30 20:22:00 volumio avahi-daemon[880]: New relevant interface lo.IPv4 for mDNS. May 30 20:22:00 volumio avahi-daemon[880]: Network interface enumeration completed. May 30 20:22:00 volumio avahi-daemon[880]: Registering new address record for 127.0.0.1 on lo.IPv4. May 30 20:22:00 volumio bluetoothd[912]: Starting SDP server May 30 20:22:00 volumio bluetoothd[912]: Excluding (cli) sap May 30 20:22:00 volumio bluetoothd[912]: Excluding (cli) scanparam May 30 20:22:00 volumio bluetoothd[912]: Excluding (cli) midi May 30 20:22:00 volumio bluetoothd[912]: Excluding (cli) battery May 30 20:22:00 volumio dbus-daemon[884]: [system] Successfully activated service 'org.freedesktop.systemd1' May 30 20:22:00 volumio systemd[1]: Started systemd-logind.service - User Login Management. May 30 20:22:00 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 30 20:22:00 volumio systemd[1]: iptables.service: Deactivated successfully. May 30 20:22:00 volumio dhcpcd[915]: wlan0: IAID 67:b5:09:0f May 30 20:22:00 volumio dhcpcd[915]: wlan0: carrier lost May 30 20:22:00 volumio systemd[1]: Started bluetooth.service - Bluetooth service. May 30 20:22:00 volumio systemd[1]: Starting bluealsa.service - BlueALSA service... May 30 20:22:00 volumio loadcpufreq[919]: Loading cpufreq kernel modules...done (acpi-cpufreq). May 30 20:22:00 volumio systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. May 30 20:22:00 volumio systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... May 30 20:22:01 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 30 20:22:01 volumio kernel: Bluetooth: BNEP filters: protocol multicast May 30 20:22:01 volumio kernel: Bluetooth: BNEP socket layer initialized May 30 20:22:01 volumio dbus-daemon[884]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=912 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 30 20:22:01 volumio bluetoothd[912]: Bluetooth management interface 1.23 initialized May 30 20:22:01 volumio bluetoothd[912]: Battery Provider Manager created May 30 20:22:01 volumio kernel: Bluetooth: MGMT ver 1.23 May 30 20:22:01 volumio kernel: NET: Registered PF_ALG protocol family May 30 20:22:01 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 30 20:22:01 volumio cpufrequtils[1079]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. May 30 20:22:01 volumio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. May 30 20:22:01 volumio dbus-daemon[884]: [system] Successfully activated service 'org.freedesktop.hostname1' May 30 20:22:01 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 30 20:22:01 volumio wpa_supplicant[1055]: Successfully initialized wpa_supplicant May 30 20:22:01 volumio systemd[1]: Started wpa_supplicant.service - WPA supplicant. May 30 20:22:01 volumio systemd[1]: Reached target network.target - Network. May 30 20:22:01 volumio systemd[1]: Reached target network-online.target - Network is Online. May 30 20:22:01 volumio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... May 30 20:22:01 volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... May 30 20:22:01 volumio systemd[1]: Starting ntpsec.service - Network Time Service... May 30 20:22:01 volumio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... May 30 20:22:01 volumio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... May 30 20:22:01 volumio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... May 30 20:22:01 volumio bluetoothd[912]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled May 30 20:22:01 volumio bluetoothd[912]: bap: Operation not supported (95) May 30 20:22:01 volumio sm-notify[1102]: Version 2.6.2 starting May 30 20:22:01 volumio volumio-remote-updater[899]: Volumio OTA Client: 1.7.0 May 30 20:22:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 30 20:22:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:22:01 volumio volumio-remote-updater[899]: Error: No active session May 30 20:22:01 volumio volumio-remote-updater[899]: [2026-05-30 20:22:01] [info] asio async_connect error: asio.system:111 (Connection refused) May 30 20:22:01 volumio volumio-remote-updater[899]: [2026-05-30 20:22:01] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 30 20:22:01 volumio volumio-remote-updater[899]: [2026-05-30 20:22:01] [error] handle_connect error: Connection refused May 30 20:22:01 volumio avahi-daemon[880]: Server startup complete. Host name is volumio.local. Local service cookie is 529893821. May 30 20:22:01 volumio bluetoothd[912]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 May 30 20:22:01 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... May 30 20:22:01 volumio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 30 20:22:01 volumio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. May 30 20:22:01 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 30 20:22:01 volumio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. May 30 20:22:01 volumio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. May 30 20:22:01 volumio kernel: Bluetooth: hci1: command 0xfc45 tx timeout May 30 20:22:01 volumio kernel: Bluetooth: hci1: BCM: failed to write clock (-110) May 30 20:22:01 volumio kernel: Bluetooth: hci1: Failed to set baudrate May 30 20:22:03 volumio kernel: Bluetooth: hci1: command 0xfc45 tx timeout May 30 20:22:03 volumio kernel: Bluetooth: hci1: BCM: Reset failed (-110) May 30 20:22:04 volumio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 30 20:22:04 volumio ifplugd[1097]: Network Interface Plugging Daemon...skip eth0...done. May 30 20:22:04 volumio ntpd[1109]: INIT: ntpd ntpsec-1.2.2: Starting May 30 20:22:04 volumio ntp-systemd-wrapper[1109]: 2026-05-30T20:22:04 ntpd[1109]: INIT: ntpd ntpsec-1.2.2: Starting May 30 20:22:04 volumio ntp-systemd-wrapper[1109]: 2026-05-30T20:22:04 ntpd[1109]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec May 30 20:22:04 volumio ntpd[1109]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec May 30 20:22:04 volumio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... May 30 20:22:04 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... May 30 20:22:04 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. May 30 20:22:04 volumio systemd[1]: Received SIGRTMIN+21 from PID 443 (plymouthd). May 30 20:22:04 volumio systemd[1]: Started ntpsec.service - Network Time Service. May 30 20:22:04 volumio welcome[1126]: Resolved ip:[0] May 30 20:22:04 volumio systemd[1]: Received SIGRTMIN+21 from PID 443 (plymouthd). May 30 20:22:04 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. May 30 20:22:04 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. May 30 20:22:04 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. May 30 20:22:04 volumio systemd[1]: Reached target getty.target - Login Prompts. May 30 20:22:04 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. May 30 20:22:04 volumio upmpdcli[895]: Could not open config: /tmp/upmpdcli.conf May 30 20:22:04 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 20:22:04 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 20:22:04 volumio ntpd[1134]: INIT: precision = 0.136 usec (-23) May 30 20:22:05 volumio ntpd[1134]: INIT: successfully locked into RAM May 30 20:22:05 volumio ntpd[1134]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf May 30 20:22:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 30 20:22:05 volumio ntpd[1134]: CONFIG: restrict nopeer ignored May 30 20:22:05 volumio ntpd[1134]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 30 20:22:05 volumio ntpd[1134]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 May 30 20:22:05 volumio ntpd[1134]: INIT: Using SO_TIMESTAMPNS(ns) May 30 20:22:05 volumio ntpd[1134]: IO: Listen and drop on 0 v6wildcard [::]:123 May 30 20:22:05 volumio ntpd[1134]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 30 20:22:05 volumio ntpd[1134]: IO: Listen normally on 2 lo 127.0.0.1:123 May 30 20:22:05 volumio ntpd[1134]: IO: Listening on routing socket on fd #19 for interface updates May 30 20:22:05 volumio sshd[1145]: Server listening on 0.0.0.0 port 22. May 30 20:22:05 volumio sshd[1145]: Server listening on :: port 22. May 30 20:22:05 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. May 30 20:22:05 volumio ntpd[1134]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes May 30 20:22:05 volumio ntpd[1134]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 May 30 20:22:05 volumio ntpd[1134]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120 May 30 20:22:05 volumio ntpd[1134]: NTSc: Using system default root certificates. May 30 20:22:05 volumio ntpd[1134]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory May 30 20:22:05 volumio bluealsa[1075]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa May 30 20:22:05 volumio bluealsa[1075]: main.c:663: Starting main dispatching loop May 30 20:22:05 volumio bluealsa[1075]: main.c:118: Acquired D-Bus service name: org.bluealsa May 30 20:22:05 volumio bluealsa[1075]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa May 30 20:22:05 volumio systemd[1]: Started bluealsa.service - BlueALSA service. May 30 20:22:05 volumio systemd[1]: Reached target bluetooth.target - Bluetooth Support. May 30 20:22:05 volumio bluealsa[1075]: bluez.c:153: Registering media application: /org/bluez/hci0 May 30 20:22:05 volumio bluealsa[1075]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 May 30 20:22:05 volumio bluealsa[1075]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 May 30 20:22:05 volumio bluealsa[1075]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 May 30 20:22:05 volumio bluealsa[1075]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 May 30 20:22:05 volumio bluetoothd[912]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 May 30 20:22:05 volumio bluetoothd[912]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 May 30 20:22:05 volumio bluetoothd[912]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 May 30 20:22:05 volumio bluetoothd[912]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 May 30 20:22:06 volumio ntpd[1134]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:06 volumio ntpd[1134]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 30 20:22:06 volumio ntpd[1134]: DNS: dns_check: DNS error: -11, System error May 30 20:22:06 volumio ntpd[1134]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 May 30 20:22:06 volumio volumio-remote-updater[899]: [2026-05-30 20:22:06] [info] asio async_connect error: asio.system:111 (Connection refused) May 30 20:22:06 volumio volumio-remote-updater[899]: [2026-05-30 20:22:06] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 30 20:22:06 volumio volumio-remote-updater[899]: [2026-05-30 20:22:06] [error] handle_connect error: Connection refused May 30 20:22:07 volumio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. May 30 20:22:07 volumio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. May 30 20:22:07 volumio ntpd[1134]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:07 volumio ntpd[1134]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 30 20:22:07 volumio ntpd[1134]: DNS: dns_check: DNS error: -11, System error May 30 20:22:07 volumio ntpd[1134]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 May 30 20:22:07 volumio nmbd[1179]: [2026/05/30 20:22:07.436850, 0] ../../source3/nmbd/nmbd.c:901(main) May 30 20:22:07 volumio nmbd[1179]: nmbd version 4.17.12-Debian started. May 30 20:22:07 volumio nmbd[1179]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 30 20:22:07 volumio nmbd[1179]: [2026/05/30 20:22:07.452458, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) May 30 20:22:07 volumio nmbd[1179]: started asyncdns process 1183 May 30 20:22:07 volumio nmbd[1179]: [2026/05/30 20:22:07.453161, 0] ../../lib/util/become_daemon.c:150(daemon_status) May 30 20:22:07 volumio nmbd[1179]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 30 20:22:07 volumio nmbd[1179]: [2026/05/30 20:22:07.453208, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) May 30 20:22:07 volumio nmbd[1179]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 30 20:22:07 volumio wireless.js[902]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 30 20:22:07 volumio wireless.js[902]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 30 20:22:08 volumio sudo[1202]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 30 20:22:08 volumio sudo[1202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:08 volumio sudo[1202]: pam_unix(sudo:session): session closed for user root May 30 20:22:08 volumio sudo[1204]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 30 20:22:08 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:08 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root May 30 20:22:08 volumio wireless.js[902]: WIRELESS.JS - INFO: Cleaning previous... May 30 20:22:08 volumio sudo[1207]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 30 20:22:08 volumio sudo[1207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:08 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 30 20:22:08 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 30 20:22:08 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring May 30 20:22:08 volumio sudo[1207]: pam_unix(sudo:session): session closed for user root May 30 20:22:08 volumio wireless.js[902]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 30 20:22:08 volumio wireless.js[902]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms May 30 20:22:08 volumio wireless.js[902]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 5c:5f:67:b5:09:0f) May 30 20:22:08 volumio sudo[1214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 30 20:22:08 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:08 volumio sudo[1214]: pam_unix(sudo:session): session closed for user root May 30 20:22:08 volumio sudo[1222]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 30 20:22:08 volumio sudo[1222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:08 volumio ntpd[1134]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:08 volumio ntpd[1134]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 30 20:22:08 volumio ntpd[1134]: DNS: dns_check: DNS error: -11, System error May 30 20:22:08 volumio ntpd[1134]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 May 30 20:22:09 volumio ntpd[1134]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:09 volumio ntpd[1134]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 30 20:22:09 volumio ntpd[1134]: DNS: dns_check: DNS error: -11, System error May 30 20:22:09 volumio ntpd[1134]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 May 30 20:22:11 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root May 30 20:22:11 volumio wireless.js[902]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE May 30 20:22:11 volumio sudo[1248]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE May 30 20:22:11 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:11 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root May 30 20:22:11 volumio wireless.js[902]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE May 30 20:22:11 volumio wireless.js[902]: WIRELESS.JS - INFO: Start wireless flow May 30 20:22:11 volumio wireless.js[902]: WIRELESS.JS - INFO: Stopped hotspot (if there).. May 30 20:22:11 volumio sudo[1262]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 30 20:22:11 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:11 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root May 30 20:22:11 volumio sudo[1264]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 30 20:22:11 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:11 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root May 30 20:22:11 volumio wireless.js[902]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 30 20:22:11 volumio wireless.js[902]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: 5c:5f:67:b5:09:0f, USB: false) May 30 20:22:11 volumio wpa_supplicant[1270]: Successfully initialized wpa_supplicant May 30 20:22:11 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 30 20:22:11 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 30 20:22:11 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring May 30 20:22:11 volumio wpa_supplicant[1273]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=WORLD May 30 20:22:11 volumio sudo[1276]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 30 20:22:11 volumio sudo[1276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:11 volumio sudo[1276]: pam_unix(sudo:session): session closed for user root May 30 20:22:12 volumio bash[1112]: setdatetime-helper: all HTTPS Date fallbacks failed May 30 20:22:12 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 30 20:22:12 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 30 20:22:13 volumio wireless.js[902]: WIRELESS.JS - INFO: DHCP IP fallback May 30 20:22:13 volumio wireless.js[902]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor May 30 20:22:13 volumio wireless.js[902]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 May 30 20:22:13 volumio wireless.js[902]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) May 30 20:22:14 volumio volumio-remote-updater[899]: [2026-05-30 20:22:14] [info] asio async_connect error: asio.system:111 (Connection refused) May 30 20:22:14 volumio volumio-remote-updater[899]: [2026-05-30 20:22:14] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 30 20:22:14 volumio volumio-remote-updater[899]: [2026-05-30 20:22:14] [error] handle_connect error: Connection refused May 30 20:22:15 volumio wpa_supplicant[1273]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE May 30 20:22:15 volumio wpa_supplicant[1273]: wlan0: SME: Trying to authenticate with 9c:9d:7e:95:7f:e6 (SSID='Xiaomi_D54C' freq=2412 MHz) May 30 20:22:15 volumio kernel: wlan0: authenticate with 9c:9d:7e:95:7f:e6 (local address=5c:5f:67:b5:09:0f) May 30 20:22:15 volumio kernel: wlan0: send auth to 9c:9d:7e:95:7f:e6 (try 1/3) May 30 20:22:15 volumio wpa_supplicant[1273]: wlan0: Trying to associate with 9c:9d:7e:95:7f:e6 (SSID='Xiaomi_D54C' freq=2412 MHz) May 30 20:22:15 volumio kernel: wlan0: authenticated May 30 20:22:15 volumio kernel: wlan0: associate with 9c:9d:7e:95:7f:e6 (try 1/3) May 30 20:22:15 volumio kernel: wlan0: RX AssocResp from 9c:9d:7e:95:7f:e6 (capab=0x431 status=0 aid=1) May 30 20:22:15 volumio wpa_supplicant[1273]: wlan0: Associated with 9c:9d:7e:95:7f:e6 May 30 20:22:15 volumio wpa_supplicant[1273]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 30 20:22:15 volumio kernel: wlan0: associated May 30 20:22:15 volumio wpa_supplicant[1273]: wlan0: WPA: Key negotiation completed with 9c:9d:7e:95:7f:e6 [PTK=CCMP GTK=TKIP] May 30 20:22:15 volumio wpa_supplicant[1273]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:9d:7e:95:7f:e6 completed [id=0 id_str=] May 30 20:22:15 volumio dhcpcd[915]: wlan0: carrier acquired May 30 20:22:15 volumio dhcpcd[915]: wlan0: connected to Access Point: Xiaomi_D54C May 30 20:22:15 volumio wireless.js[902]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 2112ms) May 30 20:22:15 volumio wireless.js[902]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful May 30 20:22:15 volumio wireless.js[902]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 9c:9d:7e:95:7f:e6 May 30 20:22:15 volumio dhcpcd[915]: wlan0: IAID 67:b5:09:0f May 30 20:22:15 volumio wireless.js[902]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow May 30 20:22:15 volumio dhcpcd[915]: wlan0: soliciting an IPv6 router May 30 20:22:16 volumio dhcpcd[915]: wlan0: soliciting a DHCP lease May 30 20:22:16 volumio sudo[1320]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 May 30 20:22:16 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:16 volumio dhcpcd[915]: control command: /sbin/dhcpcd wlan0 May 30 20:22:16 volumio dhcpcd[915]: control_free: No such file or directory May 30 20:22:16 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root May 30 20:22:17 volumio dhcpcd[915]: wlan0: offered 192.168.31.128 from 192.168.31.1 May 30 20:22:17 volumio dhcpcd[915]: wlan0: probing address 192.168.31.128/24 May 30 20:22:18 volumio wireless.js[902]: WIRELESS.JS - INFO: Start ap May 30 20:22:18 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 30 20:22:18 volumio wireless.js[902]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 30 20:22:18 volumio systemd[1]: Started wireless.service - Wireless Services. May 30 20:22:18 volumio systemd[1]: Started volumio.service - Volumio Backend Module. May 30 20:22:18 volumio systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. May 30 20:22:18 volumio systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. May 30 20:22:18 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. May 30 20:22:18 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. May 30 20:22:18 volumio volumio-cpu-tweak[1345]: Setting RT Priority for mpd May 30 20:22:18 volumio volumio-cpu-tweak[1387]: pid 35's current scheduling policy: SCHED_OTHER May 30 20:22:18 volumio volumio-cpu-tweak[1387]: pid 35's current scheduling priority: 0 May 30 20:22:18 volumio volumio-cpu-tweak[1345]: Not enough cores to set MPD affinity May 30 20:22:18 volumio volumio-cpu-tweak[1345]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 30 20:22:18 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. May 30 20:22:19 volumio soundcard-init.sh[1421]: Card 0 Chip Realtek Generic Name HDA Intel PCH May 30 20:22:19 volumio soundcard-init.sh[1435]: Simple mixer control 'Master',0 May 30 20:22:19 volumio soundcard-init.sh[1435]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined May 30 20:22:19 volumio soundcard-init.sh[1435]: Playback channels: Mono May 30 20:22:19 volumio soundcard-init.sh[1435]: Limits: Playback 0 - 87 May 30 20:22:19 volumio soundcard-init.sh[1435]: Mono: Playback 65 [75%] [-16.50dB] [on] May 30 20:22:19 volumio startx[1411]: X.Org X Server 1.21.1.7 May 30 20:22:19 volumio startx[1411]: X Protocol Version 11, Revision 0 May 30 20:22:19 volumio startx[1411]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 May 30 20:22:19 volumio startx[1411]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=8888267d-f4db-4def-b1e2-645e65f1dead bootpart=UUID=AE40-441F datapart=UUID=bbb74b93-791f-487d-ab2c-540715e4dc1d hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no plymouth=0 video=efifb fbcon=rotate:0 May 30 20:22:19 volumio startx[1411]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support) May 30 20:22:19 volumio startx[1411]: Current version of pixman: 0.42.2 May 30 20:22:19 volumio startx[1411]: Before reporting problems, check http://wiki.x.org May 30 20:22:19 volumio startx[1411]: to make sure that you have the latest version. May 30 20:22:19 volumio startx[1411]: Markers: (--) probed, (**) from config file, (==) default setting, May 30 20:22:19 volumio startx[1411]: (++) from command line, (!!) notice, (II) informational, May 30 20:22:19 volumio startx[1411]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 30 20:22:19 volumio startx[1411]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat May 30 20:22:19 2026 May 30 20:22:19 volumio startx[1411]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 30 20:22:19 volumio soundcard-init.sh[1421]: Card 1 Chip Loopback Mixer Name Loopback May 30 20:22:19 volumio systemd[1]: soundcard-init.service: Deactivated successfully. May 30 20:22:19 volumio wireless.js[902]: WIRELESS.JS - INFO: trying... May 30 20:22:19 volumio sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 20:22:19 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:19 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root May 30 20:22:19 volumio sudo[1465]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:22:19 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:19 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root May 30 20:22:19 volumio wireless.js[902]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 30 20:22:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 30 20:22:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:22:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:22:20 volumio upmpdcli[1467]: Could not open config: /tmp/upmpdcli.conf May 30 20:22:20 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 20:22:20 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 20:22:20 volumio wireless.js[902]: WIRELESS.JS - INFO: trying... May 30 20:22:20 volumio sudo[1483]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 20:22:20 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:20 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root May 30 20:22:20 volumio sudo[1486]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:22:20 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:20 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root May 30 20:22:20 volumio wireless.js[902]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 30 20:22:21 volumio wireless.js[902]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet May 30 20:22:21 volumio wireless.js[902]: WIRELESS.JS - INFO: trying... May 30 20:22:21 volumio sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 20:22:21 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:21 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root May 30 20:22:21 volumio sudo[1513]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:22:21 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:21 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root May 30 20:22:21 volumio wireless.js[902]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 30 20:22:22 volumio volumio[1332]: info: ------------------------------------------- May 30 20:22:22 volumio volumio[1332]: info: ----- Volumio3 ---- May 30 20:22:22 volumio volumio[1332]: info: ------------------------------------------- May 30 20:22:22 volumio volumio[1332]: info: ----- System startup ---- May 30 20:22:22 volumio volumio[1332]: info: ------------------------------------------- May 30 20:22:22 volumio dhcpcd[915]: wlan0: leased 192.168.31.128 for 43200 seconds May 30 20:22:22 volumio dhcpcd[915]: wlan0: adding route to 192.168.31.0/24 May 30 20:22:22 volumio avahi-daemon[880]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.128. May 30 20:22:22 volumio avahi-daemon[880]: New relevant interface wlan0.IPv4 for mDNS. May 30 20:22:22 volumio avahi-daemon[880]: Registering new address record for 192.168.31.128 on wlan0.IPv4. May 30 20:22:22 volumio systemd[1]: welcome.service: Deactivated successfully. May 30 20:22:22 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 30 20:22:22 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 30 20:22:22 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 30 20:22:22 volumio welcome[1542]: Resolved ip:[1] 192.168.31.128 May 30 20:22:22 volumio dhcpcd[915]: wlan0: adding default route via 192.168.31.1 May 30 20:22:22 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 30 20:22:22 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 30 20:22:22 volumio wireless.js[902]: WIRELESS.JS - INFO: trying... May 30 20:22:22 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. May 30 20:22:22 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 30 20:22:22 volumio sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 20:22:22 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:22 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root May 30 20:22:22 volumio sudo[1575]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:22:22 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:22 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root May 30 20:22:22 volumio wireless.js[902]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.31.128, ipV6 is undefined May 30 20:22:22 volumio wireless.js[902]: WIRELESS.JS - INFO: Connected to SSID: Xiaomi_D54C May 30 20:22:22 volumio wireless.js[902]: WIRELESS.JS - INFO: It's done! AP May 30 20:22:22 volumio wireless.js[902]: WIRELESS.JS - INFO: Restarting avahi-daemon... May 30 20:22:22 volumio sudo[1593]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon May 30 20:22:22 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:22:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 20:22:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 20:22:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:22:22 volumio systemd[1]: shairport-sync.service: Consumed 1.692s CPU time. May 30 20:22:22 volumio avahi-daemon[880]: Got SIGTERM, quitting. May 30 20:22:22 volumio avahi-daemon[880]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.128. May 30 20:22:22 volumio avahi-daemon[880]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 30 20:22:22 volumio avahi-daemon[880]: avahi-daemon 0.8 exiting. May 30 20:22:22 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 30 20:22:22 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. May 30 20:22:22 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 30 20:22:23 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 30 20:22:23 volumio avahi-daemon[1599]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 30 20:22:23 volumio avahi-daemon[1599]: Successfully dropped root privileges. May 30 20:22:23 volumio avahi-daemon[1599]: avahi-daemon 0.8 starting up. May 30 20:22:23 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 30 20:22:23 volumio avahi-daemon[1599]: Successfully called chroot(). May 30 20:22:23 volumio avahi-daemon[1599]: Successfully dropped remaining capabilities. May 30 20:22:23 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root May 30 20:22:23 volumio avahi-daemon[1599]: No service file found in /etc/avahi/services. May 30 20:22:23 volumio avahi-daemon[1599]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.128. May 30 20:22:23 volumio avahi-daemon[1599]: New relevant interface wlan0.IPv4 for mDNS. May 30 20:22:23 volumio avahi-daemon[1599]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 30 20:22:23 volumio avahi-daemon[1599]: New relevant interface lo.IPv4 for mDNS. May 30 20:22:23 volumio avahi-daemon[1599]: Network interface enumeration completed. May 30 20:22:23 volumio avahi-daemon[1599]: Registering new address record for 192.168.31.128 on wlan0.IPv4. May 30 20:22:23 volumio avahi-daemon[1599]: Registering new address record for 127.0.0.1 on lo.IPv4. May 30 20:22:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:22:23 volumio wireless.js[902]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 30 20:22:23 volumio winbindd[1595]: [2026/05/30 20:22:23.530318, 0] ../../source3/winbindd/winbindd.c:1440(main) May 30 20:22:23 volumio winbindd[1595]: winbindd version 4.17.12-Debian started. May 30 20:22:23 volumio winbindd[1595]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 30 20:22:23 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 30 20:22:23 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 30 20:22:23 volumio winbindd[1595]: [2026/05/30 20:22:23.567234, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) May 30 20:22:23 volumio winbindd[1595]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 30 20:22:23 volumio avahi-daemon[1599]: Server startup complete. Host name is volumio.local. Local service cookie is 1132758568. May 30 20:22:24 volumio volumio-remote-updater[899]: [2026-05-30 20:22:24] [connect] Successful connection May 30 20:22:24 volumio ntpd[1134]: IO: Listen normally on 3 wlan0 192.168.31.128:123 May 30 20:22:24 volumio ntpd[1134]: IO: new interface(s) found: waking up resolver May 30 20:22:24 volumio ntpd[1134]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:24 volumio ntpd[1134]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 30 20:22:24 volumio ntpd[1134]: DNS: Pool taking: 212.18.3.19 May 30 20:22:24 volumio ntpd[1134]: DNS: Pool taking: 94.16.122.152 May 30 20:22:24 volumio ntpd[1134]: DNS: Pool taking: 139.162.156.95 May 30 20:22:24 volumio ntpd[1134]: DNS: Pool taking: 185.252.140.125 May 30 20:22:24 volumio ntpd[1134]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 30 20:22:25 volumio ntpd[1134]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:25 volumio ntpd[1134]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool taking: 159.69.64.189 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool skipping: 94.16.122.152 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool taking: 217.154.182.60 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool taking: 130.180.35.90 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool taking: 2a02:8106:21:9400::6 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool taking: 2a01:7e01::f03c:94ff:fee2:cbe5 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool taking: 2a01:7e01::f03c:94ff:fee2:cb39 May 30 20:22:25 volumio ntpd[1134]: DNS: Pool taking: 2a02:8106:21:9400::2 May 30 20:22:25 volumio ntpd[1134]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 30 20:22:25 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 30 20:22:25 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. May 30 20:22:26 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... May 30 20:22:26 volumio smbd[1635]: [2026/05/30 20:22:26.277914, 0] ../../source3/smbd/server.c:1741(main) May 30 20:22:26 volumio smbd[1635]: smbd version 4.17.12-Debian started. May 30 20:22:26 volumio smbd[1635]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 30 20:22:26 volumio ntpd[1134]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:26 volumio ntpd[1134]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 30 20:22:26 volumio ntpd[1134]: DNS: Pool taking: 49.12.35.6 May 30 20:22:26 volumio ntpd[1134]: DNS: Pool taking: 104.167.24.26 May 30 20:22:26 volumio ntpd[1134]: DNS: Pool taking: 5.75.181.179 May 30 20:22:26 volumio ntpd[1134]: DNS: Pool taking: 85.220.190.246 May 30 20:22:26 volumio ntpd[1134]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 30 20:22:26 volumio volumio[1332]: info: MYVOLUMIO Environment detected May 30 20:22:26 volumio volumio[1332]: info: Plugin folders cleanup May 30 20:22:26 volumio volumio[1332]: info: Scanning into folder /volumio/app/plugins/ May 30 20:22:26 volumio volumio[1332]: info: Scanning category audio_interface May 30 20:22:26 volumio volumio[1332]: info: Scanning category miscellanea May 30 20:22:26 volumio volumio[1332]: info: Scanning category music_service May 30 20:22:26 volumio volumio[1332]: info: Scanning category plugins.json May 30 20:22:26 volumio volumio[1332]: info: Scanning category system_controller May 30 20:22:26 volumio volumio[1332]: info: Scanning category user_interface May 30 20:22:26 volumio volumio[1332]: info: Scanning into folder /data/plugins/ May 30 20:22:26 volumio volumio[1332]: info: Scanning category audio_interface May 30 20:22:26 volumio volumio[1332]: info: Scanning category music_service May 30 20:22:26 volumio volumio[1332]: info: Scanning category system_hardware May 30 20:22:26 volumio volumio[1332]: info: Scanning category user_interface May 30 20:22:26 volumio volumio[1332]: info: Plugin folders cleanup completed May 30 20:22:26 volumio volumio[1332]: info: ------------------------------------------- May 30 20:22:26 volumio volumio[1332]: info: ----- Core plugins startup ---- May 30 20:22:26 volumio volumio[1332]: info: ------------------------------------------- May 30 20:22:26 volumio volumio[1332]: info: Loading plugins from folder /volumio/app/plugins/ May 30 20:22:26 volumio volumio[1332]: info: Adding plugin upnp to MyMusic Plugins May 30 20:22:26 volumio volumio[1332]: info: Adding plugin airplay_emulation to MyMusic Plugins May 30 20:22:26 volumio volumio[1332]: info: Adding plugin upnp_browser to MyMusic Plugins May 30 20:22:26 volumio volumio[1332]: info: Loading plugins from folder /data/plugins/ May 30 20:22:26 volumio volumio[1332]: info: Adding plugin rtlsdr_radio to MyMusic Plugins May 30 20:22:26 volumio volumio[1332]: info: Loading plugin "system"... May 30 20:22:26 volumio volumio[1332]: info: Loading plugin "appearance"... May 30 20:22:27 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. May 30 20:22:27 volumio systemd[1]: Reached target multi-user.target - Multi-User System. May 30 20:22:27 volumio systemd[1]: Reached target graphical.target - Graphical Interface. May 30 20:22:27 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 30 20:22:27 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 30 20:22:27 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 30 20:22:27 volumio systemd[1]: Startup finished in 13.980s (firmware) + 10.183s (loader) + 29.122s (kernel) + 40.200s (userspace) = 1min 33.487s. May 30 20:22:27 volumio ntpd[1134]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 30 20:22:27 volumio ntpd[1134]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 30 20:22:27 volumio ntpd[1134]: DNS: Pool taking: 129.250.35.250 May 30 20:22:27 volumio ntpd[1134]: DNS: Pool taking: 185.252.140.126 May 30 20:22:27 volumio ntpd[1134]: DNS: Pool taking: 158.180.28.150 May 30 20:22:27 volumio ntpd[1134]: DNS: Pool taking: 49.12.199.148 May 30 20:22:27 volumio ntpd[1134]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 30 20:22:29 volumio ntpd[1134]: CLOCK: time stepped by -0.910596 May 30 20:22:29 volumio ntpd[1134]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes May 30 20:22:30 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 30 20:22:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 30 20:22:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:22:34 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:22:34 volumio upmpdcli[1714]: Could not open config: /tmp/upmpdcli.conf May 30 20:22:34 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 20:22:34 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 20:22:38 volumio volumio-remote-updater[899]: [2026-05-30 20:22:38] [connect] Successful connection May 30 20:22:40 volumio volumio[1332]: info: Loading plugin "network"... May 30 20:22:40 volumio volumio[1332]: info: Refreshing Cached IP Addresses May 30 20:22:40 volumio sudo[1747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 20:22:40 volumio sudo[1747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:41 volumio sudo[1747]: pam_unix(sudo:session): session closed for user root May 30 20:22:41 volumio sudo[1750]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:22:41 volumio volumio[1332]: info: Loading plugin "services"... May 30 20:22:41 volumio sudo[1750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:41 volumio sudo[1750]: pam_unix(sudo:session): session closed for user root May 30 20:22:41 volumio volumio[1332]: info: Loading plugin "volumio5onboarding"... May 30 20:22:41 volumio sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 30 20:22:41 volumio volumio[1332]: info: Loading plugin "alsa_controller"... May 30 20:22:41 volumio sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:22:42 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 20:22:42 volumio volumio[1332]: info: Loading plugin "wizard"... May 30 20:22:42 volumio volumio[1332]: info: Loading plugin "networkfs"... May 30 20:22:43 volumio volumio[1332]: info: Starting Udev Watcher for removable devices May 30 20:22:43 volumio volumio[1332]: info: Ignoring mount for partition: boot May 30 20:22:43 volumio volumio[1332]: info: Ignoring mount for partition: volumio May 30 20:22:43 volumio volumio[1332]: info: Ignoring mount for partition: volumio_data May 30 20:22:43 volumio volumio[1332]: info: Ignoring mount for partition: boot May 30 20:22:43 volumio volumio[1332]: info: Ignoring mount for partition: volumio May 30 20:22:43 volumio volumio[1332]: info: Ignoring mount for partition: volumio_data May 30 20:22:43 volumio volumio[1332]: info: Ignoring mount for partition: 3C6463EE6463A97A May 30 20:22:43 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 20:22:43 volumio volumio[1332]: info: Loading plugin "volumio_command_line_client"... May 30 20:22:43 volumio volumio[1332]: info: Loading plugin "upnp"... May 30 20:22:43 volumio volumio[1332]: info: [1780165363144] Starting Upmpd Daemon May 30 20:22:43 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 20:22:43 volumio volumio[1332]: info: Loading plugin "my_music"... May 30 20:22:43 volumio volumio[1332]: info: Loading plugin "mpd"... May 30 20:22:44 volumio sudo[1759]: pam_unix(sudo:session): session closed for user root May 30 20:22:44 volumio volumio[1332]: info: Loading plugin "upnp_browser"... May 30 20:22:45 volumio volumio[1332]: info: Starting UPNP Browser May 30 20:22:45 volumio volumio[1332]: info: Loading plugin "alarm-clock"... May 30 20:22:45 volumio volumio[1332]: info: Loading plugin "airplay_emulation"... May 30 20:22:45 volumio volumio[1332]: info: Starting Shairport Sync May 30 20:22:45 volumio volumio[1332]: info: Loading plugin "last_100"... May 30 20:22:45 volumio volumio[1332]: info: Loading plugin "webradio"... May 30 20:22:45 volumio volumio[1332]: info: Loading plugin "i2s_dacs"... May 30 20:22:46 volumio volumio[1332]: info: I2S DAC not set, start Auto-detection May 30 20:22:46 volumio volumio[1332]: info: Loading plugin "volumiodiscovery"... May 30 20:22:46 volumio volumio[1332]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 20:22:46 volumio volumio[1332]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 20:22:46 volumio volumio[1332]: *** WARNING *** For more information see May 30 20:22:46 volumio volumio[1332]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 20:22:46 volumio volumio[1332]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 20:22:46 volumio volumio[1332]: *** WARNING *** For more information see May 30 20:22:46 volumio node[1332]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 20:22:46 volumio node[1332]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 20:22:46 volumio node[1332]: *** WARNING *** For more information see May 30 20:22:46 volumio node[1332]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 20:22:46 volumio node[1332]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 20:22:46 volumio node[1332]: *** WARNING *** For more information see May 30 20:22:46 volumio volumio[1332]: info: Applying required configuration parameters for plugin volumiodiscovery May 30 20:22:46 volumio volumio[1332]: info: Discovery: Started advertising with name: Volumio May 30 20:22:46 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 20:22:46 volumio volumio[1332]: info: Loading plugin "spop"... May 30 20:22:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 30 20:22:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:22:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:22:49 volumio upmpdcli[1851]: Could not open config: /tmp/upmpdcli.conf May 30 20:22:49 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 30 20:22:49 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 30 20:22:53 volumio volumio-remote-updater[899]: [2026-05-30 20:22:53] [connect] Successful connection May 30 20:22:53 volumio volumio[1332]: info: Loading plugin "now_playing"... May 30 20:22:57 volumio volumio[1332]: info: Loading plugin "peppy_screensaver"... May 30 20:22:58 volumio volumio[1332]: info: Applying required configuration parameters for plugin peppy_screensaver May 30 20:22:58 volumio volumio[1332]: info: Plugin stylish_player is not enabled May 30 20:22:58 volumio volumio[1332]: info: Loading plugin "outputs"... May 30 20:22:58 volumio volumio[1332]: info: Loading plugin "albumart"... May 30 20:23:00 volumio volumio[1332]: info: Plugin example_plugin is not enabled May 30 20:23:00 volumio volumio[1332]: info: Loading plugin "inputs"... May 30 20:23:00 volumio volumio[1332]: info: Loading plugin "updater_comm"... May 30 20:23:00 volumio volumio[1976]: Forking 1 albumart workers May 30 20:23:00 volumio volumio[1332]: info: Plugin mpdemulation is not enabled May 30 20:23:00 volumio volumio[1332]: info: Loading plugin "rest_api"... May 30 20:23:00 volumio volumio[1332]: info: Loading plugin "websocket"... May 30 20:23:00 volumio volumio[1332]: info: Starting Socket.io Server version 1.7.4 May 30 20:23:00 volumio volumio[1332]: info: Plugin fusiondsp is not enabled May 30 20:23:00 volumio volumio[1332]: info: Loading plugin "80s80s"... May 30 20:23:00 volumio volumio[1986]: Starting albumart workers May 30 20:23:01 volumio volumio[1332]: info: Applying required configuration parameters for plugin 80s80s May 30 20:23:01 volumio volumio[1332]: info: [1780165381019] [80s80s] API delay: 30 May 30 20:23:01 volumio volumio[1332]: info: Loading plugin "radio_browser"... May 30 20:23:01 volumio volumio[1332]: info: Loading plugin "rtlsdr_radio"... May 30 20:23:02 volumio volumio[1332]: info: [RTL-SDR Radio] Loaded FM region data May 30 20:23:02 volumio volumio[1332]: info: Loading plugin "Bluetoothremote"... May 30 20:23:02 volumio volumio[1332]: info: Applying required configuration parameters for plugin Bluetoothremote May 30 20:23:02 volumio volumio[1332]: info: Loading plugin "Systeminfo"... May 30 20:23:03 volumio volumio[1332]: info: Loading plugin "display_configuration"... May 30 20:23:03 volumio volumio[1332]: info: Plugin peppymeterbasic is not enabled May 30 20:23:03 volumio volumio[1332]: info: Plugin peppyspectrum is not enabled May 30 20:23:03 volumio volumio[1332]: info: Loading i18n strings for locale de May 30 20:23:03 volumio volumio[1332]: Updating browse sources language May 30 20:23:03 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::initPlayerControls May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:23:04 volumio volumio[1332]: Express server listening on port 3000 May 30 20:23:04 volumio volumio[1332]: [Metrics] WebUI: 43s 479.38ms May 30 20:23:04 volumio volumio[1332]: info: Setting Device type: x86 May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::resetVolumioState May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::getcurrentVolume May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioRetrievevolume May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:04 volumio sudo[2022]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 20:23:04 volumio sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:23:04 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio volumio[1332]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 30 20:23:04 volumio volumio[1332]: info: Completed loading Core Plugins May 30 20:23:04 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio volumio[1332]: info: Preparing to generate the ALSA configuration file May 30 20:23:04 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio volumio[1332]: info: Volumio Network Manager: Network status updated: 2 May 30 20:23:04 volumio volumio[1332]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 30 20:23:04 volumio volumio[1332]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 30 20:23:04 volumio volumio[1332]: info: Reading ALSA contributions from plugins. May 30 20:23:04 volumio volumio[1332]: info: VolumeController:: Volume=49 Mute =false May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::pushState May 30 20:23:04 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioPushState May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::updateTrackBlock May 30 20:23:04 volumio volumio[1332]: info: CorePlayQueue::getTrackBlock May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioRetrievevolume May 30 20:23:04 volumio volumio[1332]: info: Reloading queue from file May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::setRepeat null single undefined May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::pushState May 30 20:23:04 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioPushState May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::setRandom null May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::pushState May 30 20:23:04 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioPushState May 30 20:23:04 volumio volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 30 20:23:04 volumio sudo[2041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 30 20:23:04 volumio sudo[2041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio volumio[1332]: info: VolumeController:: Volume=49 Mute =false May 30 20:23:04 volumio volumio[1332]: info: CoreStateMachine::pushState May 30 20:23:04 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioPushState May 30 20:23:04 volumio volumio[1332]: info: Discovery: adding 773a99e0-91fd-4754-a5b1-07b13827dafa May 30 20:23:04 volumio volumio[1332]: info: Discovery: Found device Volumio May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:04 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:04 volumio volumio[1332]: info: Discovery: this is already registered, 773a99e0-91fd-4754-a5b1-07b13827dafa May 30 20:23:04 volumio volumio[1332]: info: Discovery: Found device Volumio May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:04 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:04 volumio volumio[1332]: info: Asound.conf file unchanged, so no further update is needed May 30 20:23:04 volumio volumio[1332]: info: Output device has changed, restarting MPD May 30 20:23:04 volumio volumio[1332]: info: Output device has changed, restarting Shairport Sync May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:04 volumio sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 20:23:04 volumio sudo[2046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 20:23:04 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio volumio[1332]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 20:23:04 volumio volumio[1332]: info: ___________ START PLUGINS ___________ May 30 20:23:04 volumio volumio[1332]: info: ControllerMpd::onStart: Initializing MPD May 30 20:23:04 volumio volumio[1332]: info: Creating MPD Configuration file May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 20:23:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 20:23:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 30 20:23:04 volumio volumio[1332]: info: [1780165384435] CoreMusicLibrary::Adding element Medienserver May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:04 volumio sudo[2055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 30 20:23:04 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio volumio[1332]: info: UPNP Browser: Client initialized successfully May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio sudo[2059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 20:23:04 volumio sudo[2059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 20:23:04 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:04 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio volumio[1332]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:04 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 30 20:23:04 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio sudo[2060]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 20:23:04 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:23:04 volumio sudo[2073]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 30 20:23:04 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio systemd[1]: mpd.service: Deactivated successfully. May 30 20:23:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 20:23:04 volumio systemd[1]: mpd.socket: Deactivated successfully. May 30 20:23:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 20:23:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 20:23:04 volumio volumio[1332]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 20:23:04 volumio volumio[1332]: info: [1780165384545] CoreMusicLibrary::Adding element Last_100 May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 20:23:04 volumio volumio[1332]: info: [1780165384548] CoreMusicLibrary::Adding element Webradio May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 20:23:04 volumio volumio[1332]: info: Initializing BBC Radios May 30 20:23:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 20:23:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 30 20:23:04 volumio volumio[1332]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:04 volumio volumio[1332]: info: Creating Spotify config file May 30 20:23:04 volumio sudo[2077]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 20:23:04 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:23:04 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:04 volumio sudo[2089]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 30 20:23:04 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. May 30 20:23:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:23:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 30 20:23:04 volumio sudo[2041]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio volumio[1332]: info: [now-playing] ConfigUpdater: config is up to date. May 30 20:23:04 volumio volumio[1332]: info: Loading i18n strings for locale de May 30 20:23:04 volumio volumio[1332]: info: /tmp/myfifo created May 30 20:23:04 volumio volumio[1332]: info: /tmp/myfifosa created May 30 20:23:04 volumio sudo[2117]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 May 30 20:23:04 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio volumio[1332]: info: snd-dummy loaded May 30 20:23:04 volumio sudo[2121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=6 pcm_substreams=2 May 30 20:23:04 volumio sudo[2121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:04 volumio sudo[2121]: pam_unix(sudo:session): session closed for user root May 30 20:23:04 volumio volumio[1332]: info: snd-aloop loaded for x64 Spotify May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:05 volumio volumio[1332]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 20:23:05 volumio volumio[1332]: info: Adding peppy_screensaver REST Endpoint for plugin: user_interface/peppy_screensaver May 30 20:23:05 volumio volumio[1332]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver May 30 20:23:05 volumio volumio[1332]: info: Adding peppy_screensaver_font REST Endpoint for plugin: user_interface/peppy_screensaver May 30 20:23:05 volumio volumio[1332]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_font May 30 20:23:05 volumio volumio[1332]: info: peppy_screensaver: Config version updated: 94f2dc44 May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 20:23:05 volumio volumio[1332]: info: [1780165385147] CoreMusicLibrary::Adding element 80s80s Radio May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:05 volumio volumio[1332]: Cannot find translation for source 80s80s Radio May 30 20:23:05 volumio sudo[2163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl May 30 20:23:05 volumio sudo[2163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:05 volumio sudo[2163]: pam_unix(sudo:session): session closed for user root May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 20:23:05 volumio volumio[1332]: info: [1780165385189] CoreMusicLibrary::Adding element Radio Browser May 30 20:23:05 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:05 volumio volumio[1332]: Cannot find translation for source 80s80s Radio May 30 20:23:05 volumio volumio[1332]: Cannot find translation for source Radio Browser May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Starting plugin May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Loaded i18n strings for language: de May 30 20:23:05 volumio volumio[1332]: info: Loading i18n strings for locale de May 30 20:23:05 volumio volumio[1332]: info: Display-configuration --- loadI18nStrings: loading from /data/plugins/user_interface/display_configuration/i18n/strings_en.json May 30 20:23:05 volumio volumio[1332]: info: Display-configuration --- loadI18nStrings: loaded 52 keys May 30 20:23:05 volumio volumio[1332]: info: Display-configuration --- Loaded i18n strings for language: de May 30 20:23:05 volumio volumio[1332]: info: Volumio Calling Home May 30 20:23:05 volumio sudo[2167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/bash -c 'if [ -f /root/.Xauthority ]; then cp /root/.Xauthority /home/volumio/ && chown volumio:volumio /home/volumio/.Xauthority; fi' May 30 20:23:05 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] snd-aloop already loaded May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Database version: 2 May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Loaded v2 database successfully May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Database loaded at: 2026-05-30T18:23:05.379Z May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Loading blocklist from: /data/plugins/music_service/rtlsdr_radio/blocklist.json May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Got 21 phrases from file May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Updated metadata blocklist May 30 20:23:05 volumio volumio[1332]: info: [RTL-SDR Radio] Metadata module now has 21 phrases May 30 20:23:05 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root May 30 20:23:05 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:05.442+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 30 20:23:08 volumio volumio-remote-updater[899]: [2026-05-30 20:23:08] [connect] Successful connection May 30 20:23:11 volumio volumio[1332]: info: [RTL-SDR Radio] Management server started on port 3456 May 30 20:23:11 volumio volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 20:23:11 volumio volumio[1332]: info: [1780165391775] CoreMusicLibrary::Adding element FM/DAB Radio May 30 20:23:11 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:11 volumio volumio[1332]: Cannot find translation for source 80s80s Radio May 30 20:23:11 volumio volumio[1332]: Cannot find translation for source Radio Browser May 30 20:23:11 volumio volumio[1332]: Cannot find translation for source FM/DAB Radio May 30 20:23:11 volumio volumio[1332]: info: [RTL-SDR Radio] Plugin started successfully May 30 20:23:11 volumio volumio[1332]: info: [now-playing] App is listening on port 4004. May 30 20:23:11 volumio volumio[1332]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 30 20:23:11 volumio volumio[1332]: info: Display-configuration --- X server ready after 1 attempt(s) May 30 20:23:11 volumio volumio[1332]: info: Display-configuration --- fixXauthority: /home/volumio/.Xauthority updated May 30 20:23:11 volumio volumio[1332]: info: MPD Permissions set May 30 20:23:11 volumio volumio[1332]: info: MPD Permissions set May 30 20:23:11 volumio volumio[1332]: info: Upmpdcli Daemon Started May 30 20:23:12 volumio volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 May 30 20:23:12 volumio volumio[1332]: info: peppy_screensaver: pushState - status=stop service=webradio volatile=false May 30 20:23:12 volumio volumio[1332]: info: Volumio called home May 30 20:23:12 volumio volumio[1332]: info: Spotify config file written May 30 20:23:12 volumio volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 May 30 20:23:12 volumio volumio[1332]: info: Received Get System Info May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:23:12 volumio volumio[1332]: info: Discovery: Getting this device information May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:12 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:23:12 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:12.089+02:00 level=INFO msg="system info for b52c007ab9a681b6de5be70131501d0c" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 May 30 20:23:12 volumio sudo[2212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 30 20:23:12 volumio sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 20:23:12 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 30 20:23:12 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:12.119+02:00 level=INFO msg="bootstrapping state" hasInternet=true May 30 20:23:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 20:23:12 volumio sudo[2212]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio go-librespot[2214]: go-librespot daemon starting... May 30 20:23:12 volumio volumio-remote-updater[899]: [2026-05-30 20:23:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1780165388 101 May 30 20:23:12 volumio volumio[1332]: 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: 3 May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- Monitoring lid(s): /proc/acpi/button/lid/LID0/state May 30 20:23:12 volumio volumio[1332]: info: peppy_screensaver: ALSA template: /data/plugins/user_interface/peppy_screensaver/Peppyalsa.postPeppyalsa.5.x64.conf.tmpl (isX64=true) May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 30 20:23:12 volumio sudo[2236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 30 20:23:12 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: No need to fix Spotify hosts May 30 20:23:12 volumio volumio[1332]: info: Received Get System Info May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:23:12 volumio volumio[1332]: info: Discovery: Getting this device information May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:12 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:23:12 volumio volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- Connected screens: eDP-1 May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- xscreensaver cleaned up before starting May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- DPMS disabled before playback state check May 30 20:23:12 volumio volumio-remote-updater[899]: Test mode disabled May 30 20:23:12 volumio volumio-remote-updater[899]: Alpha mode disabled May 30 20:23:12 volumio volumio-remote-updater[899]: Alpha legacy test mode disabled May 30 20:23:12 volumio volumio[1332]: info: New Spotify access tokenBQDCOCdjnp... May 30 20:23:12 volumio volumio[1332]: info: Spotify credentials grant success - running version from March 24, 2019 May 30 20:23:12 volumio volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon May 30 20:23:12 volumio volumio[1332]: info: Creating Spotify config file May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 30 20:23:12 volumio volumio[1332]: info: Preparing to generate the ALSA configuration file May 30 20:23:12 volumio volumio[1332]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 30 20:23:12 volumio volumio[1332]: info: Reading ALSA contributions from plugins. May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:12 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:12 volumio sudo[2255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 20:23:12 volumio sudo[2255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio sudo[2255]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- No forced DRM orientation detected for eDP-1 May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- TTY: display=normal(0) + offset=same(0) = 0 deg (fbconv=0) May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- Plymouth: display=normal(0) + offset=same(0) = 0 deg May 30 20:23:12 volumio volumio[1332]: info: Starting Shairport Sync May 30 20:23:12 volumio volumio[1332]: info: Starting Shairport Sync May 30 20:23:12 volumio volumio[1332]: info: Starting Shairport Sync May 30 20:23:12 volumio volumio[1332]: info: Starting Shairport Sync May 30 20:23:12 volumio sudo[2260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 20:23:12 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio volumio[1332]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 30 20:23:12 volumio volumio[1332]: info: MPD Permissions set May 30 20:23:12 volumio sudo[2262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 20:23:12 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:12.635+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" May 30 20:23:12 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:12.635+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" May 30 20:23:12 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:12.636+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" May 30 20:23:12 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio volumio[1332]: info: peppy_screensaver: pushState - status=stop service=webradio volatile=false May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- Connected screens: eDP-1 May 30 20:23:12 volumio sudo[2266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 20:23:12 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 20:23:12 volumio sudo[2264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 20:23:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 20:23:12 volumio sudo[2264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:23:12 volumio systemd[1]: shairport-sync.service: Consumed 2.014s CPU time. May 30 20:23:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:23:12 volumio sudo[2260]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 20:23:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 20:23:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:23:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:23:12 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio sudo[2264]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:12 volumio volumio[1332]: info: Spotify config file written May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 20:23:12 volumio sudo[2297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 30 20:23:12 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:12 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... May 30 20:23:12 volumio volumio[1332]: info: Shairport-Sync Started May 30 20:23:12 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. May 30 20:23:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 30 20:23:12 volumio volumio[1332]: Error adding Membership: Error: addMembership EINVAL May 30 20:23:12 volumio volumio[1332]: info: Shairport-Sync Started May 30 20:23:12 volumio volumio[1332]: info: Shairport-Sync Started May 30 20:23:12 volumio volumio[1332]: info: Shairport-Sync Started May 30 20:23:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 20:23:12 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 20:23:12 volumio go-librespot[2304]: go-librespot daemon starting... May 30 20:23:12 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- Rotation config saved for Grub: screen=eDP-1, plymouth=0, fbcon=0 May 30 20:23:12 volumio volumio[1332]: info: Display-configuration --- Panel Rotation applied via xrandr May 30 20:23:13 volumio volumio[1332]: info: Received Get System Info May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:23:13 volumio volumio[1332]: info: Discovery: Getting this device information May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:13 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:23:13 volumio sudo[2313]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/graphics/fbcon/rotate_all May 30 20:23:13 volumio sudo[2313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:13 volumio sudo[2313]: pam_unix(sudo:session): session closed for user root May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- Runtime rotation applied: normal | Boot config (plymouth=0, fbconv=0) May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 30 20:23:13 volumio sudo[2318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 20:23:13 volumio sudo[2318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 20:23:13 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:13.140+02:00 level=INFO msg="enabling local network discovery" May 30 20:23:13 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:13.164+02:00 level=INFO msg="enabling BLE discovery" May 30 20:23:13 volumio systemd[1]: mpd.service: Deactivated successfully. May 30 20:23:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 20:23:13 volumio systemd[1]: mpd.socket: Deactivated successfully. May 30 20:23:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 20:23:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 20:23:13 volumio sudo[2320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 20:23:13 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- fbcon console rotation set to 0 May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- Connected screens: eDP-1 May 30 20:23:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 20:23:13 volumio volumio[1332]: error: Upnp client error: Error: read ECONNRESET May 30 20:23:13 volumio volumio[1332]: error: Upnp client error: Error: read ECONNRESET May 30 20:23:13 volumio volumio[1332]: warn: peppy_screensaver: Startup - Failed to set MPD output: Error: Command failed: mpc enable 1 May 30 20:23:13 volumio volumio[1332]: MPD error: Connection reset by peer May 30 20:23:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:13 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:13 volumio systemd[1]: mpd.service: Deactivated successfully. May 30 20:23:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 20:23:13 volumio systemd[1]: mpd.socket: Deactivated successfully. May 30 20:23:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 20:23:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- No touchscreen detected, skipping correction. May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- applyPointerCorrection: pointer_offset=0 May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- Pointer correction: none (xrandr handles relative devices) May 30 20:23:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 20:23:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 30 20:23:13 volumio sudo[2332]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -9 -f unclutter May 30 20:23:13 volumio sudo[2332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:13 volumio volumio[1332]: SPOTIFY: User informations: {"account_id":"SI9PCxu3Vs","country":"DE","display_name":"Kitcarschaefer","email":"kitcarschaefer@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/kdi4kmx8lxxtihha2pha9f1u9"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/kdi4kmx8lxxtihha2pha9f1u9","id":"kdi4kmx8lxxtihha2pha9f1u9","images":[],"product":"premium","type":"user","uri":"spotify:user:kdi4kmx8lxxtihha2pha9f1u9"} May 30 20:23:13 volumio volumio[1332]: info: Spotify Successfully logged in May 30 20:23:13 volumio sudo[2333]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 20:23:13 volumio sudo[2338]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 20:23:13 volumio volumio[1332]: info: [1780165393368] CoreMusicLibrary::Adding element Spotify May 30 20:23:13 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:23:13 volumio volumio[1332]: Cannot find translation for source 80s80s Radio May 30 20:23:13 volumio volumio[1332]: Cannot find translation for source Radio Browser May 30 20:23:13 volumio volumio[1332]: Cannot find translation for source FM/DAB Radio May 30 20:23:13 volumio volumio[1332]: Cannot find translation for source Spotify May 30 20:23:13 volumio sudo[2333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:23:13 volumio sudo[2333]: pam_unix(sudo:session): session closed for user root May 30 20:23:13 volumio sudo[2337]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/backlight/intel_backlight/brightness May 30 20:23:13 volumio sudo[2337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:13 volumio sudo[2337]: pam_unix(sudo:session): session closed for user root May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- Brightness set to 100% (96000/96000) on intel_backlight May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- No unclutter process to stop May 30 20:23:13 volumio volumio[1332]: info: Display-configuration --- unclutter stopped May 30 20:23:15 volumio volumio[1332]: info: go-librespot daemon successfully initialized May 30 20:23:15 volumio volumio[1332]: info: Asound.conf file unchanged, so no further update is needed May 30 20:23:15 volumio volumio[1332]: info: Output device has changed, restarting MPD May 30 20:23:15 volumio volumio[1332]: info: Output device has changed, restarting Shairport Sync May 30 20:23:15 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:15 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:16 volumio sudo[2354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 20:23:16 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:16 volumio sudo[2352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 20:23:16 volumio sudo[2352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:16 volumio volumio[1332]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 20:23:16 volumio sudo[2352]: pam_unix(sudo:session): session closed for user root May 30 20:23:16 volumio systemd[1]: mpd.service: Deactivated successfully. May 30 20:23:16 volumio volumio[1332]: info: Display-configuration --- Kiosk browser detected after 1s, re-applying input settings in 3s May 30 20:23:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 20:23:16 volumio systemd[1]: mpd.socket: Deactivated successfully. May 30 20:23:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 20:23:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 20:23:16 volumio volumio[1332]: info: MPD Permissions set May 30 20:23:16 volumio volumio[1332]: error: Upnp client error: Error: read ECONNRESET May 30 20:23:16 volumio volumio[1332]: warn: peppy_screensaver: Failed to set MPD output: Error: Command failed: mpc enable 1 May 30 20:23:16 volumio volumio[1332]: MPD error: Connection reset by peer May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 30 20:23:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: Starting Shairport Sync May 30 20:23:16 volumio volumio[1332]: info: go-librespot daemon successfully initialized May 30 20:23:16 volumio sudo[2372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 20:23:16 volumio sudo[2372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 20:23:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 20:23:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:23:16 volumio systemd[1]: shairport-sync.service: Consumed 1.337s CPU time. May 30 20:23:16 volumio sudo[2367]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 20:23:16 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:23:16 volumio sudo[2375]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 30 20:23:16 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root May 30 20:23:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:23:16 volumio sudo[2372]: pam_unix(sudo:session): session closed for user root May 30 20:23:16 volumio volumio[1332]: info: Shairport-Sync Started May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:23:16 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:16.889+02:00 level=INFO msg="service successfully established" component=discovery/localnet May 30 20:23:17 volumio go-librespot[2305]: time="2026-05-30T20:23:17+02:00" level=info msg="running go-librespot 0.7.1" May 30 20:23:17 volumio go-librespot[2305]: time="2026-05-30T20:23:17+02:00" level=debug msg="app state loaded" May 30 20:23:17 volumio go-librespot[2305]: time="2026-05-30T20:23:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 20:23:17 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:17.677+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.31.82:39692 May 30 20:23:17 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:17.730+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=996.992162ms timeout=10s May 30 20:23:17 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:17.730+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" May 30 20:23:17 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:17.730+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=1.001017451s platform=PLATFORM_ANDROID version=5.260413.0 May 30 20:23:17 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 20:23:17 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 20:23:17 volumio volumio[1332]: info: Received Get System Info May 30 20:23:17 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:23:17 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:23:17 volumio volumio[1332]: info: Discovery: Getting this device information May 30 20:23:17 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:17 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:17 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:23:17 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:17.736+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" name=Volumio May 30 20:23:18 volumio volumio[1332]: info: Initializing connection to go-librespot Websocket May 30 20:23:19 volumio volumio[1332]: info: Display-configuration --- Connected screens: eDP-1 May 30 20:23:19 volumio volumio[1332]: info: Display-configuration --- No touchscreen detected, skipping correction. May 30 20:23:19 volumio volumio[1332]: info: Display-configuration --- applyPointerCorrection: pointer_offset=0 May 30 20:23:19 volumio volumio[1332]: info: Display-configuration --- Pointer correction: none (xrandr handles relative devices) May 30 20:23:19 volumio volumio[1332]: info: Display-configuration --- Input settings re-applied after kiosk start May 30 20:23:19 volumio volumio[1332]: info: Initializing connection to go-librespot Websocket May 30 20:23:19 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:19.568+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=992.885588ms timeout=10s May 30 20:23:19 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:19.569+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" May 30 20:23:19 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:19.569+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=995.258779ms platform=PLATFORM_ANDROID version=5.260413.0 May 30 20:23:19 volumio volumio[1332]: info: Received Get System Info May 30 20:23:19 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:23:19 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:23:19 volumio volumio[1332]: info: Discovery: Getting this device information May 30 20:23:19 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:19 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:19 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:23:19 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:19.572+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" name=Volumio May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.327+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" language=de May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.329+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" language=de May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:23:20 volumio volumio[1332]: info: Discovery: Getting this device information May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:20 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:23:20 volumio go-librespot[2305]: time="2026-05-30T20:23:20+02:00" level=debug msg="new websocket client" May 30 20:23:20 volumio go-librespot[2305]: time="2026-05-30T20:23:20+02:00" level=debug msg="new websocket client" May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.344+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" timezone=Europe/Berlin May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.344+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" timezone=Europe/Berlin May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.345+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" available=false connected=false macAddress= ip4Address= ip6Address= May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.345+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" available=false connected=false macAddress= ip4Address= ip6Address= May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.348+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" available=true connected=true macAddress=5c:5f:67:b5:09:0f ip4Address=192.168.31.128/24 ip6Address= ssid=Xiaomi_D54C May 30 20:23:20 volumio volumio[1332]: info: Connection to go-librespot Websocket established May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.350+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" setupComplete=true May 30 20:23:20 volumio volumio[1332]: info: Connection to go-librespot Websocket established May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.350+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" available=true connected=true macAddress=5c:5f:67:b5:09:0f ip4Address=192.168.31.128/24 ip6Address= ssid=Xiaomi_D54C May 30 20:23:20 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:20.352+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" setupComplete=true May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:20 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:23:20 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 May 30 20:23:20 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:20 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:20 volumio volumio[1332]: amixer -c 1 info | grep "Loopback" May 30 20:23:27 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:27.730+02:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=996.992162ms timeout=10s error="failed to get output devices: could not get extended output devices: context deadline exceeded" May 30 20:23:27 volumio volumio[1332]: Card sysdefault:1 'Loopback'/'Loopback 1' May 30 20:23:27 volumio volumio[1332]: Mixer name : 'Loopback Mixer' May 30 20:23:27 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 May 30 20:23:27 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:27 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:27 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 May 30 20:23:27 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:27 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:27 volumio volumio[1332]: info: Getting Spotify volume May 30 20:23:27 volumio volumio[1332]: info: Getting Spotify volume May 30 20:23:27 volumio volumio[1332]: verbose: New Socket.io Connection to 192.168.31.128:3000 from 192.168.31.82 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 30 20:23:28 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 May 30 20:23:28 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:28 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:28 volumio volumio[1332]: amixer -c 7 info | grep "Dummy" May 30 20:23:29 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:29.569+02:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=992.885588ms timeout=10s error="failed to get output devices: could not get extended output devices: context deadline exceeded" May 30 20:23:30 volumio volumio[1332]: Card sysdefault:7 'Dummy'/'Dummy 1' May 30 20:23:30 volumio volumio[1332]: Mixer name : 'Dummy Mixer' May 30 20:23:30 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 May 30 20:23:30 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:30 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:30 volumio volumio[1332]: amixer -c 1 info | grep "Loopback" May 30 20:23:30 volumio volumio[1332]: Card sysdefault:1 'Loopback'/'Loopback 1' May 30 20:23:30 volumio volumio[1332]: Mixer name : 'Loopback Mixer' May 30 20:23:30 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 May 30 20:23:30 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:30 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:30 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 May 30 20:23:30 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:30 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:30 volumio volumio[1332]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 May 30 20:23:30 volumio volumio[1332]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 30 20:23:30 volumio volumio[1332]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} May 30 20:23:30 volumio volumio[1332]: amixer -c 7 info | grep "Dummy" May 30 20:23:30 volumio volumio[1332]: Card sysdefault:7 'Dummy'/'Dummy 1' May 30 20:23:30 volumio volumio[1332]: Mixer name : 'Dummy Mixer' May 30 20:23:30 volumio volumio[1332]: info: Bluetoothremote--- Checking for trusted devices to reconnect... May 30 20:23:36 volumio volumio[1332]: info: Bluetoothremote--- Device list cleared and placeholder written. May 30 20:23:36 volumio volumio[1332]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 30 20:23:36 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:36 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 20:23:36 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:23:36 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 20:23:36 volumio volumio[1332]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion May 30 20:23:36 volumio volumio[1332]: info: ------------------------------------------- May 30 20:23:36 volumio volumio[1332]: info: ----- MyVolumio plugins startup ---- May 30 20:23:36 volumio volumio[1332]: info: ------------------------------------------- May 30 20:23:36 volumio volumio[1332]: info: [MyVolumio PluginManager] Fetching plans data.... May 30 20:23:40 volumio volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 20:23:40 volumio volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 30 20:23:40 volumio volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 30 20:23:40 volumio bluetoothd[912]: Adv Monitor app :1.25 disconnected from D-Bus May 30 20:23:40 volumio volumio[1332]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx May 30 20:23:40 volumio go-librespot[2305]: time="2026-05-30T20:23:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" May 30 20:23:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 30 20:23:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 30 20:23:40 volumio bluetoothd[912]: Adv Monitor app :1.26 disconnected from D-Bus May 30 20:23:40 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:23:40 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:23:40 volumio volumio[1332]: info: Discovery: Getting this device information May 30 20:23:40 volumio volumio[1332]: info: CoreCommandRouter::volumioGetState May 30 20:23:40 volumio volumio[1332]: info: CorePlayQueue::getTrack 0 May 30 20:23:40 volumio volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:23:40 volumio volumio[1332]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 20:23:40 volumio volumio[1332]: Error: socket hang up May 30 20:23:40 volumio volumio[1332]: at connResetException (node:internal/errors:720:14) May 30 20:23:40 volumio volumio[1332]: at Socket.socketOnEnd (node:_http_client:519:23) May 30 20:23:40 volumio volumio[1332]: at Socket.emit (node:events:526:35) May 30 20:23:40 volumio volumio[1332]: at endReadableNT (node:internal/streams/readable:1376:12) May 30 20:23:40 volumio volumio[1332]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 30 20:23:40 volumio volumio[1332]: code: 'ECONNRESET', May 30 20:23:40 volumio volumio[1332]: response: undefined May 30 20:23:40 volumio volumio[1332]: } May 30 20:23:40 volumio volumio[1332]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 20:23:40 volumio bluetoothd[912]: Path / reserved for Adv Monitor app :1.27 May 30 20:23:40 volumio bluetoothd[912]: Path / reserved for Adv Monitor app :1.28 May 30 20:23:40 volumio bluetoothd[912]: Adv Monitor app :1.27 disconnected from D-Bus May 30 20:23:40 volumio bluetoothd[912]: Adv Monitor app :1.28 disconnected from D-Bus May 30 20:23:42 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:42.697+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=996.15932ms platform=PLATFORM_ANDROID version=5.260413.0 May 30 20:23:42 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:42.698+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.31.82:39692 @ 0xc0003cc0f0" latency=994.764346ms timeout=10s May 30 20:23:42 volumio volumio5-onboarding[2068]: time=2026-05-30T20:23:42.698+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.31.82:39692 @ 0xc0003cc0f0" May 30 20:23:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 30 20:23:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 30 20:23:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 30 20:23:43 volumio go-librespot[2529]: go-librespot daemon starting... May 30 20:23:43 volumio go-librespot[2530]: time="2026-05-30T20:23:43+02:00" level=info msg="running go-librespot 0.7.1" May 30 20:23:43 volumio go-librespot[2530]: time="2026-05-30T20:23:43+02:00" level=debug msg="app state loaded" May 30 20:23:43 volumio sudo[2514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 20:22' May 30 20:23:43 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:23:43 volumio go-librespot[2530]: time="2026-05-30T20:23:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"