Jun 27 12:50:00 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jun 27 12:50:00 volumio kernel: sr 2:0:0:0: Attached scsi generic sg1 type 5 Jun 27 12:50:00 volumio kernel: at24 0-0053: supply vcc not found, using dummy regulator Jun 27 12:50:00 volumio kernel: at24 0-0053: 256 byte spd EEPROM, read-only Jun 27 12:50:00 volumio (udev-worker)[521]: mouse0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:00 volumio systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 27 12:50:00 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jun 27 12:50:00 volumio kernel: hp_wmi: query 0xd returned error 0x5 Jun 27 12:50:00 volumio kernel: ACPI BIOS Error (bug): AE_AML_BUFFER_LIMIT, Field [RDTA] at bit offset/length 64/32 exceeds size of target Buffer (64 bits) (20240827/dsopcode-198) Jun 27 12:50:00 volumio kernel: ACPI Error: Aborting method \_SB.WMID.WMAA due to previous error (AE_AML_BUFFER_LIMIT) (20240827/psparse-529) Jun 27 12:50:01 volumio kernel: AES CTR mode by8 optimization enabled Jun 27 12:50:01 volumio systemd[1]: Found device dev-disk-by\x2duuid-53BC\x2d041C.device - WDC_WD1003FBYX-01Y7B0 boot. Jun 27 12:50:01 volumio systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-53BC\x2d041C.service - File System Check on /dev/disk/by-uuid/53BC-041C... Jun 27 12:50:01 volumio systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Jun 27 12:50:01 volumio systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Jun 27 12:50:01 volumio systemd[1]: Found device sys-subsystem-net-devices-eth0.device - ISP4032-based Ethernet IPv6 NIC. Jun 27 12:50:01 volumio systemd-fsck[645]: fsck.fat 4.2 (2021-01-31) Jun 27 12:50:01 volumio systemd-fsck[645]: /dev/sda1: 18 files, 22738/93500 clusters Jun 27 12:50:01 volumio systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-53BC\x2d041C.service - File System Check on /dev/disk/by-uuid/53BC-041C. Jun 27 12:50:01 volumio systemd[1]: Mounting boot.mount - /boot... Jun 27 12:50:01 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jun 27 12:50:01 volumio systemd[1]: Mounted boot.mount - /boot. Jun 27 12:50:01 volumio systemd[1]: Reached target local-fs.target - Local File Systems. Jun 27 12:50:01 volumio systemd[1]: Started ifup@eth0.service - ifup for eth0. Jun 27 12:50:01 volumio systemd[1]: Starting networking.service - Raise network interfaces... Jun 27 12:50:01 volumio systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Jun 27 12:50:01 volumio systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Jun 27 12:50:01 volumio systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jun 27 12:50:01 volumio systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 27 12:50:01 volumio systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Jun 27 12:50:01 volumio systemd[1]: Received SIGRTMIN+20 from PID 336 (plymouthd). Jun 27 12:50:01 volumio systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 656 (systemd-binfmt) Jun 27 12:50:01 volumio systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Jun 27 12:50:01 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 27 12:50:01 volumio (udev-worker)[514]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio (udev-worker)[513]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio (udev-worker)[519]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio (udev-worker)[531]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Jun 27 12:50:01 volumio systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Jun 27 12:50:01 volumio systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 27 12:50:01 volumio kernel: kvm_amd: TSC scaling supported Jun 27 12:50:01 volumio kernel: kvm_amd: Nested Virtualization enabled Jun 27 12:50:01 volumio kernel: kvm_amd: Nested Paging enabled Jun 27 12:50:01 volumio kernel: kvm_amd: LBR virtualization supported Jun 27 12:50:01 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 27 12:50:01 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 27 12:50:01 volumio systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Jun 27 12:50:01 volumio systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Jun 27 12:50:01 volumio systemd[1]: Starting rpcbind.service - RPC bind portmap service... Jun 27 12:50:01 volumio systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 27 12:50:01 volumio ifup[671]: command failed: No such device (-19) Jun 27 12:50:01 volumio sh[672]: command failed: No such device (-19) Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: autoconfig for 92HD73E1X5: line_outs=4 (0xc/0xf/0x10/0x11/0x0) type:speaker Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: hp_outs=1 (0xa/0x0/0x0/0x0/0x0) Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: mono: mono_out=0x0 Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: dig-out=0x22/0x0 Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: inputs: Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: Front Mic=0xb Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: Rear Mic=0xe Jun 27 12:50:01 volumio kernel: snd_hda_codec_idt hdaudioC0D3: Line=0xd Jun 27 12:50:01 volumio systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 27 12:50:01 volumio systemd[1]: Reached target sysinit.target - System Initialization. Jun 27 12:50:01 volumio systemd[1]: Started acpid.path - ACPI Events Check. Jun 27 12:50:01 volumio systemd[1]: Started apt-daily.timer - Daily apt download activities. Jun 27 12:50:01 volumio systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Jun 27 12:50:01 volumio systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Jun 27 12:50:01 volumio systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Jun 27 12:50:01 volumio systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Jun 27 12:50:01 volumio systemd[1]: Started man-db.timer - Daily man-db regeneration. Jun 27 12:50:01 volumio systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Jun 27 12:50:01 volumio systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Jun 27 12:50:01 volumio systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 27 12:50:01 volumio systemd[1]: Reached target paths.target - Path Units. Jun 27 12:50:01 volumio systemd[1]: Reached target timers.target - Timer Units. Jun 27 12:50:01 volumio kernel: input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:14.2/sound/card0/input4 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Front Mic as /devices/pci0000:00/0000:00:14.2/sound/card0/input5 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Rear Mic as /devices/pci0000:00/0000:00:14.2/sound/card0/input6 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Line as /devices/pci0000:00/0000:00:14.2/sound/card0/input7 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Speaker Front as /devices/pci0000:00/0000:00:14.2/sound/card0/input8 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Speaker Surround as /devices/pci0000:00/0000:00:14.2/sound/card0/input9 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Speaker CLFE as /devices/pci0000:00/0000:00:14.2/sound/card0/input10 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Speaker Side as /devices/pci0000:00/0000:00:14.2/sound/card0/input11 Jun 27 12:50:01 volumio kernel: input: HD-Audio Generic Front Headphone as /devices/pci0000:00/0000:00:14.2/sound/card0/input12 Jun 27 12:50:01 volumio systemd[1]: Listening on acpid.socket - ACPID Listen Socket. Jun 27 12:50:01 volumio systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Jun 27 12:50:01 volumio systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 27 12:50:01 volumio kernel: RPC: Registered named UNIX socket transport module. Jun 27 12:50:01 volumio kernel: RPC: Registered udp transport module. Jun 27 12:50:01 volumio kernel: RPC: Registered tcp transport module. Jun 27 12:50:01 volumio kernel: RPC: Registered tcp-with-tls transport module. Jun 27 12:50:01 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jun 27 12:50:01 volumio haveged[684]: haveged: command socket is listening at fd 3 Jun 27 12:50:01 volumio systemd[1]: Listening on triggerhappy.socket. Jun 27 12:50:01 volumio systemd[1]: Reached target sockets.target - Socket Units. Jun 27 12:50:01 volumio systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jun 27 12:50:01 volumio systemd[1]: Reached target basic.target - Basic System. Jun 27 12:50:01 volumio systemd[1]: Started acpid.service - ACPI event daemon. Jun 27 12:50:01 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 27 12:50:01 volumio systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 27 12:50:01 volumio systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Jun 27 12:50:01 volumio systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Jun 27 12:50:01 volumio systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Jun 27 12:50:01 volumio systemd[1]: Started iptables.service - Volumio Iptables Module. Jun 27 12:50:01 volumio ifup[733]: command failed: No such device (-19) Jun 27 12:50:01 volumio systemd[1]: Starting systemd-logind.service - User Login Management... Jun 27 12:50:01 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Jun 27 12:50:01 volumio acpid[706]: starting up with netlink and the input layer Jun 27 12:50:01 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 27 12:50:01 volumio (udev-worker)[529]: event12: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Jun 27 12:50:01 volumio dhcpcd[724]: dhcpcd-9.4.1 starting Jun 27 12:50:01 volumio sh[724]: dhcpcd-9.4.1 starting Jun 27 12:50:01 volumio (udev-worker)[519]: event9: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio (udev-worker)[522]: event6: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Jun 27 12:50:01 volumio systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Jun 27 12:50:01 volumio sh[724]: DUID 00:04:54:9a:ff:bd:a7:e2:cd:85:10:29:3a:a0:71:8f:f7:3c Jun 27 12:50:01 volumio dhcpcd[758]: DUID 00:04:54:9a:ff:bd:a7:e2:cd:85:10:29:3a:a0:71:8f:f7:3c Jun 27 12:50:01 volumio systemd[1]: Starting wireless.service - Wireless Services... Jun 27 12:50:01 volumio systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Jun 27 12:50:01 volumio (udev-worker)[524]: event10: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio systemd[1]: Reached target rpc_pipefs.target. Jun 27 12:50:01 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). Jun 27 12:50:01 volumio systemd[1]: Reached target nfs-client.target - NFS client services. Jun 27 12:50:01 volumio volumio_rfkill_unblock.sh[757]: Volumio WiFi Soft Blocked check script Jun 27 12:50:01 volumio volumio_rfkill_unblock.sh[757]: Check if rfkill is available Jun 27 12:50:01 volumio (udev-worker)[527]: event4: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio (udev-worker)[517]: event11: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:01 volumio acpid[706]: 3 rules loaded Jun 27 12:50:01 volumio acpid[706]: waiting for events: event logging is off Jun 27 12:50:02 volumio (udev-worker)[516]: event7: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:02 volumio (udev-worker)[513]: event5: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:02 volumio volumio_rfkill_unblock.sh[757]: Check if rfkill listed devices are already unblocked Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: Jun 27 12:50:02 volumio thd[748]: Unable to parse trigger line: Jun 27 12:50:02 volumio (udev-worker)[514]: event8: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 27 12:50:02 volumio systemd[1]: e2scrub_reap.service: Deactivated successfully. Jun 27 12:50:02 volumio systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Jun 27 12:50:02 volumio thd[748]: Found socket passed from systemd Jun 27 12:50:02 volumio thd[748]: Device /dev/input/event4 not suitable. Jun 27 12:50:02 volumio kernel: rt2800pci 0000:07:00.0: enabling device (0100 -> 0102) Jun 27 12:50:02 volumio kernel: ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 5390, rev 1502 detected Jun 27 12:50:02 volumio avahi-daemon[708]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 27 12:50:02 volumio avahi-daemon[708]: Successfully dropped root privileges. Jun 27 12:50:02 volumio avahi-daemon[708]: avahi-daemon 0.8 starting up. Jun 27 12:50:02 volumio kernel: ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 5390 detected Jun 27 12:50:02 volumio kernel: ieee80211 phy0: Selected rate control algorithm 'minstrel_ht' Jun 27 12:50:02 volumio (udev-worker)[529]: Network interface NamePolicy= disabled on kernel command line. Jun 27 12:50:02 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Jun 27 12:50:02 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Jun 27 12:50:02 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 27 12:50:02 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Jun 27 12:50:02 volumio volumio_rfkill_unblock.sh[757]: Wi-Fi is already unblocked. Jun 27 12:50:02 volumio systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Jun 27 12:50:02 volumio haveged[684]: haveged: ver: 1.9.14; arch: x86; vend: ; build: (gcc 12.2.0 ITV); collect: 128K Jun 27 12:50:02 volumio haveged[684]: haveged: cpu: (VC); data: 16K (D V); inst: 64K (V); idx: 39/40; sz: 53875/53875 Jun 27 12:50:02 volumio haveged[684]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99706 Jun 27 12:50:02 volumio haveged[684]: haveged: fills: 0, generated: 0 Jun 27 12:50:02 volumio systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Jun 27 12:50:02 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Jun 27 12:50:02 volumio systemd-logind[746]: New seat seat0. Jun 27 12:50:02 volumio systemd-logind[746]: Watching system buttons on /dev/input/event1 (Power Button) Jun 27 12:50:02 volumio systemd-logind[746]: Watching system buttons on /dev/input/event0 (Power Button) Jun 27 12:50:02 volumio systemd-logind[746]: Watching system buttons on /dev/input/event3 (Dell Dell USB Keyboard) Jun 27 12:50:02 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - RT5390R 802.11bgn PCIe Wireless Network Adapter. Jun 27 12:50:02 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Jun 27 12:50:02 volumio systemd[1]: Started rpcbind.service - RPC bind portmap service. Jun 27 12:50:02 volumio systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 27 12:50:02 volumio systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 27 12:50:02 volumio systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Jun 27 12:50:02 volumio systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Jun 27 12:50:02 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). Jun 27 12:50:02 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jun 27 12:50:02 volumio dhcpcd[785]: dhcpcd-9.4.1 starting Jun 27 12:50:02 volumio dhcpcd[817]: dev: loaded udev Jun 27 12:50:02 volumio dhcpcd[817]: DUID 00:04:54:9a:ff:bd:a7:e2:cd:85:10:29:3a:a0:71:8f:f7:3c Jun 27 12:50:02 volumio kernel: ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2860.bin' Jun 27 12:50:02 volumio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Jun 27 12:50:02 volumio kernel: ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.40 Jun 27 12:50:02 volumio kernel: qla3xxx 0000:02:01.0 eth0: driver lock acquired Jun 27 12:50:02 volumio kernel: qla3xxx 0000:02:01.0 eth0: Phy: PHY_AGERE_ET1011C Jun 27 12:50:02 volumio kernel: qla3xxx 0000:02:01.0 eth0: enabling Agere specific PHY Jun 27 12:50:02 volumio kernel: qla3xxx 0000:02:01.0 eth0: link master Jun 27 12:50:02 volumio kernel: qla3xxx 0000:02:01.0 eth0: Phy: PHY_AGERE_ET1011C Jun 27 12:50:02 volumio kernel: qla3xxx 0000:02:01.0 eth0: enabling Agere specific PHY Jun 27 12:50:02 volumio kernel: qla3xxx 0000:02:01.0 eth0: link master Jun 27 12:50:02 volumio systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 27 12:50:02 volumio systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Jun 27 12:50:02 volumio systemd[1]: Started systemd-logind.service - User Login Management. Jun 27 12:50:02 volumio avahi-daemon[708]: Successfully called chroot(). Jun 27 12:50:02 volumio avahi-daemon[708]: Successfully dropped remaining capabilities. Jun 27 12:50:02 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 27 12:50:02 volumio avahi-daemon[708]: No service file found in /etc/avahi/services. Jun 27 12:50:02 volumio systemd[1]: Finished networking.service - Raise network interfaces. Jun 27 12:50:02 volumio sh[938]: wlan0=wlan0 Jun 27 12:50:02 volumio loadcpufreq[798]: Loading cpufreq kernel modules...done (powernow-k8). Jun 27 12:50:02 volumio systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Jun 27 12:50:02 volumio systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Jun 27 12:50:02 volumio systemd[1]: iptables.service: Deactivated successfully. Jun 27 12:50:02 volumio (udev-worker)[523]: controlC0: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 0' failed with exit code 99. Jun 27 12:50:02 volumio systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Jun 27 12:50:02 volumio systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Jun 27 12:50:02 volumio alsactl[956]: No state is present for card Generic Jun 27 12:50:02 volumio alsactl[956]: Found hardware: "HDA-Intel" "IDT 92HD73E1X5" "HDA:111d7676,103c2ae0,00100103" "0x103c" "0x2ae0" Jun 27 12:50:02 volumio alsactl[956]: Hardware is initialized using a generic method Jun 27 12:50:02 volumio alsactl[956]: No state is present for card Generic Jun 27 12:50:02 volumio systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Jun 27 12:50:02 volumio systemd[1]: Reached target sound.target - Sound Card. Jun 27 12:50:02 volumio wpa_supplicant[887]: Successfully initialized wpa_supplicant Jun 27 12:50:02 volumio systemd[1]: Started wpa_supplicant.service - WPA supplicant. Jun 27 12:50:02 volumio systemd[1]: Reached target network.target - Network. Jun 27 12:50:02 volumio systemd[1]: Reached target network-online.target - Network is Online. Jun 27 12:50:02 volumio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Jun 27 12:50:02 volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... Jun 27 12:50:02 volumio cpufrequtils[950]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Jun 27 12:50:02 volumio systemd[1]: Starting ntpsec.service - Network Time Service... Jun 27 12:50:02 volumio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Jun 27 12:50:02 volumio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Jun 27 12:50:02 volumio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Jun 27 12:50:03 volumio sm-notify[967]: Version 2.6.2 starting Jun 27 12:50:03 volumio volumio-remote-updater[752]: Volumio OTA Client: 1.7.0 Jun 27 12:50:03 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jun 27 12:50:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 27 12:50:03 volumio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 27 12:50:03 volumio volumio-remote-updater[752]: Error: No active session Jun 27 12:50:03 volumio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Jun 27 12:50:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 27 12:50:03 volumio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Jun 27 12:50:03 volumio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Jun 27 12:50:03 volumio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Jun 27 12:50:03 volumio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 27 12:50:03 volumio ntpd[974]: INIT: ntpd ntpsec-1.2.2: Starting Jun 27 12:50:03 volumio ntpd[974]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 27 12:50:03 volumio ntp-systemd-wrapper[974]: 2025-06-27T17:50:03 ntpd[974]: INIT: ntpd ntpsec-1.2.2: Starting Jun 27 12:50:03 volumio ntp-systemd-wrapper[974]: 2025-06-27T17:50:03 ntpd[974]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 27 12:50:03 volumio dbus-daemon[710]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.4' (uid=0 pid=982 comm="timedatectl show --property=NTPSynchronized --valu") Jun 27 12:50:03 volumio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Jun 27 12:50:03 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jun 27 12:50:03 volumio systemd[1]: Started ntpsec.service - Network Time Service. Jun 27 12:50:03 volumio ifplugd(eth0)[1001]: ifplugd 0.28 initializing. Jun 27 12:50:03 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 27 12:50:03 volumio avahi-daemon[708]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 27 12:50:03 volumio avahi-daemon[708]: New relevant interface lo.IPv4 for mDNS. Jun 27 12:50:03 volumio avahi-daemon[708]: Network interface enumeration completed. Jun 27 12:50:03 volumio avahi-daemon[708]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 27 12:50:03 volumio dhcpcd[817]: eth0: waiting for carrier Jun 27 12:50:03 volumio dhcpcd[758]: eth0: waiting for carrier Jun 27 12:50:03 volumio sh[724]: eth0: waiting for carrier Jun 27 12:50:03 volumio dhcpcd[817]: eth1: waiting for carrier Jun 27 12:50:03 volumio dhcpcd[817]: wlan0: waiting for carrier Jun 27 12:50:03 volumio dhcpcd[817]: wlan0: carrier acquired Jun 27 12:50:03 volumio kernel: alx 0000:04:00.0 eth1: NIC Up: 1 Gbps Full Jun 27 12:50:03 volumio ifplugd(eth0)[1001]: Using interface eth0/00:C0:DD:0B:23:A0 with driver (version: v2.03.00-k5) Jun 27 12:50:03 volumio ifplugd(eth0)[1001]: Using detection mode: SIOCETHTOOL Jun 27 12:50:03 volumio ifplugd(eth0)[1001]: Initialization complete, link beat not detected. Jun 27 12:50:03 volumio volumio-remote-updater[752]: [2025-06-27 17:50:03] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 27 12:50:03 volumio volumio-remote-updater[752]: [2025-06-27 17:50:03] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 27 12:50:03 volumio volumio-remote-updater[752]: [2025-06-27 17:50:03] [error] handle_connect error: Connection refused Jun 27 12:50:03 volumio systemd[1]: Received SIGRTMIN+21 from PID 336 (plymouthd). Jun 27 12:50:03 volumio welcome[985]: Resolved ip:[0] Jun 27 12:50:03 volumio systemd[1]: Received SIGRTMIN+21 from PID 336 (plymouthd). Jun 27 12:50:03 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jun 27 12:50:03 volumio dhcpcd[817]: wlan0: IAID dc:66:5e:c5 Jun 27 12:50:03 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 27 12:50:03 volumio systemd[1]: Reached target getty.target - Login Prompts. Jun 27 12:50:03 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jun 27 12:50:03 volumio dhcpcd[817]: wlan0: adding address fe80::23f6:ce01:2aed:c38e Jun 27 12:50:03 volumio dbus-daemon[710]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 27 12:50:03 volumio ifplugd[961]: Network Interface Plugging Daemon...start eth0...done. Jun 27 12:50:03 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Jun 27 12:50:03 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 27 12:50:03 volumio bash[977]: Time is not synchronized. Attempting to sync... Jun 27 12:50:03 volumio dhcpcd[817]: ipv6_addaddr1: Permission denied Jun 27 12:50:03 volumio dhcpcd[817]: wlan0: carrier lost Jun 27 12:50:03 volumio upmpdcli[750]: Could not open config: /tmp/upmpdcli.conf Jun 27 12:50:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 27 12:50:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 27 12:50:03 volumio ntpd[991]: INIT: precision = 0.117 usec (-23) Jun 27 12:50:03 volumio ntpd[991]: INIT: successfully locked into RAM Jun 27 12:50:03 volumio ntpd[991]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Jun 27 12:50:03 volumio ntpd[991]: CONFIG: restrict nopeer ignored Jun 27 12:50:03 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 27 12:50:03 volumio ntpd[991]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 27 12:50:03 volumio ntpd[991]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2025-12-28T00:00Z last=2017-01-01T00:00Z ofs=37 Jun 27 12:50:03 volumio ntpd[991]: INIT: Using SO_TIMESTAMPNS(ns) Jun 27 12:50:03 volumio ntpd[991]: IO: Listen and drop on 0 v6wildcard [::]:123 Jun 27 12:50:03 volumio ntpd[991]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 27 12:50:03 volumio ntpd[991]: IO: Listen normally on 2 lo 127.0.0.1:123 Jun 27 12:50:03 volumio ntpd[991]: IO: Listening on routing socket on fd #19 for interface updates Jun 27 12:50:03 volumio ntpd[991]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jun 27 12:50:03 volumio ntpd[991]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Jun 27 12:50:03 volumio ntpd[991]: INIT: Running with OpenSSL 3.0.16 11 Feb 2025, 30000100 Jun 27 12:50:03 volumio ntpd[991]: NTSc: Using system default root certificates. Jun 27 12:50:03 volumio bash[977]: Sync attempt failed. Jun 27 12:50:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 27 12:50:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 27 12:50:03 volumio ntpd[991]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Jun 27 12:50:03 volumio dhcpcd[817]: eth1: carrier acquired Jun 27 12:50:03 volumio dhcpcd[817]: eth1: IAID b5:c3:44:80 Jun 27 12:50:03 volumio dhcpcd[817]: eth1: adding address fe80::91f1:47fc:bf3a:d5c0 Jun 27 12:50:03 volumio dhcpcd[817]: ipv6_addaddr1: Permission denied Jun 27 12:50:04 volumio avahi-daemon[708]: Server startup complete. Host name is volumio.local. Local service cookie is 1977085380. Jun 27 12:50:04 volumio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Jun 27 12:50:04 volumio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Jun 27 12:50:04 volumio dhcpcd[817]: eth1: soliciting an IPv6 router Jun 27 12:50:04 volumio ntpd[991]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:04 volumio ntpd[991]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 27 12:50:04 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Jun 27 12:50:04 volumio ntpd[991]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jun 27 12:50:04 volumio nmbd[1050]: [2025/06/27 17:50:04.600406, 0] ../../source3/nmbd/nmbd.c:901(main) Jun 27 12:50:04 volumio nmbd[1050]: nmbd version 4.17.12-Debian started. Jun 27 12:50:04 volumio nmbd[1050]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 27 12:50:04 volumio nmbd[1050]: [2025/06/27 17:50:04.611638, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 27 12:50:04 volumio nmbd[1050]: started asyncdns process 1058 Jun 27 12:50:04 volumio nmbd[1050]: [2025/06/27 17:50:04.613241, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jun 27 12:50:04 volumio nmbd[1050]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 27 12:50:04 volumio nmbd[1050]: [2025/06/27 17:50:04.613311, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jun 27 12:50:04 volumio nmbd[1050]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 27 12:50:04 volumio sudo[1061]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 27 12:50:04 volumio sudo[1061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:04 volumio sudo[1061]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:04 volumio sudo[1063]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 27 12:50:04 volumio sudo[1063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:04 volumio sudo[1063]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:04 volumio dhcpcd[817]: eth1: soliciting a DHCP lease Jun 27 12:50:04 volumio sudo[1072]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 27 12:50:04 volumio sudo[1072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:04 volumio sudo[1072]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:04 volumio sudo[1074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 27 12:50:04 volumio sudo[1074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:04 volumio sudo[1074]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:04 volumio sudo[1082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 27 12:50:04 volumio sudo[1082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:04 volumio sudo[1082]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:04 volumio sudo[1084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 27 12:50:04 volumio sudo[1084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:05 volumio ntpd[991]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:05 volumio ntpd[991]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 27 12:50:05 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Jun 27 12:50:05 volumio ntpd[991]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jun 27 12:50:06 volumio sudo[1084]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:06 volumio sudo[1091]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 27 12:50:06 volumio sudo[1091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:06 volumio sudo[1091]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:06 volumio sudo[1093]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Jun 27 12:50:06 volumio sudo[1093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:06 volumio sudo[1093]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:06 volumio sudo[1100]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 27 12:50:06 volumio sudo[1100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:06 volumio sudo[1100]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:06 volumio sudo[1102]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 27 12:50:06 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:06 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:06 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 27 12:50:06 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 27 12:50:06 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 27 12:50:06 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 27 12:50:06 volumio avahi-daemon[708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:06 volumio avahi-daemon[708]: New relevant interface wlan0.IPv4 for mDNS. Jun 27 12:50:06 volumio avahi-daemon[708]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 27 12:50:06 volumio welcome[1107]: Resolved ip:[1] 192.168.211.1 Jun 27 12:50:06 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 27 12:50:06 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:06 volumio systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jun 27 12:50:06 volumio avahi-daemon[708]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 27 12:50:06 volumio avahi-daemon[708]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:06 volumio avahi-daemon[708]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 27 12:50:06 volumio avahi-daemon[708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:06 volumio avahi-daemon[708]: New relevant interface wlan0.IPv4 for mDNS. Jun 27 12:50:06 volumio avahi-daemon[708]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 27 12:50:06 volumio dhcpcd[817]: wlan0: carrier acquired Jun 27 12:50:06 volumio dhcpcd[817]: wlan0: IAID dc:66:5e:c5 Jun 27 12:50:06 volumio hostapd[1114]: wlan0: interface state UNINITIALIZED->ENABLED Jun 27 12:50:06 volumio hostapd[1114]: wlan0: AP-ENABLED Jun 27 12:50:06 volumio systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jun 27 12:50:06 volumio systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jun 27 12:50:06 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Jun 27 12:50:06 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jun 27 12:50:06 volumio iwconfig[1137]: Error for wireless request "Set Power Management" (8B2C) : Jun 27 12:50:06 volumio iwconfig[1137]: SET failed on device wlan0 ; Invalid argument. Jun 27 12:50:06 volumio systemd[1]: wireless.service: Deactivated successfully. Jun 27 12:50:06 volumio systemd[1]: Started wireless.service - Wireless Services. Jun 27 12:50:06 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jun 27 12:50:06 volumio systemd[1]: Started firststart.service - First Start. Jun 27 12:50:06 volumio systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Jun 27 12:50:06 volumio systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Jun 27 12:50:06 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Jun 27 12:50:06 volumio dhcpcd[817]: wlan0: soliciting an IPv6 router Jun 27 12:50:06 volumio firststart.sh[1140]: Volumio first start configuration script Jun 27 12:50:06 volumio firststart.sh[1140]: Configuring unconfigured packages Jun 27 12:50:06 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jun 27 12:50:06 volumio ntpd[991]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:06 volumio ntpd[991]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 27 12:50:06 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Jun 27 12:50:06 volumio ntpd[991]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jun 27 12:50:06 volumio volumio-cpu-tweak[1155]: Setting RT Priority for mpd Jun 27 12:50:06 volumio volumio-cpu-tweak[1180]: pid 35's current scheduling policy: SCHED_OTHER Jun 27 12:50:06 volumio volumio-cpu-tweak[1180]: pid 35's current scheduling priority: 0 Jun 27 12:50:06 volumio volumio-cpu-tweak[1155]: Not enough cores to set MPD affinity Jun 27 12:50:06 volumio volumio-cpu-tweak[1155]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 27 12:50:06 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jun 27 12:50:06 volumio dnsmasq[1211]: started, version 2.90 cachesize 150 Jun 27 12:50:06 volumio dnsmasq[1211]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Jun 27 12:50:06 volumio dnsmasq-dhcp[1211]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jun 27 12:50:06 volumio dnsmasq[1211]: reading /etc/resolv.conf Jun 27 12:50:06 volumio dnsmasq[1211]: using nameserver 208.67.222.222#53 Jun 27 12:50:06 volumio dnsmasq[1211]: using nameserver 208.67.220.220#53 Jun 27 12:50:06 volumio dnsmasq[1211]: read /etc/hosts - 16 names Jun 27 12:50:06 volumio systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jun 27 12:50:06 volumio systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 27 12:50:06 volumio dhcpcd[817]: wlan0: soliciting a DHCP lease Jun 27 12:50:06 volumio startx[1225]: xauth: file /root/.Xauthority does not exist Jun 27 12:50:07 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Jun 27 12:50:07 volumio soundcard-init.sh[1238]: Simple mixer control 'Master',0 Jun 27 12:50:07 volumio soundcard-init.sh[1238]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Jun 27 12:50:07 volumio soundcard-init.sh[1238]: Playback channels: Mono Jun 27 12:50:07 volumio soundcard-init.sh[1238]: Limits: Playback 0 - 127 Jun 27 12:50:07 volumio soundcard-init.sh[1238]: Mono: Playback 95 [75%] [-24.00dB] [on] Jun 27 12:50:07 volumio systemd[1]: soundcard-init.service: Deactivated successfully. Jun 27 12:50:07 volumio ntpd[991]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:07 volumio ntpd[991]: IO: Listen normally on 3 wlan0 192.168.211.1:123 Jun 27 12:50:07 volumio ntpd[991]: IO: new interface(s) found: waking up resolver Jun 27 12:50:07 volumio ntpd[991]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 27 12:50:07 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Jun 27 12:50:07 volumio ntpd[991]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jun 27 12:50:07 volumio firststart.sh[1140]: Creating /var/log/samba/cores folder Jun 27 12:50:07 volumio firststart.sh[1140]: Removing default SSH host keys Jun 27 12:50:07 volumio winbindd[1166]: [2025/06/27 17:50:07.687857, 0] ../../source3/winbindd/winbindd.c:1440(main) Jun 27 12:50:07 volumio winbindd[1166]: winbindd version 4.17.12-Debian started. Jun 27 12:50:07 volumio winbindd[1166]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 27 12:50:07 volumio firststart.sh[1243]: removed '/etc/ssh/ssh_host_ecdsa_key' Jun 27 12:50:07 volumio firststart.sh[1243]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Jun 27 12:50:07 volumio firststart.sh[1243]: removed '/etc/ssh/ssh_host_ed25519_key' Jun 27 12:50:07 volumio firststart.sh[1243]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Jun 27 12:50:07 volumio firststart.sh[1243]: removed '/etc/ssh/ssh_host_rsa_key' Jun 27 12:50:07 volumio firststart.sh[1243]: removed '/etc/ssh/ssh_host_rsa_key.pub' Jun 27 12:50:07 volumio firststart.sh[1140]: Generating SSH host keys Jun 27 12:50:07 volumio winbindd[1166]: [2025/06/27 17:50:07.771977, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Jun 27 12:50:07 volumio winbindd[1166]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 27 12:50:08 volumio startx[1231]: X.Org X Server 1.21.1.7 Jun 27 12:50:08 volumio startx[1231]: X Protocol Version 11, Revision 0 Jun 27 12:50:08 volumio startx[1231]: Current Operating System: Linux volumio 6.12.30-volumio #2 SMP PREEMPT_DYNAMIC Wed May 28 19:37:30 BST 2025 x86_64 Jun 27 12:50:08 volumio startx[1231]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=099cb881-5251-41ee-8c2f-9bb0d401ea48 bootpart=UUID=53BC-041C datapart=UUID=41fa85be-6224-4b97-acbd-0d5812ead1be hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Jun 27 12:50:08 volumio startx[1231]: xorg-server 2:21.1.7-3+deb12u9 (https://www.debian.org/support) Jun 27 12:50:08 volumio startx[1231]: Current version of pixman: 0.42.2 Jun 27 12:50:08 volumio startx[1231]: Before reporting problems, check http://wiki.x.org Jun 27 12:50:08 volumio startx[1231]: to make sure that you have the latest version. Jun 27 12:50:08 volumio startx[1231]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 27 12:50:08 volumio startx[1231]: (++) from command line, (!!) notice, (II) informational, Jun 27 12:50:08 volumio startx[1231]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 27 12:50:08 volumio startx[1231]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jun 27 17:50:08 2025 Jun 27 12:50:08 volumio startx[1231]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 27 12:50:08 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Jun 27 12:50:08 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Jun 27 12:50:08 volumio volumio-remote-updater[752]: [2025-06-27 17:50:08] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 27 12:50:08 volumio volumio-remote-updater[752]: [2025-06-27 17:50:08] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 27 12:50:08 volumio volumio-remote-updater[752]: [2025-06-27 17:50:08] [error] handle_connect error: Connection refused Jun 27 12:50:08 volumio smbd[1251]: [2025/06/27 17:50:08.440250, 0] ../../source3/smbd/server.c:1741(main) Jun 27 12:50:08 volumio smbd[1251]: smbd version 4.17.12-Debian started. Jun 27 12:50:08 volumio smbd[1251]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 27 12:50:08 volumio ntpd[991]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:08 volumio ntpd[991]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 27 12:50:08 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Jun 27 12:50:08 volumio ntpd[991]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jun 27 12:50:08 volumio startx[1231]: (II) [KMS] Kernel modesetting enabled. Jun 27 12:50:09 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Jun 27 12:50:09 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Jun 27 12:50:09 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Jun 27 12:50:09 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 27 12:50:09 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jun 27 12:50:09 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jun 27 12:50:09 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jun 27 12:50:09 volumio setdatetime-helper.sh[1270]: Time is already synchronized. Jun 27 12:50:09 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 27 12:50:09 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 27 12:50:09 volumio systemd[1]: Startup finished in 26.141s (firmware) + 3.477s (loader) + 16.662s (kernel) + 13.535s (userspace) = 59.817s. Jun 27 12:50:09 volumio ntpd[991]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:09 volumio ntpd[991]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 27 12:50:09 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Jun 27 12:50:09 volumio ntpd[991]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jun 27 12:50:09 volumio dhcpcd[817]: eth1: probing for an IPv4LL address Jun 27 12:50:10 volumio firststart.sh[1244]: debconf: unable to initialize frontend: Dialog Jun 27 12:50:10 volumio firststart.sh[1244]: debconf: (TERM is not set, so the dialog frontend is not usable.) Jun 27 12:50:10 volumio firststart.sh[1244]: debconf: falling back to frontend: Readline Jun 27 12:50:10 volumio firststart.sh[1244]: debconf: unable to initialize frontend: Readline Jun 27 12:50:10 volumio firststart.sh[1244]: debconf: (This frontend requires a controlling tty.) Jun 27 12:50:10 volumio firststart.sh[1244]: debconf: falling back to frontend: Teletype Jun 27 12:50:10 volumio ntpd[991]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:10 volumio ntpd[991]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 27 12:50:10 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Jun 27 12:50:10 volumio ntpd[991]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jun 27 12:50:11 volumio volumio[1139]: info: ------------------------------------------- Jun 27 12:50:11 volumio volumio[1139]: info: ----- Volumio3 ---- Jun 27 12:50:11 volumio volumio[1139]: info: ------------------------------------------- Jun 27 12:50:11 volumio volumio[1139]: info: ----- System startup ---- Jun 27 12:50:11 volumio volumio[1139]: info: ------------------------------------------- Jun 27 12:50:11 volumio dhcpcd[817]: wlan0: probing for an IPv4LL address Jun 27 12:50:12 volumio firststart.sh[1311]: Creating SSH2 RSA key; this may take some time ... Jun 27 12:50:12 volumio firststart.sh[1398]: 3072 SHA256:cYNCZ6nhGBDuiPNcQ1Z2ccRAGN94W3fBBwOtclIQxSc root@volumio (RSA) Jun 27 12:50:12 volumio volumio[1139]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Jun 27 12:50:12 volumio firststart.sh[1311]: Creating SSH2 DSA key; this may take some time ... Jun 27 12:50:12 volumio firststart.sh[1402]: 1024 SHA256:FH6UuarI48FF8CF9BdcrNrjIrG2vbNa+C88ZnQuUCx0 root@volumio (DSA) Jun 27 12:50:12 volumio volumio[1139]: info: MYVOLUMIO Environment detected Jun 27 12:50:12 volumio firststart.sh[1311]: Creating SSH2 ECDSA key; this may take some time ... Jun 27 12:50:12 volumio firststart.sh[1409]: 256 SHA256:T3OZNd7Vszx8B14bAHgHlJQ+1jKILaqreOT2r2T2T9A root@volumio (ECDSA) Jun 27 12:50:12 volumio volumio[1139]: info: Plugin folders cleanup Jun 27 12:50:12 volumio volumio[1139]: info: Scanning into folder /volumio/app/plugins/ Jun 27 12:50:12 volumio volumio[1139]: info: Scanning category audio_interface Jun 27 12:50:12 volumio volumio[1139]: info: Scanning category miscellanea Jun 27 12:50:12 volumio volumio[1139]: info: Scanning category music_service Jun 27 12:50:12 volumio volumio[1139]: info: Scanning category plugins.json Jun 27 12:50:12 volumio volumio[1139]: info: Scanning category system_controller Jun 27 12:50:12 volumio volumio[1139]: info: Scanning category user_interface Jun 27 12:50:12 volumio volumio[1139]: info: Scanning into folder /data/plugins/ Jun 27 12:50:12 volumio volumio[1139]: info: Plugin folders cleanup completed Jun 27 12:50:12 volumio volumio[1139]: info: ------------------------------------------- Jun 27 12:50:12 volumio volumio[1139]: info: ----- Core plugins startup ---- Jun 27 12:50:12 volumio volumio[1139]: info: ------------------------------------------- Jun 27 12:50:12 volumio volumio[1139]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 12:50:12 volumio volumio[1139]: info: Adding plugin upnp to MyMusic Plugins Jun 27 12:50:12 volumio volumio[1139]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 12:50:12 volumio volumio[1139]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 12:50:12 volumio volumio[1139]: info: Loading plugins from folder /data/plugins/ Jun 27 12:50:12 volumio volumio[1139]: info: Loading plugin "system"... Jun 27 12:50:12 volumio volumio[1139]: info: No id defined. Creating one Jun 27 12:50:12 volumio volumio[1139]: info: Loading plugin "appearance"... Jun 27 12:50:12 volumio firststart.sh[1422]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Jun 27 12:50:12 volumio firststart.sh[1422]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Jun 27 12:50:12 volumio firststart.sh[1422]: insserv: Script `ssh' has overlapping Default-Start and Default-Stop runlevels (2 3 4 5) and (2 3 4 5). This should be fixed. Jun 27 12:50:12 volumio firststart.sh[1422]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Jun 27 12:50:12 volumio systemd[1]: Reloading. Jun 27 12:50:12 volumio systemd-gpt-auto-generator[1452]: EFI loader partition unknown, exiting. Jun 27 12:50:12 volumio systemd-gpt-auto-generator[1452]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Jun 27 12:50:13 volumio systemd[1]: Reloading. Jun 27 12:50:13 volumio systemd-gpt-auto-generator[1496]: EFI loader partition unknown, exiting. Jun 27 12:50:13 volumio systemd-gpt-auto-generator[1496]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Jun 27 12:50:13 volumio volumio[1139]: info: Loading plugin "network"... Jun 27 12:50:13 volumio volumio[1139]: info: Refreshing Cached IP Addresses Jun 27 12:50:13 volumio sudo[1506]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 12:50:13 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:13 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:13 volumio sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 12:50:13 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:13 volumio volumio[1139]: info: Loading plugin "services"... Jun 27 12:50:13 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:13 volumio volumio[1139]: info: Loading plugin "alsa_controller"... Jun 27 12:50:13 volumio sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 12:50:13 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:13 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:13 volumio volumio[1139]: warn: The ALSA output card is not set, defaulting to card [object Object] Jun 27 12:50:13 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 12:50:13 volumio volumio[1139]: info: Found match in Cards Database: setting mixer for card Analog Out Jun 27 12:50:13 volumio volumio[1139]: info: Setting mixer Master for card Analog Out Jun 27 12:50:13 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 27 12:50:13 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 27 12:50:13 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 27 12:50:13 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 12:50:13 volumio volumio[1139]: info: Loading plugin "wizard"... Jun 27 12:50:13 volumio volumio[1139]: info: Loading plugin "networkfs"... Jun 27 12:50:14 volumio volumio[1139]: info: Starting Udev Watcher for removable devices Jun 27 12:50:14 volumio volumio[1139]: info: Ignoring mount for partition: boot Jun 27 12:50:14 volumio volumio[1139]: info: Ignoring mount for partition: volumio Jun 27 12:50:14 volumio volumio[1139]: info: Ignoring mount for partition: volumio_data Jun 27 12:50:14 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 12:50:14 volumio volumio[1139]: info: Loading plugin "volumio_command_line_client"... Jun 27 12:50:14 volumio volumio[1139]: info: Loading plugin "upnp"... Jun 27 12:50:14 volumio volumio[1139]: info: [1751046614027] Starting Upmpd Daemon Jun 27 12:50:14 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 12:50:14 volumio volumio[1139]: info: Loading plugin "my_music"... Jun 27 12:50:14 volumio volumio[1139]: info: Loading plugin "mpd"... Jun 27 12:50:14 volumio firststart.sh[1554]: rescue-ssh.target is a disabled or a static unit not running, not starting it. Jun 27 12:50:14 volumio firststart.sh[1554]: ssh.service is a disabled or a static unit not running, not starting it. Jun 27 12:50:14 volumio volumio[1139]: info: Loading plugin "upnp_browser"... Jun 27 12:50:14 volumio systemd[1]: Reloading. Jun 27 12:50:14 volumio systemd-gpt-auto-generator[1572]: EFI loader partition unknown, exiting. Jun 27 12:50:14 volumio systemd-gpt-auto-generator[1572]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Jun 27 12:50:14 volumio volumio[1139]: info: Loading plugin "alarm-clock"... Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "airplay_emulation"... Jun 27 12:50:15 volumio volumio[1139]: info: Starting Shairport Sync Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "last_100"... Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "webradio"... Jun 27 12:50:15 volumio firststart.sh[1581]: ssh.socket is a disabled or a static unit not running, not starting it. Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "i2s_dacs"... Jun 27 12:50:15 volumio volumio[1139]: info: I2S DAC not set, start Auto-detection Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "volumiodiscovery"... Jun 27 12:50:15 volumio volumio[1139]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 12:50:15 volumio volumio[1139]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 12:50:15 volumio volumio[1139]: *** WARNING *** For more information see Jun 27 12:50:15 volumio volumio[1139]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 12:50:15 volumio volumio[1139]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 12:50:15 volumio volumio[1139]: *** WARNING *** For more information see Jun 27 12:50:15 volumio node[1139]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 12:50:15 volumio node[1139]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 12:50:15 volumio node[1139]: *** WARNING *** For more information see Jun 27 12:50:15 volumio node[1139]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 12:50:15 volumio node[1139]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 12:50:15 volumio node[1139]: *** WARNING *** For more information see Jun 27 12:50:15 volumio volumio[1139]: info: Discovery: Started advertising with name: Volumio Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "outputs"... Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "albumart"... Jun 27 12:50:15 volumio volumio[1139]: info: Plugin example_plugin is not enabled Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "inputs"... Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "updater_comm"... Jun 27 12:50:15 volumio volumio[1589]: Forking 1 albumart workers Jun 27 12:50:15 volumio volumio[1139]: info: Plugin mpdemulation is not enabled Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "rest_api"... Jun 27 12:50:15 volumio volumio[1139]: info: Loading plugin "websocket"... Jun 27 12:50:15 volumio firststart.sh[1140]: Enabling SSH for first boot Jun 27 12:50:15 volumio volumio[1139]: info: Starting Socket.io Server version 1.7.4 Jun 27 12:50:15 volumio volumio[1139]: info: Loading i18n strings for locale en Jun 27 12:50:15 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Jun 27 12:50:15 volumio dhcpcd[817]: eth1: using IPv4LL address 169.254.41.5 Jun 27 12:50:15 volumio avahi-daemon[708]: Joining mDNS multicast group on interface eth1.IPv4 with address 169.254.41.5. Jun 27 12:50:15 volumio avahi-daemon[708]: New relevant interface eth1.IPv4 for mDNS. Jun 27 12:50:15 volumio avahi-daemon[708]: Registering new address record for 169.254.41.5 on eth1.IPv4. Jun 27 12:50:15 volumio dhcpcd[817]: eth1: adding route to 169.254.0.0/16 Jun 27 12:50:15 volumio dhcpcd[817]: eth1: adding default route Jun 27 12:50:15 volumio volumio[1139]: Updating browse sources language Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:15 volumio systemd[1]: Reached target ip-changed@eth1.target - IP Address changed on eth1. Jun 27 12:50:15 volumio sshd[1620]: Server listening on 0.0.0.0 port 22. Jun 27 12:50:15 volumio sshd[1620]: Server listening on :: port 22. Jun 27 12:50:15 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Jun 27 12:50:15 volumio firststart.sh[1140]: Disabling firststart service Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::initPlayerControls Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:15 volumio systemctl[1621]: Removed "/etc/systemd/system/multi-user.target.wants/firststart.service". Jun 27 12:50:15 volumio systemd[1]: Reloading. Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: Express server listening on port 3000 Jun 27 12:50:15 volumio volumio[1139]: [Metrics] WebUI: 4s 750.60ms Jun 27 12:50:15 volumio volumio[1139]: info: Setting Device type: x86 Jun 27 12:50:15 volumio volumio[1139]: info: CoreStateMachine::resetVolumioState Jun 27 12:50:15 volumio volumio[1139]: info: CoreStateMachine::getcurrentVolume Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 12:50:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:15 volumio volumio[1139]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 27 12:50:15 volumio volumio[1139]: info: Completed loading Core Plugins Jun 27 12:50:15 volumio volumio[1139]: info: Preparing to generate the ALSA configuration file Jun 27 12:50:15 volumio volumio[1139]: info: Cannot read play queue from file Jun 27 12:50:15 volumio volumio[1139]: info: Volumio Network Manager: Network status updated: 0 Jun 27 12:50:15 volumio volumio[1139]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 27 12:50:15 volumio volumio[1139]: wlan0 Interface doesn't support scanning : Operation not supported Jun 27 12:50:15 volumio volumio[1139]: info: Cannot use regular scanning, forcing with ap-force Jun 27 12:50:15 volumio volumio[1599]: Starting albumart workers Jun 27 12:50:15 volumio sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 27 12:50:15 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:15 volumio systemd-gpt-auto-generator[1629]: EFI loader partition unknown, exiting. Jun 27 12:50:15 volumio systemd-gpt-auto-generator[1629]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Jun 27 12:50:15 volumio volumio-remote-updater[752]: [2025-06-27 17:50:15] [connect] Successful connection Jun 27 12:50:15 volumio firststart.sh[1140]: Finalizing Jun 27 12:50:16 volumio systemd[1]: firststart.service: Deactivated successfully. Jun 27 12:50:16 volumio systemd[1]: firststart.service: Consumed 2.663s CPU time. Jun 27 12:50:16 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:16 volumio dhcpcd[817]: eth1: offered 192.168.199.58 from 192.168.199.4 Jun 27 12:50:16 volumio dhcpcd[817]: eth1: probing address 192.168.199.58/24 Jun 27 12:50:16 volumio volumio[1139]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 27 12:50:16 volumio volumio-remote-updater[752]: [2025-06-27 17:50:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1751046615 101 Jun 27 12:50:16 volumio volumio[1139]: 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 Jun 27 12:50:16 volumio volumio[1139]: info: VolumeController:: Volume=40 Mute =false Jun 27 12:50:16 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:16 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:16 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jun 27 12:50:16 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 12:50:16 volumio volumio[1139]: info: VolumeController:: Volume=40 Mute =false Jun 27 12:50:16 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:16 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:16 volumio volumio[1139]: info: Asound.conf file written Jun 27 12:50:16 volumio dhcpcd[817]: wlan0: using IPv4LL address 169.254.24.42 Jun 27 12:50:16 volumio dhcpcd[817]: wlan0: adding route to 169.254.0.0/16 Jun 27 12:50:16 volumio avahi-daemon[708]: Registering new address record for 169.254.24.42 on wlan0.IPv4. Jun 27 12:50:16 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:16 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 27 12:50:16 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 27 12:50:16 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 27 12:50:16 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 27 12:50:16 volumio sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 27 12:50:16 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:16 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:16 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 27 12:50:16 volumio volumio[1139]: No state is present for card Generic Jun 27 12:50:16 volumio volumio[1139]: Found hardware: "HDA-Intel" "IDT 92HD73E1X5" "HDA:111d7676,103c2ae0,00100103" "0x103c" "0x2ae0" Jun 27 12:50:16 volumio volumio[1139]: Hardware is initialized using a generic method Jun 27 12:50:16 volumio volumio[1139]: No state is present for card Generic Jun 27 12:50:16 volumio volumio[1139]: info: Output device has changed, restarting MPD Jun 27 12:50:16 volumio welcome[1680]: Resolved ip:[3] 169.254.41.5 192.168.211.1 169.254.24.42 Jun 27 12:50:16 volumio sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 12:50:16 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:16 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 27 12:50:16 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:16 volumio volumio[1139]: info: Output device has changed, restarting Shairport Sync Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 12:50:16 volumio sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 12:50:16 volumio volumio[1139]: info: ___________ START PLUGINS ___________ Jun 27 12:50:16 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:16 volumio volumio[1139]: info: Setting Hotspot Unique name on first start: Volumio-75956 Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::Reload Ui Jun 27 12:50:16 volumio volumio[1139]: info: ControllerMpd::onStart: Initializing MPD Jun 27 12:50:16 volumio volumio[1139]: info: Creating MPD Configuration file Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 12:50:16 volumio sudo[1700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 12:50:16 volumio sudo[1700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:16 volumio volumio[1139]: info: [1751046616724] CoreMusicLibrary::Adding element Media Servers Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:16 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:16 volumio sudo[1700]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:16 volumio sudo[1703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 12:50:16 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 27 12:50:16 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 12:50:16 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:16 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 27 12:50:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 27 12:50:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 27 12:50:16 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 12:50:16 volumio volumio[1139]: info: [1751046616773] CoreMusicLibrary::Adding element Last_100 Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 12:50:16 volumio volumio[1139]: info: [1751046616775] CoreMusicLibrary::Adding element Webradio Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 27 12:50:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 12:50:16 volumio volumio[1139]: info: Initializing BBC Radios Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: Volumio Calling Home Jun 27 12:50:16 volumio sudo[1717]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 12:50:16 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:16 volumio sudo[1733]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 27 12:50:16 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:16 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:16 volumio volumio[1139]: info: MPD Permissions set Jun 27 12:50:16 volumio volumio[1139]: info: MPD Permissions set Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:17 volumio volumio[1139]: info: Starting Shairport Sync Jun 27 12:50:17 volumio volumio[1139]: info: Starting Shairport Sync Jun 27 12:50:17 volumio volumio[1139]: info: Starting Shairport Sync Jun 27 12:50:17 volumio sudo[1755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 12:50:17 volumio sudo[1753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 12:50:17 volumio sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:17 volumio sudo[1755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:17 volumio sudo[1757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 12:50:17 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 27 12:50:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 27 12:50:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 27 12:50:17 volumio systemd[1]: shairport-sync.service: Consumed 1.626s CPU time. Jun 27 12:50:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 27 12:50:17 volumio sudo[1753]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:17 volumio volumio[1139]: info: Shairport-Sync Started Jun 27 12:50:17 volumio sudo[1755]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:17 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:17 volumio volumio[1139]: Error adding Membership: Error: addMembership EINVAL Jun 27 12:50:17 volumio volumio[1139]: info: Shairport-Sync Started Jun 27 12:50:17 volumio volumio[1139]: info: Shairport-Sync Started Jun 27 12:50:17 volumio sudo[1777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Jun 27 12:50:17 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:17 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:17 volumio volumio[1139]: info: Permissions for /etc/hostapd/hostapd.conf Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 27 12:50:17 volumio volumio[1139]: info: Discovery: Restarting Advertising due to device name change Jun 27 12:50:17 volumio sudo[1781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 27 12:50:17 volumio sudo[1781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 27 12:50:17 volumio volumio[1139]: info: CoreStateMachine::getcurrentVolume Jun 27 12:50:17 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 12:50:17 volumio systemd[1]: Starting wireless.service - Wireless Services... Jun 27 12:50:18 volumio volumio[1139]: info: VolumeController:: Volume=46 Mute =false Jun 27 12:50:18 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:18 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:18 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 12:50:18 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:18 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jun 27 12:50:18 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jun 27 12:50:18 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 12:50:18 volumio volumio[1139]: info: VolumeController:: Volume=46 Mute =false Jun 27 12:50:18 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:18 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:18 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:18 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jun 27 12:50:18 volumio dnsmasq[1211]: exiting on receipt of SIGTERM Jun 27 12:50:18 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Jun 27 12:50:18 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jun 27 12:50:18 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jun 27 12:50:18 volumio dhcpcd[817]: wlan0: carrier lost Jun 27 12:50:18 volumio avahi-daemon[708]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 27 12:50:18 volumio avahi-daemon[708]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:18 volumio avahi-daemon[708]: Withdrawing address record for 169.254.24.42 on wlan0. Jun 27 12:50:18 volumio avahi-daemon[708]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 27 12:50:18 volumio volumio[1139]: info: Discovery: A device disappeared from network Jun 27 12:50:18 volumio volumio[1139]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Jun 27 12:50:18 volumio avahi-daemon[708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.24.42. Jun 27 12:50:18 volumio avahi-daemon[708]: New relevant interface wlan0.IPv4 for mDNS. Jun 27 12:50:18 volumio avahi-daemon[708]: Registering new address record for 169.254.24.42 on wlan0.IPv4. Jun 27 12:50:18 volumio avahi-daemon[708]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 27 12:50:18 volumio avahi-daemon[708]: Withdrawing address record for 169.254.24.42 on wlan0. Jun 27 12:50:18 volumio avahi-daemon[708]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.24.42. Jun 27 12:50:18 volumio avahi-daemon[708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:18 volumio dhcpcd[817]: wlan0: deleting route to 169.254.0.0/16 Jun 27 12:50:18 volumio systemd[1]: hostapd.service: Deactivated successfully. Jun 27 12:50:18 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jun 27 12:50:18 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:18 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 27 12:50:18 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 27 12:50:18 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 27 12:50:18 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 27 12:50:18 volumio sudo[1821]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 27 12:50:18 volumio sudo[1821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:18 volumio avahi-daemon[708]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 27 12:50:18 volumio avahi-daemon[708]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:18 volumio avahi-daemon[708]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 27 12:50:18 volumio sudo[1821]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:18 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 27 12:50:18 volumio welcome[1826]: Resolved ip:[1] 169.254.41.5 Jun 27 12:50:18 volumio sudo[1831]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 27 12:50:18 volumio sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:18 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 27 12:50:18 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:18 volumio sudo[1831]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:18 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:18 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 27 12:50:18 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 27 12:50:18 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 27 12:50:18 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 27 12:50:18 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 27 12:50:18 volumio welcome[1840]: Resolved ip:[1] 169.254.41.5 Jun 27 12:50:18 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:18 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:18 volumio volumio[1139]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jun 27 12:50:18 volumio volumio[1139]: Terminated Jun 27 12:50:18 volumio volumio[1139]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jun 27 12:50:18 volumio volumio[1139]: Terminated Jun 27 12:50:18 volumio volumio[1139]: info: Completed starting Core Plugins Jun 27 12:50:18 volumio volumio[1139]: info: ------------------------------------------- Jun 27 12:50:18 volumio volumio[1139]: info: ----- MyVolumio plugins startup ---- Jun 27 12:50:18 volumio volumio[1139]: info: ------------------------------------------- Jun 27 12:50:18 volumio volumio[1139]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 27 12:50:18 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 27 12:50:18 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:18 volumio sudo[1849]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 27 12:50:18 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:18 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:18 volumio sudo[1851]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 27 12:50:18 volumio sudo[1851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:18 volumio sudo[1851]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:18 volumio sudo[1860]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 27 12:50:18 volumio sudo[1860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:18 volumio sudo[1860]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:18 volumio sudo[1862]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 27 12:50:18 volumio sudo[1862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:18 volumio volumio[1139]: info: Discovery: A device disappeared from network Jun 27 12:50:18 volumio volumio[1139]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Jun 27 12:50:18 volumio volumio[1139]: info: Discovery: A device disappeared from network Jun 27 12:50:18 volumio volumio[1139]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Jun 27 12:50:19 volumio mpd[1735]: 2025-06-27T17:50:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 12:50:19 volumio mpd[1735]: 2025-06-27T17:50:19 exception: Database corrupted Jun 27 12:50:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 27 12:50:19 volumio volumio[1139]: error: MPD error: The expression evaluated to a falsy value: Jun 27 12:50:19 volumio volumio[1139]: assert.ok(self.idling) Jun 27 12:50:19 volumio volumio[1139]: error: The expression evaluated to a falsy value: Jun 27 12:50:19 volumio volumio[1139]: assert.ok(self.idling) Jun 27 12:50:19 volumio volumio[1139]: error: updateQueue error: null Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jun 27 12:50:19 volumio volumio[1139]: info: Jun 27 12:50:19 volumio volumio[1139]: ---------------------------- MPD announces state update: update Jun 27 12:50:19 volumio volumio[1139]: info: ControllerMpd::getState Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jun 27 12:50:19 volumio volumio[1139]: info: Jun 27 12:50:19 volumio volumio[1139]: ---------------------------- MPD announces state update: update Jun 27 12:50:19 volumio volumio[1139]: info: ControllerMpd::getState Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jun 27 12:50:19 volumio volumio[1139]: info: sendMpdCommand status took 8 milliseconds Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::parseState Jun 27 12:50:19 volumio sudo[1862]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:19 volumio sudo[1876]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 27 12:50:19 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:19 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:19 volumio sudo[1878]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 27 12:50:19 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:50:19 volumio sudo[1878]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:19 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:19 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 27 12:50:19 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 27 12:50:19 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 27 12:50:19 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 27 12:50:19 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 27 12:50:19 volumio avahi-daemon[708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:19 volumio avahi-daemon[708]: New relevant interface wlan0.IPv4 for mDNS. Jun 27 12:50:19 volumio avahi-daemon[708]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 27 12:50:19 volumio welcome[1883]: Resolved ip:[2] 169.254.41.5 192.168.211.1 Jun 27 12:50:19 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 27 12:50:19 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:19 volumio systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jun 27 12:50:19 volumio volumio[1139]: info: Command Router : Notfying DB Updatefalse Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::Close All Modals sent Jun 27 12:50:19 volumio volumio[1139]: info: sendMpdCommand status took 271 milliseconds Jun 27 12:50:19 volumio volumio[1139]: info: sendMpdCommand status took 270 milliseconds Jun 27 12:50:19 volumio volumio[1139]: info: sendMpdCommand status took 270 milliseconds Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::parseState Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::parseState Jun 27 12:50:19 volumio avahi-daemon[708]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 27 12:50:19 volumio avahi-daemon[708]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:19 volumio avahi-daemon[708]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 27 12:50:19 volumio avahi-daemon[708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 27 12:50:19 volumio avahi-daemon[708]: New relevant interface wlan0.IPv4 for mDNS. Jun 27 12:50:19 volumio avahi-daemon[708]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 27 12:50:19 volumio dhcpcd[817]: wlan0: carrier acquired Jun 27 12:50:19 volumio hostapd[1890]: wlan0: interface state UNINITIALIZED->ENABLED Jun 27 12:50:19 volumio hostapd[1890]: wlan0: AP-ENABLED Jun 27 12:50:19 volumio volumio[1139]: info: Command Router : Notfying DB Updatefalse Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::Close All Modals sent Jun 27 12:50:19 volumio systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jun 27 12:50:19 volumio volumio[1139]: verbose: ControllerMpd::parseState Jun 27 12:50:19 volumio volumio[1139]: info: ControllerMpd::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 27 12:50:19 volumio volumio[1139]: verbose: CURRENT POSITION 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::syncState stateService stop Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus stop Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:19 volumio volumio[1139]: info: No code Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:19 volumio dhcpcd[817]: wlan0: IAID dc:66:5e:c5 Jun 27 12:50:19 volumio volumio[1139]: info: ControllerMpd::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 27 12:50:19 volumio volumio[1139]: verbose: CURRENT POSITION 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::syncState stateService stop Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus stop Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:19 volumio volumio[1139]: info: No code Jun 27 12:50:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:50:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:50:19 volumio systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jun 27 12:50:19 volumio volumio[1139]: info: ------------------------------ 364ms Jun 27 12:50:19 volumio volumio[1139]: info: ------------------------------ 365ms Jun 27 12:50:19 volumio dnsmasq[1903]: started, version 2.90 cachesize 150 Jun 27 12:50:19 volumio dnsmasq[1903]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Jun 27 12:50:19 volumio iwconfig[1901]: Error for wireless request "Set Power Management" (8B2C) : Jun 27 12:50:19 volumio iwconfig[1901]: SET failed on device wlan0 ; Invalid argument. Jun 27 12:50:19 volumio systemd[1]: wireless.service: Deactivated successfully. Jun 27 12:50:19 volumio dnsmasq-dhcp[1903]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jun 27 12:50:19 volumio systemd[1]: Started wireless.service - Wireless Services. Jun 27 12:50:19 volumio dnsmasq[1903]: reading /etc/resolv.conf Jun 27 12:50:19 volumio dnsmasq[1903]: using nameserver 208.67.222.222#53 Jun 27 12:50:19 volumio dnsmasq[1903]: using nameserver 208.67.220.220#53 Jun 27 12:50:19 volumio dnsmasq[1903]: read /etc/hosts - 16 names Jun 27 12:50:19 volumio sudo[1781]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:19 volumio systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jun 27 12:50:20 volumio dhcpcd[817]: wlan0: soliciting a DHCP lease Jun 27 12:50:20 volumio dhcpcd[817]: wlan0: soliciting an IPv6 router Jun 27 12:50:21 volumio dhcpcd[817]: eth1: leased 192.168.199.58 for 40431 seconds Jun 27 12:50:21 volumio avahi-daemon[708]: Registering new address record for 192.168.199.58 on eth1.IPv4. Jun 27 12:50:21 volumio dhcpcd[817]: eth1: adding route to 192.168.199.0/24 Jun 27 12:50:21 volumio dhcpcd[817]: eth1: changing default route via 192.168.199.254 Jun 27 12:50:21 volumio systemd[1]: Stopped target ip-changed@eth1.target - IP Address changed on eth1. Jun 27 12:50:21 volumio systemd[1]: Stopping ip-changed@eth1.target - IP Address changed on eth1... Jun 27 12:50:21 volumio systemd[1]: Reached target ip-changed@eth1.target - IP Address changed on eth1. Jun 27 12:50:21 volumio dnsmasq[1903]: reading /etc/resolv.conf Jun 27 12:50:21 volumio dnsmasq[1903]: using nameserver 192.168.199.4#53 Jun 27 12:50:21 volumio dnsmasq[1903]: using nameserver 208.67.222.222#53 Jun 27 12:50:21 volumio dnsmasq[1903]: using nameserver 208.67.220.220#53 Jun 27 12:50:21 volumio avahi-daemon[708]: Withdrawing address record for 169.254.41.5 on eth1. Jun 27 12:50:21 volumio avahi-daemon[708]: Leaving mDNS multicast group on interface eth1.IPv4 with address 169.254.41.5. Jun 27 12:50:21 volumio dhcpcd[817]: eth1: deleting route to 169.254.0.0/16 Jun 27 12:50:21 volumio avahi-daemon[708]: Joining mDNS multicast group on interface eth1.IPv4 with address 192.168.199.58. Jun 27 12:50:21 volumio systemd[1]: Stopped target ip-changed@eth1.target - IP Address changed on eth1. Jun 27 12:50:21 volumio systemd[1]: Stopping ip-changed@eth1.target - IP Address changed on eth1... Jun 27 12:50:21 volumio systemd[1]: Reached target ip-changed@eth1.target - IP Address changed on eth1. Jun 27 12:50:22 volumio ntpd[991]: IO: Listen normally on 4 eth1 192.168.199.58:123 Jun 27 12:50:22 volumio ntpd[991]: IO: new interface(s) found: waking up resolver Jun 27 12:50:22 volumio ntpd[991]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:22 volumio volumio[1139]: info: Discovery: Started advertising with name: Volumio Jun 27 12:50:23 volumio ntpd[991]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 216.31.17.12 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 198.60.22.240 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 23.186.168.128 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 64.6.144.6 Jun 27 12:50:23 volumio ntpd[991]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 27 12:50:23 volumio ntpd[991]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:23 volumio ntpd[991]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 149.28.200.179 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 5.161.111.190 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 23.186.168.129 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 45.79.214.107 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 2604:4500:6:7c9::186 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 2620:149:a23:3000::1f2 Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 240b:4002:100:9f00:5bd1:9512:8a8b:25e Jun 27 12:50:23 volumio ntpd[991]: DNS: Pool taking: 2001:470:1f0e:3e0::2 Jun 27 12:50:23 volumio ntpd[991]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 27 12:50:23 volumio volumio[1139]: info: Volumio Network Manager: Network status updated: 1 Jun 27 12:50:24 volumio sudo[1966]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 12:50:24 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:24 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:24 volumio sudo[1968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 12:50:24 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:24 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:24 volumio sudo[1972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 27 12:50:24 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 27 12:50:24 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:24 volumio volumio[1139]: info: Upmpdcli Daemon Started Jun 27 12:50:24 volumio volumio[1139]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 27 12:50:24 volumio ntpd[991]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: adding a6e2af3d-f3ed-4254-a692-d5dcf109c6e1 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: Found device Volumio Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: this is already registered, a6e2af3d-f3ed-4254-a692-d5dcf109c6e1 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: Found device Volumio Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: this is already registered, a6e2af3d-f3ed-4254-a692-d5dcf109c6e1 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: Found device Volumio Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:24 volumio volumio[1139]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: this is already registered, a6e2af3d-f3ed-4254-a692-d5dcf109c6e1 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: Found device Volumio Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: this is already registered, a6e2af3d-f3ed-4254-a692-d5dcf109c6e1 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: Found device Volumio Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: this is already registered, a6e2af3d-f3ed-4254-a692-d5dcf109c6e1 Jun 27 12:50:24 volumio volumio[1139]: info: Discovery: Found device Volumio Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:24 volumio ntpd[991]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 27 12:50:24 volumio ntpd[991]: DNS: Pool taking: 193.29.63.226 Jun 27 12:50:24 volumio ntpd[991]: DNS: Pool taking: 23.186.168.125 Jun 27 12:50:24 volumio ntpd[991]: DNS: Pool taking: 23.168.24.210 Jun 27 12:50:24 volumio ntpd[991]: DNS: Pool taking: 216.240.36.24 Jun 27 12:50:24 volumio ntpd[991]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 27 12:50:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 27 12:50:24 volumio sudo[1994]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 12:50:24 volumio sudo[1994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:24 volumio sudo[1994]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:25 volumio dhcpcd[817]: wlan0: probing for an IPv4LL address Jun 27 12:50:25 volumio ntpd[991]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 27 12:50:25 volumio ntpd[991]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 27 12:50:25 volumio ntpd[991]: DNS: Pool taking: 162.159.200.123 Jun 27 12:50:25 volumio ntpd[991]: DNS: Pool taking: 142.202.190.19 Jun 27 12:50:25 volumio ntpd[991]: DNS: Pool taking: 23.150.40.242 Jun 27 12:50:25 volumio ntpd[991]: DNS: Pool skipping: 193.29.63.226 Jun 27 12:50:25 volumio ntpd[991]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 27 12:50:27 volumio volumio[1139]: info: Adding plugin bluetooth to MyMusic Plugins Jun 27 12:50:27 volumio volumio[1139]: info: Adding plugin multiroom to MyMusic Plugins Jun 27 12:50:27 volumio volumio[1139]: info: Adding plugin metavolumio to MyMusic Plugins Jun 27 12:50:27 volumio volumio[1139]: info: Adding plugin cd_controller to MyMusic Plugins Jun 27 12:50:27 volumio volumio[1139]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 27 12:50:27 volumio volumio[1139]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 27 12:50:27 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:27 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:27 volumio volumio[1139]: info: Starting MyVolumio Remote Streaming Endpoints Jun 27 12:50:27 volumio volumio[1139]: info: MyVolumio not started Jun 27 12:50:27 volumio volumio[1139]: info: Initializing device activation check Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 27 12:50:27 volumio volumio[1139]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 27 12:50:27 volumio upmpdcli[2001]: writing RSA key Jun 27 12:50:28 volumio volumio[1139]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 27 12:50:28 volumio volumio[1139]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 27 12:50:28 volumio volumio[1139]: info: Streaming services startup Jun 27 12:50:28 volumio volumio[1139]: info: Starting Streaming Daemon Jun 27 12:50:28 volumio volumio[1139]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 27 12:50:28 volumio sudo[2006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 12:50:28 volumio sudo[2006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:28 volumio sudo[2006]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:28 volumio volumio[1139]: error: Cannot start Volumio Streaming Daemon Jun 27 12:50:28 volumio volumio[1139]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 12:50:28 volumio volumio[1139]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 12:50:29 volumio ntpd[991]: CLOCK: time stepped by -0.428018 Jun 27 12:50:29 volumio ntpd[991]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jun 27 12:50:29 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 27 12:50:29 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 27 12:50:29 volumio volumio[1139]: info: Refreshing Cached IP Addresses Jun 27 12:50:29 volumio sudo[2047]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 27 12:50:29 volumio sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 12:50:29 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:29 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:29 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:29 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:29 volumio sudo[2051]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 12:50:29 volumio sudo[2051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:29 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Jun 27 12:50:29 volumio systemd[1]: upmpdcli.service: Consumed 3.844s CPU time. Jun 27 12:50:29 volumio sudo[2051]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:30 volumio dhcpcd[817]: wlan0: using IPv4LL address 169.254.24.42 Jun 27 12:50:30 volumio avahi-daemon[708]: Registering new address record for 169.254.24.42 on wlan0.IPv4. Jun 27 12:50:30 volumio dhcpcd[817]: wlan0: adding route to 169.254.0.0/16 Jun 27 12:50:30 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:30 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 27 12:50:30 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 27 12:50:30 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 27 12:50:30 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 27 12:50:30 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 27 12:50:30 volumio welcome[2064]: Resolved ip:[3] 192.168.199.58 192.168.211.1 169.254.24.42 Jun 27 12:50:30 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 27 12:50:30 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 27 12:50:30 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Jun 27 12:50:32 volumio ntpd[991]: IO: Listen normally on 5 wlan0 169.254.24.42:123 Jun 27 12:50:32 volumio ntpd[991]: IO: new interface(s) found: waking up resolver Jun 27 12:50:32 volumio dhcpcd[758]: timed out Jun 27 12:50:32 volumio sh[724]: timed out Jun 27 12:50:32 volumio dhcpcd[758]: dhcpcd exited Jun 27 12:50:32 volumio sh[654]: ifup: failed to bring up eth0 Jun 27 12:50:32 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 27 12:50:32 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 27 12:50:34 volumio volumio[1139]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.005&uuid=75956f7cda7339496831188aaf54d3cc" http://updates.volumio.org/downloader-v1/track-device Jun 27 12:50:34 volumio volumio[1139]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 27 12:50:34 volumio volumio[1139]: Dload Upload Total Spent Left Speed Jun 27 12:50:34 volumio volumio[1139]: [764B blob data] Jun 27 12:50:34 volumio volumio[1139]: retrying in 5 seconds, trial 0 Jun 27 12:50:34 volumio volumio[1139]: info: Volumio Calling Home Jun 27 12:50:34 volumio volumio[1139]: info: Volumio called home Jun 27 12:50:35 volumio dbus-daemon[710]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.23' (uid=0 pid=2026 comm="/usr/lib/chromium/chromium --show-component-extens") Jun 27 12:50:35 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... Jun 27 12:50:35 volumio kernel: Bluetooth: Core ver 2.22 Jun 27 12:50:35 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Jun 27 12:50:35 volumio kernel: Bluetooth: HCI device and connection manager initialized Jun 27 12:50:35 volumio kernel: Bluetooth: HCI socket layer initialized Jun 27 12:50:35 volumio kernel: Bluetooth: L2CAP socket layer initialized Jun 27 12:50:35 volumio kernel: Bluetooth: SCO socket layer initialized Jun 27 12:50:35 volumio bluetoothd[2135]: Bluetooth daemon 5.72 Jun 27 12:50:35 volumio dbus-daemon[710]: [system] Successfully activated service 'org.bluez' Jun 27 12:50:35 volumio systemd[1]: Started bluetooth.service - Bluetooth service. Jun 27 12:50:35 volumio bluetoothd[2135]: Starting SDP server Jun 27 12:50:35 volumio bluetoothd[2135]: Excluding (cli) wiimote Jun 27 12:50:35 volumio bluetoothd[2135]: Excluding (cli) sap Jun 27 12:50:35 volumio bluetoothd[2135]: Excluding (cli) midi Jun 27 12:50:35 volumio bluetoothd[2135]: Excluding (cli) battery Jun 27 12:50:35 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 27 12:50:35 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jun 27 12:50:35 volumio kernel: Bluetooth: BNEP socket layer initialized Jun 27 12:50:35 volumio bluetoothd[2135]: Bluetooth management interface 1.23 initialized Jun 27 12:50:35 volumio dbus-daemon[710]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.24' (uid=0 pid=2135 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Jun 27 12:50:35 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jun 27 12:50:35 volumio dbus-daemon[710]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 27 12:50:35 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jun 27 12:50:39 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 27 12:50:39 volumio sudo[2260]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 12:50:39 volumio sudo[2258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 12:50:39 volumio sudo[2262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 27 12:50:39 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:39 volumio sudo[2258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:39 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:39 volumio sudo[2258]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:39 volumio sudo[2260]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 27 12:50:39 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:39 volumio volumio[1139]: info: Upmpdcli Daemon Started Jun 27 12:50:39 volumio upmpdcli[2280]: writing RSA key Jun 27 12:50:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 12:50:42 volumio volumio[1139]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 27 12:50:42 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:42 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:42 volumio volumio[1139]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 27 12:50:43 volumio volumio[1139]: info: Received Get System Info Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 12:50:43 volumio volumio[1139]: info: Discovery: Getting this device information Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:43 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 12:50:43 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:50:43 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:50:43 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:43 volumio volumio[1139]: info: Retrieving Cloud Streaming UI Jun 27 12:50:43 volumio volumio[1139]: info: Getting Tidal Cloud Configuration Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:43 volumio volumio[1139]: info: Getting Qobuz Cloud Configuration Jun 27 12:50:43 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:43 volumio volumio[1139]: info: Getting Spotify Cloud Configuration Jun 27 12:50:43 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:43 volumio volumio[1139]: info: Saving Spotify Acccount Jun 27 12:50:43 volumio volumio[1139]: info: Got Tidal Cloud Configuration Jun 27 12:50:43 volumio volumio[1139]: info: Got it Jun 27 12:50:43 volumio volumio[1139]: info: Got it Jun 27 12:50:43 volumio volumio[1139]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:43 volumio volumio[1139]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 27 12:50:43 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:50:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:50:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 27 12:50:44 volumio volumio[1139]: info: Loading i18n strings for locale en Jun 27 12:50:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 27 12:50:44 volumio volumio[1139]: info: Setting timezone to UTC Jun 27 12:50:44 volumio sudo[2336]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 27 12:50:44 volumio sudo[2336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:44 volumio sudo[2336]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:44 volumio sudo[2340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jun 27 12:50:44 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:44 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:44 volumio sudo[2344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 27 12:50:44 volumio sudo[2344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:44 volumio sudo[2344]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:44 volumio sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jun 27 12:50:44 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:44 volumio dbus-daemon[710]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=2349 comm="/usr/bin/timedatectl set-timezone UTC") Jun 27 12:50:44 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 27 12:50:44 volumio dbus-daemon[710]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 27 12:50:44 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 27 12:50:44 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 27 12:50:44 volumio volumio[1139]: Updating browse sources language Jun 27 12:50:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:44 volumio volumio[1139]: info: Fetching Streaming Services browse cache Jun 27 12:50:46 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 12:50:46 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:46 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 12:50:46 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:50:46 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:50:46 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:50:46 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 27 12:50:46 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:50:46 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 12:50:47 volumio volumio[1139]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 27 12:50:47 volumio volumio[1139]: info: Completed starting MyVolumio Plugin Jun 27 12:50:47 volumio volumio[1139]: [Metrics] CommandRouter: 36s 615.40ms Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::volumiosetStartupVolume Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::Close All Modals sent Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::Close All Modals sent Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Retrieving Cloud Streaming UI Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Retrieving Cloud Streaming UI Jun 27 12:50:47 volumio volumio[1139]: info: Getting Tidal Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Getting Qobuz Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Getting Spotify Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Saving Spotify Acccount Jun 27 12:50:47 volumio volumio[1139]: info: Got Tidal Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: Got it Jun 27 12:50:47 volumio volumio[1139]: info: Got it Jun 27 12:50:47 volumio volumio[1139]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 27 12:50:47 volumio volumio[1139]: info: Getting Tidal Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Getting Qobuz Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Getting Spotify Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: info: Saving Spotify Acccount Jun 27 12:50:47 volumio volumio[1139]: info: Got Tidal Cloud Configuration Jun 27 12:50:47 volumio volumio[1139]: info: Got it Jun 27 12:50:47 volumio volumio[1139]: info: Got it Jun 27 12:50:47 volumio volumio[1139]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 27 12:50:47 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:47 volumio volumio[1139]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 27 12:50:48 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 27 12:50:48 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 12:50:48 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 27 12:50:48 volumio volumio[1139]: info: Version has changed, forcing UI Reload Jun 27 12:50:48 volumio volumio[1139]: info: CoreCommandRouter::Reload Ui Jun 27 12:50:49 volumio volumio[1139]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 27 12:50:49 volumio volumio[1139]: info: Received Get System Info Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 12:50:49 volumio volumio[1139]: info: Discovery: Getting this device information Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:50:49 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 12:50:49 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:50:49 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:50:49 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:49 volumio volumio[1139]: info: Retrieving Cloud Streaming UI Jun 27 12:50:49 volumio volumio[1139]: info: Getting Tidal Cloud Configuration Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:49 volumio volumio[1139]: info: Getting Qobuz Cloud Configuration Jun 27 12:50:49 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:49 volumio volumio[1139]: info: Getting Spotify Cloud Configuration Jun 27 12:50:49 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:49 volumio volumio[1139]: info: Saving Spotify Acccount Jun 27 12:50:49 volumio volumio[1139]: info: Got Tidal Cloud Configuration Jun 27 12:50:49 volumio volumio[1139]: info: Got it Jun 27 12:50:49 volumio volumio[1139]: info: Got it Jun 27 12:50:49 volumio volumio[1139]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:50:49 volumio volumio[1139]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 27 12:50:49 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:50:49 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:50:50 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 27 12:50:50 volumio volumio[1139]: info: Loading i18n strings for locale en Jun 27 12:50:50 volumio volumio[1139]: Updating browse sources language Jun 27 12:50:50 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:50:50 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 27 12:50:50 volumio volumio[1139]: info: Setting timezone to UTC Jun 27 12:50:50 volumio sudo[2374]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 27 12:50:50 volumio sudo[2374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:50 volumio sudo[2374]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:50 volumio sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jun 27 12:50:50 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:50 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:50 volumio sudo[2382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 27 12:50:50 volumio sudo[2382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:50 volumio sudo[2382]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:50 volumio sudo[2386]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jun 27 12:50:50 volumio sudo[2386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:50:50 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root Jun 27 12:50:51 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 27 12:50:51 volumio volumio[1139]: info: Fetching Streaming Services browse cache Jun 27 12:50:54 volumio volumio[1139]: info: BOOT COMPLETED Jun 27 12:51:00 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 27 12:51:00 volumio volumio[1139]: info: Setting timezone to America/Chicago Jun 27 12:51:00 volumio sudo[2413]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 27 12:51:00 volumio sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:00 volumio sudo[2413]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:00 volumio sudo[2417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Chicago /etc/localtime Jun 27 12:51:00 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:00 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:00 volumio sudo[2421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 27 12:51:00 volumio sudo[2421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:00 volumio sudo[2421]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:00 volumio sudo[2425]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Chicago Jun 27 12:51:00 volumio sudo[2425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:00 volumio systemd-timedated[2350]: Changed time zone to 'America/Chicago' (CDT). Jun 27 12:51:00 volumio sudo[2425]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:02 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 27 12:51:02 volumio volumio[1139]: info: Loading i18n strings for locale en Jun 27 12:51:02 volumio volumio[1139]: Updating browse sources language Jun 27 12:51:02 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:51:02 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 27 12:51:02 volumio volumio[1139]: info: Setting timezone to America/Chicago Jun 27 12:51:02 volumio sudo[2431]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 27 12:51:02 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:02 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:02 volumio sudo[2435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Chicago /etc/localtime Jun 27 12:51:02 volumio sudo[2435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:02 volumio sudo[2435]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:02 volumio sudo[2439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 27 12:51:02 volumio sudo[2439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:02 volumio sudo[2439]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:02 volumio sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Chicago Jun 27 12:51:02 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:02 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:02 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 27 12:51:02 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 27 12:51:02 volumio sudo[2446]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 12:51:02 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:02 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:02 volumio volumio[1139]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 27 12:51:02 volumio volumio[1139]: wlan0 Interface doesn't support scanning : Operation not supported Jun 27 12:51:02 volumio volumio[1139]: info: Cannot use regular scanning, forcing with ap-force Jun 27 12:51:02 volumio sudo[2450]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 27 12:51:02 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:03 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:03 volumio volumio[1139]: info: Fetching Streaming Services browse cache Jun 27 12:51:05 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jun 27 12:51:07 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 12:51:07 volumio volumio[1139]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 27 12:51:07 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 27 12:51:07 volumio volumio[1139]: info: Received Get System Version Jun 27 12:51:07 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 12:51:07 volumio volumio[1139]: info: Received Get System Info Jun 27 12:51:07 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 12:51:07 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 12:51:07 volumio volumio[1139]: info: Discovery: Getting this device information Jun 27 12:51:07 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:51:07 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:51:07 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 12:51:09 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 27 12:51:09 volumio volumio[1139]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 27 12:51:09 volumio setdatetime-helper.sh[2475]: Time is not synchronized. Attempting to sync... Jun 27 12:51:09 volumio volumio[1139]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 12:51:09 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:51:10 volumio sudo[2484]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 27 Jun 2025 17:51:10 GMT#015' Jun 27 12:51:10 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:51:10 volumio setdatetime-helper.sh[2485]: Fri Jun 27 12:51:10 CDT 2025 Jun 27 12:51:10 volumio setdatetime-helper.sh[2475]: Time synchronized successfully. Jun 27 12:51:10 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:10 volumio systemd-journald[481]: Time jumped backwards, rotating. Jun 27 12:51:10 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 27 12:51:10 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 27 12:51:14 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 27 12:51:14 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 12:51:14 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:51:14 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:51:14 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:51:14 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 27 12:51:15 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:51:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:51:20 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 27 12:51:20 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 12:51:20 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:51:20 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:51:20 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:51:20 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 27 12:51:20 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:51:20 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:51:24 volumio volumio[1139]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 27 12:51:24 volumio volumio[1139]: info: Received Get System Info Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 12:51:24 volumio volumio[1139]: info: Discovery: Getting this device information Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:51:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 12:51:24 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:51:24 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:51:24 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:51:24 volumio volumio[1139]: info: Retrieving Cloud Streaming UI Jun 27 12:51:24 volumio volumio[1139]: info: Getting Tidal Cloud Configuration Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:51:24 volumio volumio[1139]: info: Getting Qobuz Cloud Configuration Jun 27 12:51:24 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:51:24 volumio volumio[1139]: info: Getting Spotify Cloud Configuration Jun 27 12:51:24 volumio volumio[1139]: info: Asking plugin for UI Config Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:51:24 volumio volumio[1139]: info: Saving Spotify Acccount Jun 27 12:51:24 volumio volumio[1139]: info: Got Tidal Cloud Configuration Jun 27 12:51:24 volumio volumio[1139]: info: Got it Jun 27 12:51:24 volumio volumio[1139]: info: Got it Jun 27 12:51:24 volumio volumio[1139]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:51:24 volumio volumio[1139]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 27 12:51:24 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:51:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:51:25 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 27 12:51:25 volumio volumio[1139]: info: Loading i18n strings for locale en Jun 27 12:51:25 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 27 12:51:25 volumio volumio[1139]: info: Setting timezone to America/Chicago Jun 27 12:51:25 volumio sudo[2544]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 27 12:51:25 volumio sudo[2544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:25 volumio sudo[2544]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:25 volumio sudo[2548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Chicago /etc/localtime Jun 27 12:51:25 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:25 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:25 volumio sudo[2552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 27 12:51:25 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:25 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:25 volumio sudo[2556]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Chicago Jun 27 12:51:25 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:25 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:25 volumio volumio[1139]: Updating browse sources language Jun 27 12:51:25 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 12:51:25 volumio volumio[1139]: info: Fetching Streaming Services browse cache Jun 27 12:51:26 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 27 12:51:26 volumio volumio[1139]: info: MyVolumio login type: Token Jun 27 12:51:26 volumio volumio[1139]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 27 12:51:27 volumio volumio[1139]: info: MyVolumio token set successfully Jun 27 12:51:27 volumio volumio[1139]: info: MYVOLUMIO: Adding device Jun 27 12:51:27 volumio volumio[1139]: info: MYVOLUMIO: Evaluating Server Jun 27 12:51:27 volumio volumio[1139]: info: MyVolumio status changed Jun 27 12:51:27 volumio volumio[1139]: info: Streaming services startup Jun 27 12:51:27 volumio volumio[1139]: info: Starting Streaming Daemon Jun 27 12:51:27 volumio volumio[1139]: info: Removing browser output: myVolumio user plan is not superstar Jun 27 12:51:27 volumio volumio[1139]: info: Removing audio output: Jun 27 12:51:27 volumio volumio[1139]: info: Stoppping Tunnel 1 Jun 27 12:51:27 volumio sudo[2578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 12:51:27 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:27 volumio sudo[2580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 27 12:51:27 volumio sudo[2580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:27 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:27 volumio volumio[1139]: error: Cannot start Volumio Streaming Daemon Jun 27 12:51:27 volumio volumio[1139]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 12:51:27 volumio volumio[1139]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 12:51:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 27 12:51:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 27 12:51:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 27 12:51:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 27 12:51:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 27 12:51:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 27 12:51:27 volumio sudo[2580]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:27 volumio volumio[1139]: info: Remote SSH Stopped Jun 27 12:51:27 volumio volumio[1139]: info: Setting Geolocation for MyVolumio to us3 Jun 27 12:51:27 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:27 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:27 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:27 volumio volumio[1139]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 27 12:51:28 volumio volumio[1139]: info: Updating MyVolumio device info Jun 27 12:51:28 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:28 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:28 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:28 volumio volumio[1139]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 27 12:51:30 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 27 12:51:30 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 12:51:30 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:51:30 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:51:30 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:51:30 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 27 12:51:30 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:51:30 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:51:31 volumio volumio[1139]: info: MYVOLUMIO: Adding device Jun 27 12:51:31 volumio volumio[1139]: info: MYVOLUMIO: Evaluating Server Jun 27 12:51:32 volumio volumio[1139]: info: Setting Geolocation for MyVolumio to us3 Jun 27 12:51:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 27 12:51:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 12:51:32 volumio volumio-remote-updater[752]: Test mode disabled Jun 27 12:51:32 volumio volumio-remote-updater[752]: Alpha mode disabled Jun 27 12:51:32 volumio volumio-remote-updater[752]: Alpha legacy test mode disabled Jun 27 12:51:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 27 12:51:32 volumio volumio[1139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 12:51:32 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 12:51:32 volumio volumio[1139]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 27 12:51:33 volumio volumio[1139]: info: Updating MyVolumio device info Jun 27 12:51:33 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:33 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:33 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:33 volumio volumio[1139]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 27 12:51:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jun 27 12:51:43 volumio volumio[1139]: info: System name has changed, restarting Shairport Sync Jun 27 12:51:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:43 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 12:51:43 volumio volumio[1139]: info: Updating MyVolumio device info Jun 27 12:51:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:43 volumio volumio[1139]: info: Discovery: Restarting Advertising due to device name change Jun 27 12:51:43 volumio sudo[2696]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 27 12:51:43 volumio sudo[2696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:43 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jun 27 12:51:43 volumio sudo[2696]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:44 volumio sudo[2700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jun 27 12:51:44 volumio sudo[2700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 27 12:51:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:44 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 12:51:44 volumio volumio[1139]: info: Starting Shairport Sync Jun 27 12:51:44 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Jun 27 12:51:44 volumio sudo[2705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 12:51:44 volumio sudo[2705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 27 12:51:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 27 12:51:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 27 12:51:44 volumio systemd[1]: shairport-sync.service: Consumed 1.386s CPU time. Jun 27 12:51:44 volumio sudo[2700]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:44 volumio volumio[1139]: info: Permissions for /etc/hosts set Jun 27 12:51:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 27 12:51:44 volumio volumio[1139]: info: Hostname now is bw-volumio Jun 27 12:51:44 volumio sudo[2705]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:44 volumio volumio[1139]: info: Shairport-Sync Started Jun 27 12:51:44 volumio sudo[2710]: volumio : unable to resolve host volumio: System error Jun 27 12:51:44 volumio sudo[2711]: volumio : unable to resolve host volumio: System error Jun 27 12:51:44 volumio sudo[2711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Jun 27 12:51:44 volumio sudo[2710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname bw-volumio Jun 27 12:51:44 volumio sudo[2710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:44 bw-volumio sudo[2711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:44 bw-volumio sudo[2710]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:44 bw-volumio volumio[1139]: info: New hostname set Jun 27 12:51:44 bw-volumio sudo[2711]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:44 bw-volumio volumio[1139]: info: Permissions for /etc/avahi/services/volumio.service Jun 27 12:51:44 bw-volumio volumio[1139]: info: Avahi name changed to bw-volumio Jun 27 12:51:44 bw-volumio avahi-daemon[708]: Files changed, reloading. Jun 27 12:51:44 bw-volumio avahi-daemon[708]: Loading service file /services/volumio.service. Jun 27 12:51:44 bw-volumio volumio[1139]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 27 12:51:44 bw-volumio volumio[1139]: info: Discovery: A device disappeared from network Jun 27 12:51:44 bw-volumio volumio[1139]: info: Discovery: Device volumio disappeared from network Jun 27 12:51:44 bw-volumio volumio[1139]: info: Discovery: A device disappeared from network Jun 27 12:51:44 bw-volumio volumio[1139]: info: Discovery: A device disappeared from network Jun 27 12:51:45 bw-volumio avahi-daemon[708]: Service "BW-Volumio" (/services/volumio.service) successfully established. Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 27 12:51:45 bw-volumio volumio[1139]: info: Preparing to save Alsa Options, stopping services first Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:51:45 bw-volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioPause Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreStateMachine::pause Jun 27 12:51:45 bw-volumio volumio[1139]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0,0","label":"Analog Out"},"disallowPush":true} Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 12:51:45 bw-volumio volumio[1139]: info: Found match in Cards Database: setting mixer for card Analog Out Jun 27 12:51:45 bw-volumio volumio[1139]: info: Setting mixer Master for card Analog Out Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 27 12:51:45 bw-volumio volumio[1139]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 27 12:51:45 bw-volumio volumio[1139]: info: Disabling external Volume Control Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 12:51:45 bw-volumio volumio[1139]: info: Preparing to generate the ALSA configuration file Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 27 12:51:45 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 27 12:51:45 bw-volumio sudo[2749]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 12:51:45 bw-volumio sudo[2749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:45 bw-volumio sudo[2749]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:45 bw-volumio sudo[2751]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 12:51:45 bw-volumio volumio[1139]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 27 12:51:45 bw-volumio volumio[1139]: wlan0 Interface doesn't support scanning : Operation not supported Jun 27 12:51:45 bw-volumio volumio[1139]: info: Cannot use regular scanning, forcing with ap-force Jun 27 12:51:45 bw-volumio sudo[2751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:45 bw-volumio sudo[2751]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:45 bw-volumio sudo[2761]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 27 12:51:45 bw-volumio sudo[2761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:46 bw-volumio sudo[2761]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:46 bw-volumio volumio[1139]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 27 12:51:46 bw-volumio volumio[1139]: wlan0 Interface doesn't support scanning : Operation not supported Jun 27 12:51:46 bw-volumio volumio[1139]: info: Cannot use regular scanning, forcing with ap-force Jun 27 12:51:46 bw-volumio sudo[2765]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 27 12:51:46 bw-volumio sudo[2765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:47 bw-volumio sudo[2765]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:47 bw-volumio volumio[1139]: info: VolumeController:: Volume=46 Mute =false Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:51:47 bw-volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:51:47 bw-volumio volumio[1139]: info: Asound.conf file unchanged, so no further update is needed Jun 27 12:51:47 bw-volumio volumio[1139]: info: Output device has changed, restarting MPD Jun 27 12:51:47 bw-volumio volumio[1139]: info: Output device has changed, restarting Shairport Sync Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:47 bw-volumio sudo[2776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 12:51:47 bw-volumio sudo[2776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:47 bw-volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 12:51:47 bw-volumio sudo[2776]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:47 bw-volumio sudo[2780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 12:51:47 bw-volumio sudo[2780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:47 bw-volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 27 12:51:47 bw-volumio volumio[1139]: info: MPD Permissions set Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 27 12:51:47 bw-volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 12:51:47 bw-volumio volumio[1139]: info: Starting Shairport Sync Jun 27 12:51:47 bw-volumio sudo[2804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 12:51:47 bw-volumio sudo[2804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 27 12:51:47 bw-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 27 12:51:47 bw-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 27 12:51:47 bw-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 27 12:51:47 bw-volumio systemd[1]: shairport-sync.service: Consumed 1.417s CPU time. Jun 27 12:51:47 bw-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 27 12:51:47 bw-volumio sudo[2804]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:47 bw-volumio volumio[1139]: info: Shairport-Sync Started Jun 27 12:51:47 bw-volumio systemd[1]: mpd.service: Deactivated successfully. Jun 27 12:51:47 bw-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 27 12:51:47 bw-volumio systemd[1]: mpd.service: Consumed 1.020s CPU time. Jun 27 12:51:47 bw-volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 27 12:51:47 bw-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 27 12:51:47 bw-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 27 12:51:47 bw-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 27 12:51:47 bw-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 27 12:51:47 bw-volumio sudo[2822]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 12:51:47 bw-volumio sudo[2822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 27 12:51:47 bw-volumio sudo[2822]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:48 bw-volumio mpd[2824]: 2025-06-27T12:51:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 12:51:48 bw-volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 27 12:51:48 bw-volumio sudo[2780]: pam_unix(sudo:session): session closed for user root Jun 27 12:51:48 bw-volumio volumio[1139]: error: updateQueue error: null Jun 27 12:51:48 bw-volumio volumio[1139]: info: Discovery: Started advertising with name: BW-Volumio Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreStateMachine::getcurrentVolume Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 12:51:49 bw-volumio volumio[1139]: info: VolumeController:: Volume=46 Mute =false Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:51:49 bw-volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jun 27 12:51:49 bw-volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 12:51:49 bw-volumio volumio[1139]: info: VolumeController:: Volume=46 Mute =false Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreStateMachine::pushState Jun 27 12:51:49 bw-volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 27 12:51:49 bw-volumio volumio[1139]: info: Discovery: adding a6e2af3d-f3ed-4254-a692-d5dcf109c6e1 Jun 27 12:51:49 bw-volumio volumio[1139]: info: Discovery: Found device BW-Volumio Jun 27 12:51:49 bw-volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 27 12:51:49 bw-volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 27 12:51:49 bw-volumio volumio[1139]: compat.c: read() failed: Resource temporarily unavailable Jun 27 12:51:49 bw-volumio volumio[1139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 12:51:49 bw-volumio volumio[1139]: Error: dns service error: unknown Jun 27 12:51:49 bw-volumio volumio[1139]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 27 12:51:49 bw-volumio volumio[1139]: errorCode: -65537 Jun 27 12:51:49 bw-volumio volumio[1139]: } Jun 27 12:51:49 bw-volumio volumio[1139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 12:51:49 bw-volumio sudo[2866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-27 12:50' Jun 27 12:51:49 bw-volumio sudo[2866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:47:19 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f0f6a90610a0a39b2758a5b3958219e6"