May 25 21:21:00 virusplayer alsactl[709]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 21:21:00 virusplayer alsactl[709]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 21:21:00 virusplayer alsactl[709]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 21:21:00 virusplayer systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. May 25 21:21:00 virusplayer systemd[1]: Reached target sound.target - Sound Card. May 25 21:21:00 virusplayer avahi-daemon[675]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 25 21:21:00 virusplayer avahi-daemon[675]: Successfully dropped root privileges. May 25 21:21:00 virusplayer avahi-daemon[675]: avahi-daemon 0.8 starting up. May 25 21:21:00 virusplayer th-udev-rebind.sh[718]: Rebinding /dev/input/event0 to thd using --add... May 25 21:21:00 virusplayer kernel: Bluetooth: hci0: BCM: features 0x2e May 25 21:21:00 virusplayer kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 May 25 21:21:00 virusplayer kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 May 25 21:21:00 virusplayer rpi-eeprom-update[683]: Device does not a have a Raspberry Pi bootloader EEPROM (e.g. Pi 4 or Pi 5). Skipping bootloader update. May 25 21:21:00 virusplayer th-udev-rebind.sh[718]: Rebinding /dev/input/event1 to thd using --add... May 25 21:21:00 virusplayer systemd[1]: e2scrub_reap.service: Deactivated successfully. May 25 21:21:00 virusplayer systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. May 25 21:21:00 virusplayer systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. May 25 21:21:00 virusplayer systemd[1]: th-udev-rebind.service: Deactivated successfully. May 25 21:21:00 virusplayer systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. May 25 21:21:00 virusplayer kernel: 8021q: 802.1Q VLAN Support v1.8 May 25 21:21:00 virusplayer dhcpcd[679]: dhcpcd-9.4.1 starting May 25 21:21:00 virusplayer dhcpcd[749]: dev: loaded udev May 25 21:21:00 virusplayer dhcpcd[749]: DUID 00:01:00:01:31:55:84:83:b8:27:eb:30:10:13 May 25 21:21:00 virusplayer systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. May 25 21:21:00 virusplayer systemd-logind[688]: New seat seat0. May 25 21:21:00 virusplayer systemd-logind[688]: Watching system buttons on /dev/input/event0 (vc4-hdmi) May 25 21:21:00 virusplayer systemd[1]: Started dbus.service - D-Bus System Message Bus. May 25 21:21:00 virusplayer avahi-daemon[675]: Successfully called chroot(). May 25 21:21:00 virusplayer avahi-daemon[675]: Successfully dropped remaining capabilities. May 25 21:21:00 virusplayer loadcpufreq[702]: Loading cpufreq kernel modules...done (none). May 25 21:21:00 virusplayer avahi-daemon[675]: Loading service file /services/volumio.service. May 25 21:21:00 virusplayer avahi-daemon[675]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 25 21:21:00 virusplayer avahi-daemon[675]: New relevant interface lo.IPv4 for mDNS. May 25 21:21:00 virusplayer avahi-daemon[675]: Network interface enumeration completed. May 25 21:21:00 virusplayer avahi-daemon[675]: Registering new address record for 127.0.0.1 on lo.IPv4. May 25 21:21:00 virusplayer systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. May 25 21:21:00 virusplayer systemd[1]: Finished networking.service - Raise network interfaces. May 25 21:21:00 virusplayer systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... May 25 21:21:01 virusplayer dhcpcd[749]: eth0: waiting for carrier May 25 21:21:01 virusplayer dhcpcd[749]: eth0: carrier acquired May 25 21:21:01 virusplayer kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 25 21:21:01 virusplayer kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 25 21:21:01 virusplayer systemd[1]: Starting wpa_supplicant.service - WPA supplicant... May 25 21:21:01 virusplayer sh[704]: eth0: waiting for carrier May 25 21:21:01 virusplayer sh[704]: eth0: carrier acquired May 25 21:21:01 virusplayer dhcpcd[713]: eth0: waiting for carrier May 25 21:21:01 virusplayer dhcpcd[713]: eth0: carrier acquired May 25 21:21:01 virusplayer dbus-daemon[676]: [system] Successfully activated service 'org.freedesktop.systemd1' May 25 21:21:01 virusplayer systemd[1]: iptables.service: Deactivated successfully. May 25 21:21:01 virusplayer dhcpcd[749]: eth0: IAID eb:65:45:46 May 25 21:21:01 virusplayer systemd[1]: Started systemd-logind.service - User Login Management. May 25 21:21:01 virusplayer systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 25 21:21:01 virusplayer dhcpcd[749]: eth0: adding address fe80::8a23:ae19:774e:f8f May 25 21:21:01 virusplayer dhcpcd[749]: ipv6_addaddr1: Permission denied May 25 21:21:01 virusplayer dhcpcd[713]: eth0: IAID eb:65:45:46 May 25 21:21:01 virusplayer sh[704]: eth0: IAID eb:65:45:46 May 25 21:21:01 virusplayer sh[704]: eth0: adding address fe80::8a23:ae19:774e:f8f May 25 21:21:01 virusplayer sh[704]: ipv6_addaddr1: Permission denied May 25 21:21:01 virusplayer dhcpcd[713]: eth0: adding address fe80::8a23:ae19:774e:f8f May 25 21:21:01 virusplayer dhcpcd[713]: ipv6_addaddr1: Permission denied May 25 21:21:01 virusplayer dhcpcd[713]: eth0: soliciting an IPv6 router May 25 21:21:01 virusplayer sh[704]: eth0: soliciting an IPv6 router May 25 21:21:01 virusplayer systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. May 25 21:21:01 virusplayer systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... May 25 21:21:01 virusplayer cpufrequtils[824]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 25 21:21:01 virusplayer systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. May 25 21:21:01 virusplayer (udev-worker)[463]: Network interface NamePolicy= disabled on kernel command line. May 25 21:21:01 virusplayer dhcpcd[749]: eth0: soliciting an IPv6 router May 25 21:21:01 virusplayer dhcpcd[749]: eth0: rebinding lease of 192.168.111.12 May 25 21:21:01 virusplayer (udev-worker)[465]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. May 25 21:21:01 virusplayer haveged[647]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K May 25 21:21:01 virusplayer haveged[647]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 May 25 21:21:01 virusplayer haveged[647]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99877 May 25 21:21:01 virusplayer haveged[647]: haveged: fills: 0, generated: 0 May 25 21:21:01 virusplayer dhcpcd[749]: eth0: probing address 192.168.111.12/28 May 25 21:21:01 virusplayer systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. May 25 21:21:01 virusplayer systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... May 25 21:21:01 virusplayer wpa_supplicant[826]: Successfully initialized wpa_supplicant May 25 21:21:01 virusplayer volumio-remote-updater[696]: Volumio OTA Client: 1.7.0 May 25 21:21:01 virusplayer volumio-remote-updater[696]: Error: No active session May 25 21:21:01 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:01] [info] asio async_connect error: asio.system:111 (Connection refused) May 25 21:21:01 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:01] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 25 21:21:01 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:01] [error] handle_connect error: Connection refused May 25 21:21:01 virusplayer systemd[1]: Starting bluetooth.service - Bluetooth service... May 25 21:21:01 virusplayer systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. May 25 21:21:01 virusplayer systemd[1]: Started wpa_supplicant.service - WPA supplicant. May 25 21:21:01 virusplayer systemd[1]: Started ifup@wlan0.service - ifup for wlan0. May 25 21:21:01 virusplayer systemd[1]: Reached target network.target - Network. May 25 21:21:01 virusplayer systemd[1]: Reached target network-online.target - Network is Online. May 25 21:21:01 virusplayer systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... May 25 21:21:01 virusplayer systemd[1]: Starting nmbd.service - Samba NMB Daemon... May 25 21:21:01 virusplayer systemd[1]: Starting ntpsec.service - Network Time Service... May 25 21:21:01 virusplayer avahi-daemon[675]: Server startup complete. Host name is virusplayer.local. Local service cookie is 2453960736. May 25 21:21:01 virusplayer systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... May 25 21:21:01 virusplayer systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... May 25 21:21:01 virusplayer kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 21:21:01 virusplayer dhcpcd[749]: wlan0: waiting for carrier May 25 21:21:01 virusplayer systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... May 25 21:21:01 virusplayer kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 May 25 21:21:01 virusplayer sm-notify[893]: Version 2.6.2 starting May 25 21:21:01 virusplayer upmpdcli[694]: Could not open config: /tmp/upmpdcli.conf May 25 21:21:01 virusplayer systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 25 21:21:01 virusplayer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 21:21:02 virusplayer systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... May 25 21:21:02 virusplayer systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 25 21:21:02 virusplayer systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. May 25 21:21:02 virusplayer systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 21:21:02 virusplayer sh[704]: eth0: rebinding lease of 192.168.111.12 May 25 21:21:02 virusplayer dhcpcd[713]: eth0: rebinding lease of 192.168.111.12 May 25 21:21:02 virusplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 25 21:21:02 virusplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 25 21:21:02 virusplayer systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. May 25 21:21:02 virusplayer systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. May 25 21:21:02 virusplayer ntpd[927]: INIT: ntpd ntpsec-1.2.2: Starting May 25 21:21:02 virusplayer ntpd[927]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec May 25 21:21:02 virusplayer sh[704]: eth0: probing address 192.168.111.12/28 May 25 21:21:02 virusplayer ntp-systemd-wrapper[927]: 2026-05-25T21:21:02 ntpd[927]: INIT: ntpd ntpsec-1.2.2: Starting May 25 21:21:02 virusplayer ntp-systemd-wrapper[927]: 2026-05-25T21:21:02 ntpd[927]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec May 25 21:21:02 virusplayer dhcpcd[713]: eth0: probing address 192.168.111.12/28 May 25 21:21:02 virusplayer systemd[1]: Started ntpsec.service - Network Time Service. May 25 21:21:02 virusplayer systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 25 21:21:02 virusplayer welcome[940]: Resolved ip:[0] May 25 21:21:02 virusplayer ifplugd(eth0)[966]: ifplugd 0.28 initializing. May 25 21:21:02 virusplayer ifplugd(eth0)[966]: Using interface eth0/B8:27:EB:65:45:46 with driver (version: 6.12.74-v7+) May 25 21:21:02 virusplayer ifplugd(eth0)[966]: Using detection mode: SIOCETHTOOL May 25 21:21:02 virusplayer ifplugd(eth0)[966]: Initialization complete, link beat detected. May 25 21:21:02 virusplayer ntpd[948]: INIT: precision = 0.885 usec (-20) May 25 21:21:02 virusplayer ntpd[948]: INIT: successfully locked into RAM May 25 21:21:02 virusplayer systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... May 25 21:21:02 virusplayer ntpd[948]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf May 25 21:21:02 virusplayer ntpd[948]: CONFIG: restrict nopeer ignored May 25 21:21:02 virusplayer ifplugd(eth0)[966]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 25 21:21:02 virusplayer ntpd[948]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 25 21:21:02 virusplayer ntpd[948]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 May 25 21:21:02 virusplayer ntpd[948]: INIT: Using SO_TIMESTAMPNS(ns) May 25 21:21:02 virusplayer ntpd[948]: IO: Listen and drop on 0 v6wildcard [::]:123 May 25 21:21:02 virusplayer ntpd[948]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 25 21:21:02 virusplayer ntpd[948]: IO: Listen normally on 2 lo 127.0.0.1:123 May 25 21:21:02 virusplayer ntpd[948]: IO: Listening on routing socket on fd #19 for interface updates May 25 21:21:02 virusplayer sh[979]: wlan0=wlan0 May 25 21:21:02 virusplayer bluetoothd[866]: Bluetooth daemon 5.83 May 25 21:21:02 virusplayer bluetoothd[866]: Starting SDP server May 25 21:21:02 virusplayer bluetoothd[866]: Excluding (cli) sap May 25 21:21:02 virusplayer bluetoothd[866]: Excluding (cli) scanparam May 25 21:21:02 virusplayer bluetoothd[866]: Excluding (cli) midi May 25 21:21:02 virusplayer bluetoothd[866]: Excluding (cli) battery May 25 21:21:02 virusplayer systemd[1]: Started bluetooth.service - Bluetooth service. May 25 21:21:02 virusplayer avahi-daemon[675]: Service "Virusplayer" (/services/volumio.service) successfully established. May 25 21:21:02 virusplayer ntpd[948]: INIT: This system has a 32-bit time_t. May 25 21:21:02 virusplayer ntpd[948]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. May 25 21:21:02 virusplayer ntpd[948]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 25 21:21:02 virusplayer ntpd[948]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 May 25 21:21:02 virusplayer systemd[1]: Starting bluealsa.service - BlueALSA service... May 25 21:21:02 virusplayer ntpd[948]: INIT: Running with OpenSSL 3.0.19 27 Jan 2026, 30000130 May 25 21:21:02 virusplayer ntpd[948]: NTSc: Using system default root certificates. May 25 21:21:02 virusplayer systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 21:21:02 virusplayer sshd[981]: Server listening on 0.0.0.0 port 22. May 25 21:21:02 virusplayer sshd[981]: Server listening on :: port 22. May 25 21:21:02 virusplayer systemd[1]: Started ssh.service - OpenBSD Secure Shell server. May 25 21:21:02 virusplayer kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 25 21:21:02 virusplayer kernel: Bluetooth: BNEP filters: protocol multicast May 25 21:21:02 virusplayer kernel: Bluetooth: BNEP socket layer initialized May 25 21:21:02 virusplayer dbus-daemon[676]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=866 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 25 21:21:02 virusplayer bluetoothd[866]: Bluetooth management interface 1.23 initialized May 25 21:21:03 virusplayer kernel: Bluetooth: MGMT ver 1.23 May 25 21:21:03 virusplayer bluetoothd[866]: Battery Provider Manager created May 25 21:21:03 virusplayer ntpd[948]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory May 25 21:21:03 virusplayer ifplugd(eth0)[966]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 25 21:21:03 virusplayer kernel: NET: Registered PF_ALG protocol family May 25 21:21:03 virusplayer systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 25 21:21:03 virusplayer kernel: cryptd: max_cpu_qlen set to 1000 May 25 21:21:03 virusplayer dbus-daemon[676]: [system] Successfully activated service 'org.freedesktop.hostname1' May 25 21:21:03 virusplayer systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 25 21:21:03 virusplayer bluetoothd[866]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled May 25 21:21:03 virusplayer bluetoothd[866]: bap: Operation not supported (95) May 25 21:21:03 virusplayer bluetoothd[866]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 May 25 21:21:04 virusplayer ntpd[948]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 21:21:04 virusplayer ntpd[948]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 25 21:21:04 virusplayer ntpd[948]: DNS: dns_check: DNS error: -11, System error May 25 21:21:04 virusplayer ntpd[948]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 May 25 21:21:04 virusplayer bluealsa[985]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa May 25 21:21:04 virusplayer bluealsa[985]: main.c:663: Starting main dispatching loop May 25 21:21:04 virusplayer systemd[1]: Started bluealsa.service - BlueALSA service. May 25 21:21:04 virusplayer bluealsa[985]: main.c:118: Acquired D-Bus service name: org.bluealsa May 25 21:21:04 virusplayer bluealsa[985]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa May 25 21:21:04 virusplayer systemd[1]: Reached target bluetooth.target - Bluetooth Support. May 25 21:21:04 virusplayer bluealsa[985]: bluez.c:153: Registering media application: /org/bluez/hci0 May 25 21:21:04 virusplayer bluealsa[985]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 May 25 21:21:04 virusplayer bluealsa[985]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 May 25 21:21:04 virusplayer bluealsa[985]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 May 25 21:21:04 virusplayer bluealsa[985]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 May 25 21:21:04 virusplayer bluetoothd[866]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 May 25 21:21:04 virusplayer bluetoothd[866]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 May 25 21:21:04 virusplayer bluetoothd[866]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 May 25 21:21:04 virusplayer bluetoothd[866]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 May 25 21:21:05 virusplayer systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. May 25 21:21:05 virusplayer systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. May 25 21:21:05 virusplayer ntpd[948]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 21:21:05 virusplayer ntpd[948]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 25 21:21:05 virusplayer ntpd[948]: DNS: dns_check: DNS error: -11, System error May 25 21:21:05 virusplayer ntpd[948]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 May 25 21:21:05 virusplayer nmbd[1038]: [2026/05/25 21:21:05.720761, 0] ../../source3/nmbd/nmbd.c:901(main) May 25 21:21:05 virusplayer nmbd[1038]: nmbd version 4.17.8-Raspbian started. May 25 21:21:05 virusplayer nmbd[1038]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 25 21:21:05 virusplayer nmbd[1038]: [2026/05/25 21:21:05.726378, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) May 25 21:21:05 virusplayer nmbd[1038]: started asyncdns process 1039 May 25 21:21:05 virusplayer nmbd[1038]: [2026/05/25 21:21:05.728142, 0] ../../lib/util/become_daemon.c:150(daemon_status) May 25 21:21:05 virusplayer nmbd[1038]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 25 21:21:05 virusplayer nmbd[1038]: [2026/05/25 21:21:05.728338, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) May 25 21:21:05 virusplayer nmbd[1038]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 25 21:21:06 virusplayer ntpd[948]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 21:21:06 virusplayer ntpd[948]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 25 21:21:06 virusplayer ntpd[948]: DNS: dns_check: DNS error: -11, System error May 25 21:21:06 virusplayer ntpd[948]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: === SNM TRANSITION === May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Previous ethernet state: disconnected May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: New ethernet state: connected May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Single Network Mode: enabled May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: First start: yes May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: === END TRANSITION === May 25 21:21:06 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 25 21:21:06 virusplayer dhcpcd[749]: eth0: leased 192.168.111.12 for 86400 seconds May 25 21:21:06 virusplayer avahi-daemon[675]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.111.12. May 25 21:21:06 virusplayer avahi-daemon[675]: New relevant interface eth0.IPv4 for mDNS. May 25 21:21:06 virusplayer avahi-daemon[675]: Registering new address record for 192.168.111.12 on eth0.IPv4. May 25 21:21:06 virusplayer dhcpcd[749]: eth0: adding route to 192.168.111.0/28 May 25 21:21:06 virusplayer dhcpcd[749]: eth0: adding default route via 192.168.111.1 May 25 21:21:06 virusplayer systemd[1]: systemd-rfkill.service: Deactivated successfully. May 25 21:21:06 virusplayer systemd[1]: welcome.service: Deactivated successfully. May 25 21:21:06 virusplayer systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 21:21:06 virusplayer systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 21:21:06 virusplayer systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 21:21:06 virusplayer systemd[1]: Started nmbd.service - Samba NMB Daemon. May 25 21:21:06 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:06] [info] asio async_connect error: asio.system:111 (Connection refused) May 25 21:21:06 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:06] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 25 21:21:06 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:06] [error] handle_connect error: Connection refused May 25 21:21:06 virusplayer welcome[1075]: Resolved ip:[1] 192.168.111.12 May 25 21:21:06 virusplayer systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 25 21:21:06 virusplayer systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 21:21:06 virusplayer systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 25 21:21:07 virusplayer sudo[1087]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 21:21:07 virusplayer sudo[1087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 21:21:07 virusplayer sudo[1087]: pam_unix(sudo:session): session closed for user root May 25 21:21:07 virusplayer sudo[1090]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 25 21:21:07 virusplayer sudo[1090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 21:21:07 virusplayer dhcpcd[713]: eth0: leased 192.168.111.12 for 86400 seconds May 25 21:21:07 virusplayer sh[704]: eth0: leased 192.168.111.12 for 86400 seconds May 25 21:21:07 virusplayer ntpd[948]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 21:21:07 virusplayer sudo[1090]: pam_unix(sudo:session): session closed for user root May 25 21:21:07 virusplayer sh[704]: eth0: adding route to 192.168.111.0/28 May 25 21:21:07 virusplayer sh[704]: eth0: adding default route via 192.168.111.1 May 25 21:21:07 virusplayer dhcpcd[713]: eth0: adding route to 192.168.111.0/28 May 25 21:21:07 virusplayer dhcpcd[713]: eth0: adding default route via 192.168.111.1 May 25 21:21:07 virusplayer winbindd[1088]: [2026/05/25 21:21:07.586860, 0] ../../source3/winbindd/winbindd.c:1440(main) May 25 21:21:07 virusplayer winbindd[1088]: winbindd version 4.17.8-Raspbian started. May 25 21:21:07 virusplayer winbindd[1088]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 25 21:21:07 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Cleaning previous... May 25 21:21:07 virusplayer winbindd[1088]: [2026/05/25 21:21:07.615480, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) May 25 21:21:07 virusplayer winbindd[1088]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 25 21:21:07 virusplayer systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 25 21:21:07 virusplayer systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 25 21:21:07 virusplayer systemd[1]: welcome.service: Deactivated successfully. May 25 21:21:07 virusplayer systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 21:21:07 virusplayer systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 21:21:07 virusplayer sh[704]: forked to background, child pid 712 May 25 21:21:07 virusplayer sudo[1109]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 25 21:21:07 virusplayer sudo[1109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 21:21:07 virusplayer sudo[1109]: pam_unix(sudo:session): session closed for user root May 25 21:21:07 virusplayer kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 21:21:07 virusplayer systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 21:21:07 virusplayer systemd[1]: Started winbind.service - Samba Winbind Daemon. May 25 21:21:07 virusplayer welcome[1114]: Resolved ip:[1] 192.168.111.12 May 25 21:21:07 virusplayer systemd[1]: Starting smbd.service - Samba SMB Daemon... May 25 21:21:07 virusplayer wireless.js[699]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 25 21:21:07 virusplayer systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 21:21:07 virusplayer wireless.js[699]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 12ms May 25 21:21:07 virusplayer systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 25 21:21:07 virusplayer wireless.js[699]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:30:10:13) May 25 21:21:07 virusplayer sudo[1148]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 25 21:21:07 virusplayer sudo[1148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:07 virusplayer sudo[1148]: pam_unix(sudo:session): session closed for user root May 25 21:21:07 virusplayer sudo[1159]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 25 21:21:07 virusplayer sudo[1159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:08 virusplayer sh[1180]: eth0=eth0 May 25 21:21:08 virusplayer ifplugd(eth0)[966]: client: ifup: interface eth0 already configured May 25 21:21:08 virusplayer ifplugd(eth0)[966]: Program executed successfully. May 25 21:21:08 virusplayer ifplugd[882]: Network Interface Plugging Daemon...start eth0...done. May 25 21:21:08 virusplayer systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. May 25 21:21:08 virusplayer smbd[1175]: [2026/05/25 21:21:08.503172, 0] ../../source3/smbd/server.c:1741(main) May 25 21:21:08 virusplayer smbd[1175]: smbd version 4.17.8-Raspbian started. May 25 21:21:08 virusplayer smbd[1175]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 25 21:21:08 virusplayer sudo[1159]: pam_unix(sudo:session): session closed for user root May 25 21:21:08 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability May 25 21:21:08 virusplayer wireless.js[699]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) May 25 21:21:08 virusplayer wireless.js[699]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active May 25 21:21:08 virusplayer sudo[1189]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 25 21:21:08 virusplayer sudo[1189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 21:21:08 virusplayer sudo[1189]: pam_unix(sudo:session): session closed for user root May 25 21:21:08 virusplayer sudo[1192]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 21:21:08 virusplayer sudo[1192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 21:21:08 virusplayer sudo[1192]: pam_unix(sudo:session): session closed for user root May 25 21:21:08 virusplayer wpa_supplicant[1195]: Successfully initialized wpa_supplicant May 25 21:21:09 virusplayer wpa_supplicant[1198]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 25 21:21:09 virusplayer wireless.js[699]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 2931ms May 25 21:21:09 virusplayer wireless.js[699]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable May 25 21:21:09 virusplayer wireless.js[699]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 25 21:21:09 virusplayer kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 21:21:09 virusplayer systemd[1]: Started wireless.service - Wireless Services. May 25 21:21:09 virusplayer systemd[1]: Started volumio.service - Volumio Backend Module. May 25 21:21:09 virusplayer systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... May 25 21:21:09 virusplayer systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. May 25 21:21:09 virusplayer systemd[1]: Received SIGRTMIN+21 from PID 307 (plymouthd). May 25 21:21:09 virusplayer systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. May 25 21:21:09 virusplayer systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. May 25 21:21:09 virusplayer systemd[1]: Received SIGRTMIN+21 from PID 307 (n/a). May 25 21:21:09 virusplayer systemd[1]: Started getty@tty1.service - Getty on tty1. May 25 21:21:09 virusplayer systemd[1]: Reached target getty.target - Login Prompts. May 25 21:21:09 virusplayer volumio-cpu-tweak[1221]: Setting RT Priority for mpd May 25 21:21:09 virusplayer volumio-cpu-tweak[1238]: pid 35's current scheduling policy: SCHED_OTHER May 25 21:21:09 virusplayer volumio-cpu-tweak[1238]: pid 35's current scheduling priority: 0 May 25 21:21:09 virusplayer volumio-cpu-tweak[1221]: Setting MPD Affinity May 25 21:21:09 virusplayer volumio-cpu-tweak[1243]: pid 3's current affinity mask: f May 25 21:21:09 virusplayer volumio-cpu-tweak[1221]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 25 21:21:09 virusplayer volumio-cpu-tweak[1221]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 25 21:21:09 virusplayer systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. May 25 21:21:09 virusplayer systemd[1]: Started smbd.service - Samba SMB Daemon. May 25 21:21:11 virusplayer ntpd[948]: IO: Listen normally on 3 eth0 192.168.111.12:123 May 25 21:21:11 virusplayer ntpd[948]: IO: new interface(s) found: waking up resolver May 25 21:21:14 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:14] [connect] Successful connection May 25 21:21:14 virusplayer volumio[1219]: info: ------------------------------------------- May 25 21:21:14 virusplayer volumio[1219]: info: ----- Volumio3 ---- May 25 21:21:14 virusplayer volumio[1219]: info: ------------------------------------------- May 25 21:21:14 virusplayer volumio[1219]: info: ----- System startup ---- May 25 21:21:14 virusplayer volumio[1219]: info: ------------------------------------------- May 25 21:21:16 virusplayer ntpd[948]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 25 21:21:16 virusplayer ntpd[948]: DNS: dns_check: DNS error: -11, System error May 25 21:21:16 virusplayer ntpd[948]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 May 25 21:21:16 virusplayer volumio[1219]: info: MYVOLUMIO Environment detected May 25 21:21:16 virusplayer ntpd[948]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 21:21:16 virusplayer volumio[1219]: info: Plugin folders cleanup May 25 21:21:16 virusplayer volumio[1219]: info: Scanning into folder /volumio/app/plugins/ May 25 21:21:16 virusplayer volumio[1219]: info: Scanning category audio_interface May 25 21:21:16 virusplayer volumio[1219]: info: Scanning category miscellanea May 25 21:21:16 virusplayer volumio[1219]: info: Scanning category music_service May 25 21:21:16 virusplayer volumio[1219]: info: Scanning category plugins.json May 25 21:21:16 virusplayer volumio[1219]: info: Scanning category system_controller May 25 21:21:16 virusplayer volumio[1219]: info: Scanning category user_interface May 25 21:21:16 virusplayer volumio[1219]: info: Scanning into folder /data/plugins/ May 25 21:21:16 virusplayer volumio[1219]: info: Scanning category music_service May 25 21:21:16 virusplayer volumio[1219]: info: Plugin folders cleanup completed May 25 21:21:16 virusplayer volumio[1219]: info: ------------------------------------------- May 25 21:21:16 virusplayer volumio[1219]: info: ----- Core plugins startup ---- May 25 21:21:16 virusplayer volumio[1219]: info: ------------------------------------------- May 25 21:21:16 virusplayer volumio[1219]: info: Loading plugins from folder /volumio/app/plugins/ May 25 21:21:16 virusplayer volumio[1219]: info: Adding plugin upnp to MyMusic Plugins May 25 21:21:16 virusplayer volumio[1219]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 21:21:16 virusplayer volumio[1219]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 21:21:16 virusplayer volumio[1219]: info: Loading plugins from folder /data/plugins/ May 25 21:21:16 virusplayer volumio[1219]: info: Loading plugin "system"... May 25 21:21:16 virusplayer volumio[1219]: info: Loading plugin "appearance"... May 25 21:21:17 virusplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 25 21:21:17 virusplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 21:21:17 virusplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 21:21:17 virusplayer upmpdcli[1269]: Could not open config: /tmp/upmpdcli.conf May 25 21:21:17 virusplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 25 21:21:17 virusplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 25 21:21:18 virusplayer volumio[1219]: info: Loading plugin "network"... May 25 21:21:18 virusplayer volumio[1219]: info: Refreshing Cached IP Addresses May 25 21:21:18 virusplayer sudo[1272]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 21:21:18 virusplayer sudo[1272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:18 virusplayer sudo[1272]: pam_unix(sudo:session): session closed for user root May 25 21:21:18 virusplayer volumio[1219]: info: Loading plugin "services"... May 25 21:21:18 virusplayer sudo[1273]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 21:21:18 virusplayer volumio[1219]: info: Loading plugin "volumio5onboarding"... May 25 21:21:18 virusplayer sudo[1273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:18 virusplayer sudo[1285]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 21:21:18 virusplayer sudo[1285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:18 virusplayer volumio[1219]: info: Loading plugin "alsa_controller"... May 25 21:21:18 virusplayer sudo[1273]: pam_unix(sudo:session): session closed for user root May 25 21:21:19 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 21:21:19 virusplayer volumio[1219]: info: Loading plugin "wizard"... May 25 21:21:19 virusplayer volumio[1219]: info: Loading plugin "networkfs"... May 25 21:21:19 virusplayer volumio[1219]: info: Starting Udev Watcher for removable devices May 25 21:21:19 virusplayer volumio[1219]: info: Ignoring mount for partition: boot May 25 21:21:19 virusplayer volumio[1219]: info: Ignoring mount for partition: volumio May 25 21:21:19 virusplayer volumio[1219]: info: Ignoring mount for partition: volumio_data May 25 21:21:19 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 21:21:19 virusplayer volumio[1219]: info: Loading plugin "volumio_command_line_client"... May 25 21:21:19 virusplayer volumio[1219]: info: Loading plugin "upnp"... May 25 21:21:19 virusplayer volumio[1219]: info: [1779724279252] Starting Upmpd Daemon May 25 21:21:19 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 21:21:19 virusplayer volumio[1219]: info: Loading plugin "my_music"... May 25 21:21:19 virusplayer volumio[1219]: info: Loading plugin "mpd"... May 25 21:21:19 virusplayer sudo[1285]: pam_unix(sudo:session): session closed for user root May 25 21:21:19 virusplayer volumio[1219]: info: Loading plugin "upnp_browser"... May 25 21:21:22 virusplayer volumio[1219]: info: Starting UPNP Browser May 25 21:21:22 virusplayer volumio[1219]: info: Loading plugin "alarm-clock"... May 25 21:21:22 virusplayer volumio[1219]: info: Loading plugin "airplay_emulation"... May 25 21:21:22 virusplayer volumio[1219]: info: Starting Shairport Sync May 25 21:21:22 virusplayer volumio[1219]: info: Loading plugin "last_100"... May 25 21:21:22 virusplayer volumio[1219]: info: Loading plugin "webradio"... May 25 21:21:22 virusplayer volumio[1219]: info: Loading plugin "i2s_dacs"... May 25 21:21:22 virusplayer volumio[1219]: info: I2S DAC not set, start Auto-detection May 25 21:21:22 virusplayer volumio[1219]: info: Loading plugin "volumiodiscovery"... May 25 21:21:22 virusplayer volumio[1219]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 21:21:22 virusplayer node[1219]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 21:21:22 virusplayer volumio[1219]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 21:21:22 virusplayer node[1219]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 21:21:22 virusplayer volumio[1219]: *** WARNING *** For more information see May 25 21:21:22 virusplayer node[1219]: *** WARNING *** For more information see May 25 21:21:22 virusplayer volumio[1219]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 21:21:22 virusplayer node[1219]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 21:21:22 virusplayer volumio[1219]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 21:21:22 virusplayer node[1219]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 21:21:22 virusplayer volumio[1219]: *** WARNING *** For more information see May 25 21:21:22 virusplayer node[1219]: *** WARNING *** For more information see May 25 21:21:22 virusplayer volumio[1219]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 21:21:22 virusplayer volumio[1219]: info: Discovery: Started advertising with name: Virusplayer May 25 21:21:22 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 21:21:22 virusplayer volumio[1219]: info: Loading plugin "spop"... May 25 21:21:25 virusplayer kernel: Console: switching to colour frame buffer device 128x48 May 25 21:21:25 virusplayer kernel: vc4-drm soc:gpu: [drm] fb0: vc4drmfb frame buffer device May 25 21:21:25 virusplayer volumio[1219]: info: Loading plugin "youtube2"... May 25 21:21:26 virusplayer ntpd[948]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 25 21:21:26 virusplayer ntpd[948]: DNS: dns_check: DNS error: -11, System error May 25 21:21:26 virusplayer ntpd[948]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 May 25 21:21:26 virusplayer ntpd[948]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 21:21:27 virusplayer volumio[1219]: info: Plugin ytmusic is not enabled May 25 21:21:27 virusplayer volumio[1219]: info: Loading plugin "outputs"... May 25 21:21:27 virusplayer volumio[1219]: info: Loading plugin "albumart"... May 25 21:21:27 virusplayer volumio[1219]: info: Plugin example_plugin is not enabled May 25 21:21:27 virusplayer volumio[1219]: info: Loading plugin "inputs"... May 25 21:21:27 virusplayer volumio[1219]: info: Loading plugin "updater_comm"... May 25 21:21:27 virusplayer volumio[1219]: info: Plugin mpdemulation is not enabled May 25 21:21:27 virusplayer volumio[1219]: info: Loading plugin "rest_api"... May 25 21:21:27 virusplayer volumio[1219]: info: Loading plugin "websocket"... May 25 21:21:27 virusplayer volumio[1219]: info: Starting Socket.io Server version 1.7.4 May 25 21:21:27 virusplayer volumio[1219]: info: Loading plugin "radio_browser"... May 25 21:21:28 virusplayer volumio[1317]: Forking 3 albumart workers May 25 21:21:28 virusplayer systemd[1]: systemd-fsckd.service: Deactivated successfully. May 25 21:21:29 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:29] [connect] Successful connection May 25 21:21:29 virusplayer volumio[1219]: info: Loading i18n strings for locale en May 25 21:21:30 virusplayer volumio[1219]: Updating browse sources language May 25 21:21:30 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::initPlayerControls May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 21:21:31 virusplayer volumio[1219]: Express server listening on port 3000 May 25 21:21:31 virusplayer volumio[1219]: [Metrics] WebUI: 18s 646.25ms May 25 21:21:31 virusplayer volumio[1219]: info: CoreStateMachine::resetVolumioState May 25 21:21:31 virusplayer volumio[1219]: info: CoreStateMachine::getcurrentVolume May 25 21:21:31 virusplayer volumio[1219]: info: CoreCommandRouter::volumioRetrievevolume May 25 21:21:32 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:21:32 virusplayer sudo[1387]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 21:21:32 virusplayer sudo[1387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:32 virusplayer sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 21:21:32 virusplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 25 21:21:32 virusplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 21:21:32 virusplayer sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:32 virusplayer sudo[1387]: pam_unix(sudo:session): session closed for user root May 25 21:21:32 virusplayer sudo[1389]: pam_unix(sudo:session): session closed for user root May 25 21:21:32 virusplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 21:21:32 virusplayer volumio[1219]: info: Volumio Network Manager: Network status updated: 1 May 25 21:21:32 virusplayer upmpdcli[1392]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.111.12 port 49152 IPV6 port 49152 May 25 21:21:32 virusplayer upmpdcli[1392]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 May 25 21:21:32 virusplayer upmpdcli[1392]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused May 25 21:21:32 virusplayer upmpdcli[1392]: :2:../src/main.cxx:732::MPD connection failed May 25 21:21:33 virusplayer volumio[1219]: info: CoreStateMachine::pushState May 25 21:21:33 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:21:33 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:21:33 virusplayer volumio[1219]: info: CoreCommandRouter::volumioPushState May 25 21:21:33 virusplayer volumio[1219]: info: CoreStateMachine::updateTrackBlock May 25 21:21:33 virusplayer volumio[1219]: info: CorePlayQueue::getTrackBlock May 25 21:21:33 virusplayer volumio[1219]: info: CoreCommandRouter::volumioRetrievevolume May 25 21:21:33 virusplayer volumio-remote-updater[696]: [2026-05-25 21:21:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779724289 101 May 25 21:21:33 virusplayer volumio[1219]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 May 25 21:21:34 virusplayer systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 25 21:21:34 virusplayer volumio[1219]: info: Reloading queue from file May 25 21:21:34 virusplayer volumio[1219]: info: CoreStateMachine::pushState May 25 21:21:34 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:21:34 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:21:34 virusplayer volumio[1219]: info: CoreCommandRouter::volumioPushState May 25 21:21:34 virusplayer ntpd[948]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 25 21:21:34 virusplayer ntpd[948]: DNS: dns_check: DNS error: -11, System error May 25 21:21:34 virusplayer ntpd[948]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 May 25 21:21:34 virusplayer volumio[1219]: info: Setting Device type: Raspberry PI May 25 21:21:34 virusplayer volumio[1219]: info: CoreStateMachine::setRepeat null single undefined May 25 21:21:34 virusplayer volumio[1219]: info: CoreStateMachine::pushState May 25 21:21:34 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:21:34 virusplayer volumio[1219]: info: CoreCommandRouter::volumioPushState May 25 21:21:34 virusplayer volumio[1219]: info: CoreStateMachine::setRandom true May 25 21:21:34 virusplayer volumio[1219]: info: CoreStateMachine::pushState May 25 21:21:34 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:21:34 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:21:34 virusplayer volumio[1219]: info: CoreCommandRouter::volumioPushState May 25 21:21:34 virusplayer volumio[1219]: info: Completed loading Core Plugins May 25 21:21:34 virusplayer volumio[1219]: info: Preparing to generate the ALSA configuration file May 25 21:21:34 virusplayer upmpdcli[1392]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused May 25 21:21:34 virusplayer upmpdcli[1392]: :2:../src/main.cxx:732::MPD connection failed May 25 21:21:34 virusplayer volumio[1219]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 25 21:21:34 virusplayer volumio[1219]: info: Reading ALSA contributions from plugins. May 25 21:21:35 virusplayer sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 25 21:21:35 virusplayer sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:35 virusplayer volumio[1219]: info: Asound.conf file unchanged, so no further update is needed May 25 21:21:35 virusplayer volumio[1219]: info: Output device has changed, restarting MPD May 25 21:21:35 virusplayer ntpd[948]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 21:21:35 virusplayer volumio[1219]: info: Output device has changed, restarting Shairport Sync May 25 21:21:35 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:35 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:21:35 virusplayer sudo[1426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 21:21:35 virusplayer sudo[1426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:35 virusplayer sudo[1422]: pam_unix(sudo:session): session closed for user root May 25 21:21:35 virusplayer sudo[1426]: pam_unix(sudo:session): session closed for user root May 25 21:21:35 virusplayer sudo[1430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 21:21:35 virusplayer volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 21:21:35 virusplayer sudo[1430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:35 virusplayer volumio[1219]: info: ___________ START PLUGINS ___________ May 25 21:21:35 virusplayer volumio[1219]: info: ControllerMpd::onStart: Initializing MPD May 25 21:21:35 virusplayer volumio[1219]: info: Creating MPD Configuration file May 25 21:21:35 virusplayer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 21:21:35 virusplayer systemd[1]: Starting mpd.service - Music Player Daemon... May 25 21:21:35 virusplayer sudo[1437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 25 21:21:35 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 21:21:35 virusplayer volumio[1219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 21:21:35 virusplayer volumio[1219]: info: [1779724295974] CoreMusicLibrary::Adding element Media Servers May 25 21:21:35 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 21:21:36 virusplayer sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:36 virusplayer sudo[1440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 21:21:36 virusplayer sudo[1440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:36 virusplayer sudo[1441]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 21:21:36 virusplayer sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 21:21:36 virusplayer sudo[1446]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 25 21:21:36 virusplayer sudo[1441]: pam_unix(sudo:session): session closed for user root May 25 21:21:36 virusplayer sudo[1443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 21:21:36 virusplayer sudo[1440]: pam_unix(sudo:session): session closed for user root May 25 21:21:36 virusplayer sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:36 virusplayer systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 25 21:21:36 virusplayer volumio[1219]: info: UPNP Browser: Client initialized successfully May 25 21:21:36 virusplayer sudo[1437]: pam_unix(sudo:session): session closed for user root May 25 21:21:36 virusplayer systemd[1]: mpd.service: Deactivated successfully. May 25 21:21:36 virusplayer systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 21:21:36 virusplayer systemd[1]: mpd.socket: Deactivated successfully. May 25 21:21:36 virusplayer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 21:21:36 virusplayer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 21:21:36 virusplayer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 21:21:36 virusplayer systemd[1]: Starting mpd.service - Music Player Daemon... May 25 21:21:36 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:36 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:21:36 virusplayer sudo[1456]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 21:21:36 virusplayer sudo[1456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 21:21:36 virusplayer sudo[1460]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 25 21:21:36 virusplayer sudo[1456]: pam_unix(sudo:session): session closed for user root May 25 21:21:36 virusplayer volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 21:21:36 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:36 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:21:37 virusplayer volumio5-onboarding[1449]: time=2026-05-25T21:21:37.002+05:30 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 25 21:21:37 virusplayer volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 21:21:37 virusplayer volumio[1219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 21:21:37 virusplayer volumio[1219]: info: [1779724297193] CoreMusicLibrary::Adding element Last_100 May 25 21:21:37 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 21:21:37 virusplayer volumio[1219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 21:21:37 virusplayer volumio[1219]: info: [1779724297290] CoreMusicLibrary::Adding element Webradio May 25 21:21:37 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 21:21:37 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 21:21:37 virusplayer volumio[1219]: info: Initializing BBC Radios May 25 21:21:38 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 21:21:38 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:21:38 virusplayer volumio[1332]: Starting albumart workers May 25 21:21:38 virusplayer bash[910]: setdatetime-helper: all HTTPS Date fallbacks failed May 25 21:21:38 virusplayer systemd[1]: setdatetime-helper.service: Deactivated successfully. May 25 21:21:38 virusplayer systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 25 21:21:38 virusplayer systemd[1]: setdatetime-helper.service: Consumed 1.499s CPU time. May 25 21:21:38 virusplayer systemd[1]: Reached target multi-user.target - Multi-User System. May 25 21:21:38 virusplayer systemd[1]: Reached target graphical.target - Graphical Interface. May 25 21:21:38 virusplayer volumio[1219]: info: Creating Spotify config file May 25 21:21:38 virusplayer systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 25 21:21:38 virusplayer systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 25 21:21:38 virusplayer systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 25 21:21:38 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:38 virusplayer volumio[1333]: Starting albumart workers May 25 21:21:40 virusplayer upmpdcli[1392]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout May 25 21:21:40 virusplayer upmpdcli[1392]: :2:../src/main.cxx:732::MPD connection failed May 25 21:21:41 virusplayer volumio[1338]: Starting albumart workers May 25 21:21:42 virusplayer volumio[1219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 21:21:42 virusplayer volumio[1219]: info: [1779724302249] CoreMusicLibrary::Adding element YouTube2 May 25 21:21:42 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 21:21:42 virusplayer volumio[1219]: Cannot find translation for source YouTube2 May 25 21:21:42 virusplayer volumio[1219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 21:21:42 virusplayer volumio[1219]: info: [1779724302479] CoreMusicLibrary::Adding element Radio Browser May 25 21:21:42 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 21:21:42 virusplayer volumio[1219]: Cannot find translation for source YouTube2 May 25 21:21:42 virusplayer volumio[1219]: Cannot find translation for source Radio Browser May 25 21:21:42 virusplayer volumio[1219]: info: Volumio Calling Home May 25 21:21:44 virusplayer volumio[1219]: info: MPD Permissions set May 25 21:21:44 virusplayer volumio[1219]: info: MPD Permissions set May 25 21:21:44 virusplayer volumio[1219]: info: Upmpdcli Daemon Started May 25 21:21:44 virusplayer volumio[1219]: 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 25 21:21:44 virusplayer volumio[1219]: info: Spotify config file written May 25 21:21:44 virusplayer sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 21:21:44 virusplayer sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:44 virusplayer volumio[1219]: 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 25 21:21:44 virusplayer volumio[1219]: info: Received Get System Info May 25 21:21:44 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:21:44 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:21:44 virusplayer volumio[1219]: info: Discovery: Getting this device information May 25 21:21:44 virusplayer volumio[1219]: info: CoreCommandRouter::volumioGetState May 25 21:21:44 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:21:44 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:21:45 virusplayer volumio5-onboarding[1449]: time=2026-05-25T21:21:45.023+05:30 level=INFO msg="system info for d8b4d94150f464d54b9720f8381c1d21" deviceName=Virusplayer deviceVariant=volumio deviceModel= softwareVersion=4.119 May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 25 21:21:45 virusplayer 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 25 21:21:45 virusplayer 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 25 21:21:45 virusplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 21:21:45 virusplayer go-librespot[1524]: go-librespot daemon starting... May 25 21:21:45 virusplayer sudo[1516]: pam_unix(sudo:session): session closed for user root May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer mpd[1462]: 2026-05-25T21:21:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 21:21:45 virusplayer systemd[1]: Started mpd.service - Music Player Daemon. May 25 21:21:45 virusplayer sudo[1443]: pam_unix(sudo:session): session closed for user root May 25 21:21:45 virusplayer sudo[1430]: pam_unix(sudo:session): session closed for user root May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:21:45 virusplayer volumio[1219]: info: No need to fix Spotify hosts May 25 21:21:45 virusplayer volumio[1219]: info: Completed starting Core Plugins May 25 21:21:45 virusplayer volumio[1219]: info: ------------------------------------------- May 25 21:21:45 virusplayer volumio[1219]: info: ----- MyVolumio plugins startup ---- May 25 21:21:45 virusplayer volumio[1219]: info: ------------------------------------------- May 25 21:21:45 virusplayer volumio[1219]: info: [MyVolumio PluginManager] Fetching plans data.... May 25 21:21:46 virusplayer volumio[1219]: info: Starting Shairport Sync May 25 21:21:46 virusplayer volumio[1219]: info: Starting Shairport Sync May 25 21:21:46 virusplayer go-librespot[1526]: time="2026-05-25T21:21:46+05:30" level=info msg="running go-librespot 0.7.1" May 25 21:21:46 virusplayer go-librespot[1526]: time="2026-05-25T21:21:46+05:30" level=debug msg="app state loaded" May 25 21:21:46 virusplayer go-librespot[1526]: time="2026-05-25T21:21:46+05:30" level=info msg="api server listening on 127.0.0.1:9879" May 25 21:21:46 virusplayer volumio[1219]: info: Starting Shairport Sync May 25 21:21:46 virusplayer sudo[1562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 21:21:46 virusplayer sudo[1568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 21:21:46 virusplayer sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:46 virusplayer sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:46 virusplayer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 21:21:46 virusplayer systemd[1]: shairport-sync.service: Deactivated successfully. May 25 21:21:46 virusplayer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 21:21:46 virusplayer systemd[1]: shairport-sync.service: Consumed 2.029s CPU time. May 25 21:21:46 virusplayer sudo[1567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 21:21:46 virusplayer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 21:21:46 virusplayer sudo[1568]: pam_unix(sudo:session): session closed for user root May 25 21:21:46 virusplayer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 21:21:46 virusplayer systemd[1]: shairport-sync.service: Deactivated successfully. May 25 21:21:46 virusplayer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 21:21:46 virusplayer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 21:21:46 virusplayer sudo[1567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:21:46 virusplayer sudo[1562]: pam_unix(sudo:session): session closed for user root May 25 21:21:46 virusplayer ntpd[948]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 25 21:21:46 virusplayer ntpd[948]: DNS: dns_check: DNS error: -11, System error May 25 21:21:46 virusplayer ntpd[948]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 May 25 21:21:46 virusplayer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 21:21:46 virusplayer systemd[1]: shairport-sync.service: Deactivated successfully. May 25 21:21:46 virusplayer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 21:21:46 virusplayer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 21:21:46 virusplayer sudo[1567]: pam_unix(sudo:session): session closed for user root May 25 21:21:47 virusplayer volumio[1219]: error: MPD error: The expression evaluated to a falsy value: May 25 21:21:47 virusplayer volumio[1219]: assert.ok(self.idling) May 25 21:21:47 virusplayer volumio[1219]: error: The expression evaluated to a falsy value: May 25 21:21:47 virusplayer volumio[1219]: assert.ok(self.idling) May 25 21:21:47 virusplayer volumio[1219]: error: updateQueue error: null May 25 21:21:47 virusplayer volumio[1219]: info: Shairport-Sync Started May 25 21:21:47 virusplayer volumio[1219]: Error adding Membership: Error: addMembership EINVAL May 25 21:21:47 virusplayer volumio[1219]: info: Shairport-Sync Started May 25 21:21:47 virusplayer volumio[1219]: info: Shairport-Sync Started May 25 21:21:47 virusplayer volumio[1219]: info: MPD running with PID1462 May 25 21:21:47 virusplayer volumio[1219]: ,establishing connection May 25 21:21:47 virusplayer volumio[1219]: error: updateQueue error: null May 25 21:21:48 virusplayer upmpdcli[1610]: writing RSA key May 25 21:21:48 virusplayer volumio[1219]: info: go-librespot daemon successfully initialized May 25 21:21:50 virusplayer volumio5-onboarding[1449]: time=2026-05-25T21:21:50.040+05:30 level=INFO msg="bootstrapping state" hasInternet=false May 25 21:21:50 virusplayer volumio[1219]: info: Received Get System Info May 25 21:21:50 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:21:50 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:21:50 virusplayer volumio[1219]: info: Discovery: Getting this device information May 25 21:21:50 virusplayer volumio[1219]: info: CoreCommandRouter::volumioGetState May 25 21:21:50 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:21:50 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:21:50 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 21:21:50 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 21:21:50 virusplayer volumio5-onboarding[1449]: time=2026-05-25T21:21:50.115+05:30 level=INFO msg="enabling local network discovery" May 25 21:21:50 virusplayer volumio5-onboarding[1449]: time=2026-05-25T21:21:50.149+05:30 level=INFO msg="enabling BLE discovery" May 25 21:21:51 virusplayer volumio5-onboarding[1449]: time=2026-05-25T21:21:51.242+05:30 level=INFO msg="service successfully established" component=discovery/localnet May 25 21:21:51 virusplayer volumio[1219]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 25 21:21:51 virusplayer volumio[1219]: info: Initializing connection to go-librespot Websocket May 25 21:21:56 virusplayer go-librespot[1526]: time="2026-05-25T21:21:56+05:30" 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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 21:21:56 virusplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 21:21:56 virusplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 21:21:59 virusplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 25 21:21:59 virusplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 21:21:59 virusplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 21:21:59 virusplayer go-librespot[1639]: go-librespot daemon starting... May 25 21:21:59 virusplayer go-librespot[1640]: time="2026-05-25T21:21:59+05:30" level=info msg="running go-librespot 0.7.1" May 25 21:21:59 virusplayer go-librespot[1640]: time="2026-05-25T21:21:59+05:30" level=debug msg="app state loaded" May 25 21:21:59 virusplayer go-librespot[1640]: time="2026-05-25T21:21:59+05:30" level=info msg="api server listening on 127.0.0.1:9879" May 25 21:22:02 virusplayer volumio[1219]: info: Discovery: adding 32216bbc-e58c-4c1d-a175-911f61316462 May 25 21:22:02 virusplayer volumio[1219]: info: Discovery: Found device Virusplayer May 25 21:22:02 virusplayer volumio[1219]: info: CoreCommandRouter::volumioGetState May 25 21:22:02 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:22:05 virusplayer volumio[1219]: error: MyVolumio Plugin failed to start in a timely fashion May 25 21:22:05 virusplayer volumio[1219]: [Metrics] CommandRouter: 51s 402.19ms May 25 21:22:05 virusplayer volumio[1219]: info: CoreCommandRouter::volumiosetStartupVolume May 25 21:22:05 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 21:22:05 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:22:06 virusplayer volumio[1219]: info: CoreCommandRouter::Close All Modals sent May 25 21:22:06 virusplayer volumio[1219]: info: CoreCommandRouter::Close All Modals sent May 25 21:22:07 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 25 21:22:07 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 21:22:07 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 25 21:22:10 virusplayer go-librespot[1640]: time="2026-05-25T21:22:10+05:30" 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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 21:22:10 virusplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 21:22:10 virusplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 21:22:10 virusplayer volumio[1219]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io May 25 21:22:10 virusplayer volumio[1219]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 25 21:22:11 virusplayer volumio[1219]: info: Discovery: this is already registered, 32216bbc-e58c-4c1d-a175-911f61316462 May 25 21:22:11 virusplayer volumio[1219]: info: Discovery: Found device Virusplayer May 25 21:22:11 virusplayer volumio[1219]: info: CoreCommandRouter::volumioGetState May 25 21:22:11 virusplayer volumio[1219]: info: CorePlayQueue::getTrack 0 May 25 21:22:12 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 21:22:12 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 21:22:12 virusplayer volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 21:22:12 virusplayer volumio[1219]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=d8b4d94150f464d54b9720f8381c1d21" http://updates.volumio.org/downloader-v1/track-device May 25 21:22:12 virusplayer volumio[1219]: % Total % Received % Xferd Average Speed Time Time Time Current May 25 21:22:12 virusplayer volumio[1219]: Dload Upload Total Spent Left Speed May 25 21:22:12 virusplayer volumio[1219]: [1.5K blob data] May 25 21:22:12 virusplayer volumio[1219]: retrying in 5 seconds, trial 0 May 25 21:22:12 virusplayer volumio[1219]: info: Volumio Calling Home May 25 21:22:13 virusplayer volumio[1219]: info: BOOT COMPLETED May 25 21:22:13 virusplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 25 21:22:13 virusplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 21:22:13 virusplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 21:22:13 virusplayer go-librespot[1699]: go-librespot daemon starting... May 25 21:22:13 virusplayer go-librespot[1700]: time="2026-05-25T21:22:13+05:30" level=info msg="running go-librespot 0.7.1" May 25 21:22:13 virusplayer go-librespot[1700]: time="2026-05-25T21:22:13+05:30" level=debug msg="app state loaded" May 25 21:22:13 virusplayer go-librespot[1700]: time="2026-05-25T21:22:13+05:30" level=info msg="api server listening on 127.0.0.1:9879" May 25 21:22:21 virusplayer bash[1520]: setdatetime-helper: all HTTPS Date fallbacks failed May 25 21:22:21 virusplayer systemd[1]: setdatetime-helper.service: Deactivated successfully. May 25 21:22:21 virusplayer systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 25 21:22:21 virusplayer systemd[1]: Startup finished in 15.724s (kernel) + 1min 26.059s (userspace) = 1min 41.783s. May 25 21:22:23 virusplayer go-librespot[1700]: time="2026-05-25T21:22:23+05:30" 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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 21:22:23 virusplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 21:22:23 virusplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 21:22:26 virusplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. May 25 21:22:26 virusplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 21:22:26 virusplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 21:22:26 virusplayer go-librespot[1729]: go-librespot daemon starting... May 25 21:22:26 virusplayer go-librespot[1730]: time="2026-05-25T21:22:26+05:30" level=info msg="running go-librespot 0.7.1" May 25 21:22:26 virusplayer go-librespot[1730]: time="2026-05-25T21:22:26+05:30" level=debug msg="app state loaded" May 25 21:22:26 virusplayer go-librespot[1730]: time="2026-05-25T21:22:26+05:30" level=info msg="api server listening on 127.0.0.1:9879" May 25 21:22:29 virusplayer go-librespot[1730]: time="2026-05-25T21:22:29+05:30" level=debug msg="new websocket client" May 25 21:22:29 virusplayer volumio[1219]: info: Connection to go-librespot Websocket established May 25 21:22:32 virusplayer volumio[1219]: info: Getting Spotify volume May 25 21:22:39 virusplayer go-librespot[1730]: time="2026-05-25T21:22:39+05:30" 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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 21:22:39 virusplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 21:22:39 virusplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 21:22:39 virusplayer volumio[1219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:22:39 virusplayer volumio[1219]: Error: socket hang up May 25 21:22:39 virusplayer volumio[1219]: at connResetException (node:internal/errors:720:14) May 25 21:22:39 virusplayer volumio[1219]: at Socket.socketOnEnd (node:_http_client:519:23) May 25 21:22:39 virusplayer volumio[1219]: at Socket.emit (node:events:526:35) May 25 21:22:39 virusplayer volumio[1219]: at endReadableNT (node:internal/streams/readable:1376:12) May 25 21:22:39 virusplayer volumio[1219]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 25 21:22:39 virusplayer volumio[1219]: code: 'ECONNRESET', May 25 21:22:39 virusplayer volumio[1219]: response: undefined May 25 21:22:39 virusplayer volumio[1219]: } May 25 21:22:39 virusplayer volumio[1219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:22:42 virusplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. May 25 21:22:42 virusplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 21:22:42 virusplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 21:22:42 virusplayer go-librespot[1780]: go-librespot daemon starting... May 25 21:22:42 virusplayer go-librespot[1781]: time="2026-05-25T21:22:42+05:30" level=info msg="running go-librespot 0.7.1" May 25 21:22:42 virusplayer go-librespot[1781]: time="2026-05-25T21:22:42+05:30" level=debug msg="app state loaded" May 25 21:22:42 virusplayer go-librespot[1781]: time="2026-05-25T21:22:42+05:30" level=info msg="api server listening on 127.0.0.1:9879" May 25 21:22:46 virusplayer sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 21:21' May 25 21:22:46 virusplayer sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"