Aug 29 06:53:55 volumio kernel: Linux version 6.12.57-volumio (gkkpch@UbuntuVM24.04) (gcc (Ubuntu 13.3.0-6ubuntu2~24.04) 13.3.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 Aug 29 06:53:55 volumio kernel: Command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=70a86aca-25df-40d1-8d1b-e7528535975f bootpart=UUID=34D3-6B91 datapart=UUID=612766c6-6c19-4a14-93db-507079e22c91 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Aug 29 06:53:55 volumio kernel: KERNEL supported cpus: Aug 29 06:53:55 volumio kernel: Intel GenuineIntel Aug 29 06:53:55 volumio kernel: AMD AuthenticAMD Aug 29 06:53:55 volumio kernel: Hygon HygonGenuine Aug 29 06:53:55 volumio kernel: Centaur CentaurHauls Aug 29 06:53:55 volumio kernel: zhaoxin Shanghai Aug 29 06:53:55 volumio kernel: BIOS-provided physical RAM map: Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000057fff] usable Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x0000000000058000-0x0000000000058fff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x0000000000059000-0x000000000009dfff] usable Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x000000000009e000-0x000000000009ffff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000d7b08fff] usable Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000d7b09000-0x00000000d7b0ffff] ACPI NVS Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000d7b10000-0x00000000d828afff] usable Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000d828b000-0x00000000d84a9fff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000d84aa000-0x00000000db4a6fff] usable Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000db4a7000-0x00000000db51ffff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000db520000-0x00000000db53bfff] ACPI data Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000db53c000-0x00000000dba75fff] ACPI NVS Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000dba76000-0x00000000dbf77fff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000dbf78000-0x00000000dbffefff] type 20 Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000dbfff000-0x00000000dbffffff] usable Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000dd000000-0x00000000df1fffff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000fed00000-0x00000000fed03fff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved Aug 29 06:53:55 volumio kernel: BIOS-e820: [mem 0x0000000100000000-0x000000011fdfffff] usable Aug 29 06:53:55 volumio kernel: NX (Execute Disable) protection: active Aug 29 06:53:55 volumio kernel: APIC: Static calls initialized Aug 29 06:53:55 volumio kernel: efi: EFI v2.3.1 by American Megatrends Aug 29 06:53:55 volumio kernel: efi: ESRT=0xdbefc518 ACPI=0xdb527000 ACPI 2.0=0xdb527000 SMBIOS=0xdbf76218 Aug 29 06:53:55 volumio kernel: efi: Remove mem50: MMIO range=[0xf8000000-0xfbffffff] (64MB) from e820 map Aug 29 06:53:55 volumio kernel: e820: remove [mem 0xf8000000-0xfbffffff] reserved Aug 29 06:53:55 volumio kernel: efi: Not removing mem51: MMIO range=[0xfec00000-0xfec00fff] (4KB) from e820 map Aug 29 06:53:55 volumio kernel: efi: Not removing mem52: MMIO range=[0xfed00000-0xfed03fff] (16KB) from e820 map Aug 29 06:53:55 volumio kernel: efi: Not removing mem53: MMIO range=[0xfed1c000-0xfed1ffff] (16KB) from e820 map Aug 29 06:53:55 volumio kernel: efi: Not removing mem54: MMIO range=[0xfee00000-0xfee00fff] (4KB) from e820 map Aug 29 06:53:55 volumio kernel: efi: Remove mem55: MMIO range=[0xff000000-0xffffffff] (16MB) from e820 map Aug 29 06:53:55 volumio kernel: e820: remove [mem 0xff000000-0xffffffff] reserved Aug 29 06:53:55 volumio kernel: SMBIOS 2.7 present. Aug 29 06:53:55 volumio kernel: DMI: Microsoft Corporation Surface Pro 2/Surface Pro 2, BIOS 2.05.0250 04/10/2015 Aug 29 06:53:55 volumio kernel: DMI: Memory slots populated: 2/2 Aug 29 06:53:55 volumio kernel: tsc: Fast TSC calibration using PIT Aug 29 06:53:55 volumio kernel: tsc: Detected 2294.867 MHz processor Aug 29 06:53:55 volumio kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 29 06:53:55 volumio kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 29 06:53:55 volumio kernel: last_pfn = 0x11fe00 max_arch_pfn = 0x400000000 Aug 29 06:53:55 volumio kernel: MTRR map: 6 entries (2 fixed + 4 variable; max 22), built from 10 variable MTRRs Aug 29 06:53:55 volumio kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 29 06:53:55 volumio kernel: e820: update [mem 0xdd000000-0xffffffff] usable ==> reserved Aug 29 06:53:55 volumio kernel: last_pfn = 0xdc000 max_arch_pfn = 0x400000000 Aug 29 06:53:55 volumio kernel: esrt: Reserving ESRT space from 0x00000000dbefc518 to 0x00000000dbefc5c8. Aug 29 06:53:55 volumio kernel: Using GB pages for direct mapping Aug 29 06:53:55 volumio kernel: Secure boot could not be determined Aug 29 06:53:55 volumio kernel: RAMDISK: [mem 0x2e241000-0x33117fff] Aug 29 06:53:55 volumio kernel: ACPI: Early table checksum verification disabled Aug 29 06:53:55 volumio kernel: ACPI: RSDP 0x00000000DB527000 000024 (v02 OEMA ) Aug 29 06:53:55 volumio kernel: ACPI: XSDT 0x00000000DB527088 000094 (v01 OEMA O E M A 01072009 AMI 00010013) Aug 29 06:53:55 volumio kernel: ACPI: FACP 0x00000000DB535F90 00010C (v05 OEMA O E M A 01072009 AMI 00010013) Aug 29 06:53:55 volumio kernel: ACPI: DSDT 0x00000000DB5271B0 00EDDA (v02 OEMA O E M A 00000250 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: FACS 0x00000000DBA74080 000040 Aug 29 06:53:55 volumio kernel: ACPI: APIC 0x00000000DB5360A0 000072 (v03 OEMA O E M A 01072009 AMI 00010013) Aug 29 06:53:55 volumio kernel: ACPI: FPDT 0x00000000DB536118 000044 (v01 OEMA O E M A 01072009 AMI 00010013) Aug 29 06:53:55 volumio kernel: ACPI: MSDM 0x00000000DB536160 000055 (v03 OEMA O E M A 01072009 AMI 00010013) Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0x00000000DB5361B8 000200 (v01 INTEL sensrhub 00000000 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0x00000000DB5363B8 000141 (v01 Intel zpoddult 00001000 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0x00000000DB536500 000539 (v01 PmRef Cpu0Ist 00003000 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0x00000000DB536A40 000AD8 (v01 PmRef CpuPm 00003000 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: MCFG 0x00000000DB537518 00003C (v01 OEMA O E M A 01072009 MSFT 00000097) Aug 29 06:53:55 volumio kernel: ACPI: HPET 0x00000000DB537558 000038 (v01 OEMA O E M A 01072009 AMI. 00000005) Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0x00000000DB537590 000315 (v01 SataRe SataTabl 00001000 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0x00000000DB5378A8 00399E (v01 SaSsdt SaSsdt 00003000 INTL 20091112) Aug 29 06:53:55 volumio kernel: ACPI: BGRT 0x00000000DB53B248 000038 (v00 OEMA O E M A 01072009 AMI 00010013) Aug 29 06:53:55 volumio kernel: ACPI: CSRT 0x00000000DB53B280 0000C4 (v01 INTL HSWULT-R 00000001 INTL 20100528) Aug 29 06:53:55 volumio kernel: ACPI: Reserving FACP table memory at [mem 0xdb535f90-0xdb53609b] Aug 29 06:53:55 volumio kernel: ACPI: Reserving DSDT table memory at [mem 0xdb5271b0-0xdb535f89] Aug 29 06:53:55 volumio kernel: ACPI: Reserving FACS table memory at [mem 0xdba74080-0xdba740bf] Aug 29 06:53:55 volumio kernel: ACPI: Reserving APIC table memory at [mem 0xdb5360a0-0xdb536111] Aug 29 06:53:55 volumio kernel: ACPI: Reserving FPDT table memory at [mem 0xdb536118-0xdb53615b] Aug 29 06:53:55 volumio kernel: ACPI: Reserving MSDM table memory at [mem 0xdb536160-0xdb5361b4] Aug 29 06:53:55 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xdb5361b8-0xdb5363b7] Aug 29 06:53:55 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xdb5363b8-0xdb5364f8] Aug 29 06:53:55 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xdb536500-0xdb536a38] Aug 29 06:53:55 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xdb536a40-0xdb537517] Aug 29 06:53:55 volumio kernel: ACPI: Reserving MCFG table memory at [mem 0xdb537518-0xdb537553] Aug 29 06:53:55 volumio kernel: ACPI: Reserving HPET table memory at [mem 0xdb537558-0xdb53758f] Aug 29 06:53:55 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xdb537590-0xdb5378a4] Aug 29 06:53:55 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xdb5378a8-0xdb53b245] Aug 29 06:53:55 volumio kernel: ACPI: Reserving BGRT table memory at [mem 0xdb53b248-0xdb53b27f] Aug 29 06:53:55 volumio kernel: ACPI: Reserving CSRT table memory at [mem 0xdb53b280-0xdb53b343] Aug 29 06:53:55 volumio kernel: Zone ranges: Aug 29 06:53:55 volumio kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 29 06:53:55 volumio kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Aug 29 06:53:55 volumio kernel: Normal [mem 0x0000000100000000-0x000000011fdfffff] Aug 29 06:53:55 volumio kernel: Movable zone start for each node Aug 29 06:53:55 volumio kernel: Early memory node ranges Aug 29 06:53:55 volumio kernel: node 0: [mem 0x0000000000001000-0x0000000000057fff] Aug 29 06:53:55 volumio kernel: node 0: [mem 0x0000000000059000-0x000000000009dfff] Aug 29 06:53:55 volumio kernel: node 0: [mem 0x0000000000100000-0x00000000d7b08fff] Aug 29 06:53:55 volumio kernel: node 0: [mem 0x00000000d7b10000-0x00000000d828afff] Aug 29 06:53:55 volumio kernel: node 0: [mem 0x00000000d84aa000-0x00000000db4a6fff] Aug 29 06:53:55 volumio kernel: node 0: [mem 0x00000000dbfff000-0x00000000dbffffff] Aug 29 06:53:55 volumio kernel: node 0: [mem 0x0000000100000000-0x000000011fdfffff] Aug 29 06:53:55 volumio kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000011fdfffff] Aug 29 06:53:55 volumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: On node 0, zone DMA: 98 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: On node 0, zone DMA32: 7 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: On node 0, zone DMA32: 543 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: On node 0, zone DMA32: 2904 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: On node 0, zone Normal: 16384 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: On node 0, zone Normal: 512 pages in unavailable ranges Aug 29 06:53:55 volumio kernel: Reserving Intel graphics memory at [mem 0xdd200000-0xdf1fffff] Aug 29 06:53:55 volumio kernel: ACPI: PM-Timer IO Port: 0x1808 Aug 29 06:53:55 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1]) Aug 29 06:53:55 volumio kernel: IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-39 Aug 29 06:53:55 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Aug 29 06:53:55 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 29 06:53:55 volumio kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 29 06:53:55 volumio kernel: ACPI: HPET id: 0x8086a701 base: 0xfed00000 Aug 29 06:53:55 volumio kernel: e820: update [mem 0xd9305000-0xd9324fff] usable ==> reserved Aug 29 06:53:55 volumio kernel: [Firmware Bug]: TSC_DEADLINE disabled due to Errata; please update microcode to version: 0x20 (or later) Aug 29 06:53:55 volumio kernel: CPU topo: Max. logical packages: 1 Aug 29 06:53:55 volumio kernel: CPU topo: Max. logical dies: 1 Aug 29 06:53:55 volumio kernel: CPU topo: Max. dies per package: 1 Aug 29 06:53:55 volumio kernel: CPU topo: Max. threads per core: 2 Aug 29 06:53:55 volumio kernel: CPU topo: Num. cores per package: 2 Aug 29 06:53:55 volumio kernel: CPU topo: Num. threads per package: 4 Aug 29 06:53:55 volumio kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00058000-0x00058fff] Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x0009e000-0x000fffff] Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xd7b09000-0xd7b0ffff] Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xd828b000-0xd84a9fff] Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xd9305000-0xd9324fff] Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xdb4a7000-0xdbffefff] Aug 29 06:53:55 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xdc000000-0xffffffff] Aug 29 06:53:55 volumio kernel: [mem 0xdf200000-0xfebfffff] available for PCI devices Aug 29 06:53:55 volumio kernel: Booting paravirtualized kernel on bare hardware Aug 29 06:53:55 volumio kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Aug 29 06:53:55 volumio kernel: setup_percpu: NR_CPUS:64 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Aug 29 06:53:55 volumio kernel: percpu: Embedded 59 pages/cpu s201368 r8192 d32104 u524288 Aug 29 06:53:55 volumio kernel: pcpu-alloc: s201368 r8192 d32104 u524288 alloc=1*2097152 Aug 29 06:53:55 volumio kernel: pcpu-alloc: [0] 0 1 2 3 Aug 29 06:53:55 volumio kernel: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=70a86aca-25df-40d1-8d1b-e7528535975f bootpart=UUID=34D3-6B91 datapart=UUID=612766c6-6c19-4a14-93db-507079e22c91 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Aug 29 06:53:55 volumio kernel: Unknown kernel command line parameters "splash bootdelay=5 biosdevname=0 imgpart=UUID=70a86aca-25df-40d1-8d1b-e7528535975f bootpart=UUID=34D3-6B91 datapart=UUID=612766c6-6c19-4a14-93db-507079e22c91 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Aug 29 06:53:55 volumio kernel: random: crng init done Aug 29 06:53:55 volumio kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 29 06:53:55 volumio kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 29 06:53:55 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 1028126 Aug 29 06:53:55 volumio kernel: mem auto-init: stack:all(pattern), heap alloc:off, heap free:off Aug 29 06:53:55 volumio kernel: software IO TLB: area num 4. Aug 29 06:53:55 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 29 06:53:55 volumio kernel: Kernel/User page tables isolation: enabled Aug 29 06:53:55 volumio kernel: ftrace: allocating 43851 entries in 172 pages Aug 29 06:53:55 volumio kernel: ftrace: allocated 172 pages with 4 groups Aug 29 06:53:55 volumio kernel: Dynamic Preempt: voluntary Aug 29 06:53:55 volumio kernel: rcu: Preemptible hierarchical RCU implementation. Aug 29 06:53:55 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. Aug 29 06:53:55 volumio kernel: Trampoline variant of Tasks RCU enabled. Aug 29 06:53:55 volumio kernel: Rude variant of Tasks RCU enabled. Aug 29 06:53:55 volumio kernel: Tracing variant of Tasks RCU enabled. Aug 29 06:53:55 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Aug 29 06:53:55 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 29 06:53:55 volumio kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Aug 29 06:53:55 volumio kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Aug 29 06:53:55 volumio kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Aug 29 06:53:55 volumio kernel: NR_IRQS: 4352, nr_irqs: 728, preallocated irqs: 16 Aug 29 06:53:55 volumio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 29 06:53:55 volumio kernel: Console: colour dummy device 80x25 Aug 29 06:53:55 volumio kernel: printk: legacy console [tty0] enabled Aug 29 06:53:55 volumio kernel: ACPI: Core revision 20240827 Aug 29 06:53:55 volumio kernel: hpet: HPET dysfunctional in PC10. Force disabled. Aug 29 06:53:55 volumio kernel: APIC: Switch to symmetric I/O mode setup Aug 29 06:53:55 volumio kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Aug 29 06:53:55 volumio kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2114444afc6, max_idle_ns: 440795235083 ns Aug 29 06:53:55 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 4589.73 BogoMIPS (lpj=9179468) Aug 29 06:53:55 volumio kernel: CPU0: Thermal monitoring enabled (TM1) Aug 29 06:53:55 volumio kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 Aug 29 06:53:55 volumio kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 Aug 29 06:53:55 volumio kernel: process: using mwait in idle threads Aug 29 06:53:55 volumio kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 29 06:53:55 volumio kernel: Spectre V2 : Mitigation: Retpolines Aug 29 06:53:55 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Aug 29 06:53:55 volumio kernel: Speculative Store Bypass: Vulnerable Aug 29 06:53:55 volumio kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Aug 29 06:53:55 volumio kernel: MMIO Stale Data: Unknown: No mitigations Aug 29 06:53:55 volumio kernel: SRBDS: Vulnerable: No microcode Aug 29 06:53:55 volumio kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 29 06:53:55 volumio kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 29 06:53:55 volumio kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 29 06:53:55 volumio kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 29 06:53:55 volumio kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 29 06:53:55 volumio kernel: Freeing SMP alternatives memory: 36K Aug 29 06:53:55 volumio kernel: pid_max: default: 32768 minimum: 301 Aug 29 06:53:55 volumio kernel: LSM: initializing lsm=capability,yama Aug 29 06:53:55 volumio kernel: Yama: becoming mindful. Aug 29 06:53:55 volumio kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 29 06:53:55 volumio kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 29 06:53:55 volumio kernel: smpboot: CPU0: Intel(R) Core(TM) i5-4200U CPU @ 1.60GHz (family: 0x6, model: 0x45, stepping: 0x1) Aug 29 06:53:55 volumio kernel: Performance Events: PEBS fmt2+, Haswell events, 16-deep LBR, full-width counters, Intel PMU driver. Aug 29 06:53:55 volumio kernel: ... version: 3 Aug 29 06:53:55 volumio kernel: ... bit width: 48 Aug 29 06:53:55 volumio kernel: ... generic registers: 4 Aug 29 06:53:55 volumio kernel: ... value mask: 0000ffffffffffff Aug 29 06:53:55 volumio kernel: ... max period: 00007fffffffffff Aug 29 06:53:55 volumio kernel: ... fixed-purpose events: 3 Aug 29 06:53:55 volumio kernel: ... event mask: 000000070000000f Aug 29 06:53:55 volumio kernel: signal: max sigframe size: 1776 Aug 29 06:53:55 volumio kernel: Estimated ratio of average max frequency by base frequency (times 1024): 1024 Aug 29 06:53:55 volumio kernel: rcu: Hierarchical SRCU implementation. Aug 29 06:53:55 volumio kernel: rcu: Max phase no-delay instances is 1000. Aug 29 06:53:55 volumio kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Aug 29 06:53:55 volumio kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. Aug 29 06:53:55 volumio kernel: smp: Bringing up secondary CPUs ... Aug 29 06:53:55 volumio kernel: smpboot: x86: Booting SMP configuration: Aug 29 06:53:55 volumio kernel: .... node #0, CPUs: #1 #2 #3 Aug 29 06:53:55 volumio kernel: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. Aug 29 06:53:55 volumio kernel: smp: Brought up 1 node, 4 CPUs Aug 29 06:53:55 volumio kernel: smpboot: Total of 4 processors activated (18358.93 BogoMIPS) Aug 29 06:53:55 volumio kernel: Memory: 3857000K/4112504K available (16384K kernel code, 2032K rwdata, 5320K rodata, 3128K init, 840K bss, 249152K reserved, 0K cma-reserved) Aug 29 06:53:55 volumio kernel: devtmpfs: initialized Aug 29 06:53:55 volumio kernel: x86/mm: Memory block size: 128MB Aug 29 06:53:55 volumio kernel: ACPI: PM: Registering ACPI NVS region [mem 0xd7b09000-0xd7b0ffff] (28672 bytes) Aug 29 06:53:55 volumio kernel: ACPI: PM: Registering ACPI NVS region [mem 0xdb53c000-0xdba75fff] (5480448 bytes) Aug 29 06:53:55 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Aug 29 06:53:55 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 29 06:53:55 volumio kernel: pinctrl core: initialized pinctrl subsystem Aug 29 06:53:55 volumio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 29 06:53:55 volumio kernel: audit: initializing netlink subsys (disabled) Aug 29 06:53:55 volumio kernel: audit: type=2000 audit(1788000820.000:1): state=initialized audit_enabled=0 res=1 Aug 29 06:53:55 volumio kernel: thermal_sys: Registered thermal governor 'fair_share' Aug 29 06:53:55 volumio kernel: thermal_sys: Registered thermal governor 'bang_bang' Aug 29 06:53:55 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 29 06:53:55 volumio kernel: thermal_sys: Registered thermal governor 'user_space' Aug 29 06:53:55 volumio kernel: cpuidle: using governor ladder Aug 29 06:53:55 volumio kernel: cpuidle: using governor menu Aug 29 06:53:55 volumio kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 29 06:53:55 volumio kernel: PCI: ECAM [mem 0xf8000000-0xfbffffff] (base 0xf8000000) for domain 0000 [bus 00-3f] Aug 29 06:53:55 volumio kernel: PCI: not using ECAM ([mem 0xf8000000-0xfbffffff] not reserved) Aug 29 06:53:55 volumio kernel: PCI: Using configuration type 1 for base access Aug 29 06:53:55 volumio kernel: core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on Aug 29 06:53:55 volumio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 29 06:53:55 volumio kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 29 06:53:55 volumio kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 29 06:53:55 volumio kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 29 06:53:55 volumio kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 29 06:53:55 volumio kernel: ACPI: Added _OSI(Module Device) Aug 29 06:53:55 volumio kernel: ACPI: Added _OSI(Processor Device) Aug 29 06:53:55 volumio kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 29 06:53:55 volumio kernel: ACPI: 7 ACPI AML tables successfully acquired and loaded Aug 29 06:53:55 volumio kernel: ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored Aug 29 06:53:55 volumio kernel: ACPI: Dynamic OEM Table Load: Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0xFFFF8881008C4000 0003D3 (v01 PmRef Cpu0Cst 00003001 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: Dynamic OEM Table Load: Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0xFFFF8881009C1800 0005AA (v01 PmRef ApIst 00003000 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: Dynamic OEM Table Load: Aug 29 06:53:55 volumio kernel: ACPI: SSDT 0xFFFF88810099A000 000119 (v01 PmRef ApCst 00003000 INTL 20120711) Aug 29 06:53:55 volumio kernel: ACPI: EC: EC started Aug 29 06:53:55 volumio kernel: ACPI: EC: interrupt blocked Aug 29 06:53:55 volumio kernel: ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 Aug 29 06:53:55 volumio kernel: ACPI: \_SB_.PCI0.LPCB.EC0_: Boot DSDT EC used to handle transactions Aug 29 06:53:55 volumio kernel: ACPI: Interpreter enabled Aug 29 06:53:55 volumio kernel: ACPI: PM: (supports S0 S3 S4 S5) Aug 29 06:53:55 volumio kernel: ACPI: Using IOAPIC for interrupt routing Aug 29 06:53:55 volumio kernel: PCI: ECAM [mem 0xf8000000-0xfbffffff] (base 0xf8000000) for domain 0000 [bus 00-3f] Aug 29 06:53:55 volumio kernel: PCI: ECAM [mem 0xf8000000-0xfbffffff] reserved as ACPI motherboard resource Aug 29 06:53:55 volumio kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 29 06:53:55 volumio kernel: PCI: Using E820 reservations for host bridge windows Aug 29 06:53:55 volumio kernel: ACPI: Enabled 5 GPEs in block 00 to 7F Aug 29 06:53:55 volumio kernel: acpi PNP0C0A:04: ACPI dock station (docks/bays count: 1) Aug 29 06:53:55 volumio kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3e]) Aug 29 06:53:55 volumio kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 29 06:53:55 volumio kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR] Aug 29 06:53:55 volumio kernel: PCI host bridge to bus 0000:00 Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000fffff window] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: root bus resource [mem 0xdf200000-0xfeafffff window] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: root bus resource [bus 00-3e] Aug 29 06:53:55 volumio kernel: pci 0000:00:00.0: [8086:0a04] type 00 class 0x060000 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: [8086:0a16] type 00 class 0x030000 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: BAR 0 [mem 0xf7800000-0xf7bfffff 64bit] Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: BAR 2 [mem 0xe0000000-0xefffffff 64bit pref] Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: BAR 4 [io 0xf000-0xf03f] Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Aug 29 06:53:55 volumio kernel: pci 0000:00:03.0: [8086:0a0c] type 00 class 0x040300 PCIe Root Complex Integrated Endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:03.0: BAR 0 [mem 0xf7c14000-0xf7c17fff 64bit] Aug 29 06:53:55 volumio kernel: pci 0000:00:14.0: [8086:9c31] type 00 class 0x0c0330 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:14.0: BAR 0 [mem 0xf7c00000-0xf7c0ffff 64bit] Aug 29 06:53:55 volumio kernel: pci 0000:00:14.0: PME# supported from D3hot D3cold Aug 29 06:53:55 volumio kernel: pci 0000:00:16.0: [8086:9c3a] type 00 class 0x078000 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:16.0: BAR 0 [mem 0xf7c1d000-0xf7c1d01f 64bit] Aug 29 06:53:55 volumio kernel: pci 0000:00:16.0: PME# supported from D0 D3hot D3cold Aug 29 06:53:55 volumio kernel: pci 0000:00:1b.0: [8086:9c20] type 00 class 0x040300 PCIe Root Complex Integrated Endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:1b.0: BAR 0 [mem 0xf7c10000-0xf7c13fff 64bit] Aug 29 06:53:55 volumio kernel: pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold Aug 29 06:53:55 volumio kernel: pci 0000:00:1d.0: [8086:9c26] type 00 class 0x0c0320 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:1d.0: BAR 0 [mem 0xf7c1b000-0xf7c1b3ff] Aug 29 06:53:55 volumio kernel: pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.0: [8086:9c43] type 00 class 0x060100 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: [8086:9c03] type 00 class 0x010601 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: BAR 0 [io 0xf0b0-0xf0b7] Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: BAR 1 [io 0xf0a0-0xf0a3] Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: BAR 2 [io 0xf090-0xf097] Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: BAR 3 [io 0xf080-0xf083] Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: BAR 4 [io 0xf060-0xf07f] Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: BAR 5 [mem 0xf7c1a000-0xf7c1a7ff] Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.2: PME# supported from D3hot Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.3: [8086:9c22] type 00 class 0x0c0500 conventional PCI endpoint Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.3: BAR 0 [mem 0xf7c19000-0xf7c190ff 64bit] Aug 29 06:53:55 volumio kernel: pci 0000:00:1f.3: BAR 4 [io 0xf040-0xf05f] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: on NUMA node 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKA disabled Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKB disabled Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKC disabled Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKD disabled Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKE disabled Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKF disabled Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKG disabled Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 0 Aug 29 06:53:55 volumio kernel: ACPI: PCI: Interrupt link LNKH disabled Aug 29 06:53:55 volumio kernel: ACPI: EC: interrupt unblocked Aug 29 06:53:55 volumio kernel: ACPI: EC: event unblocked Aug 29 06:53:55 volumio kernel: ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 Aug 29 06:53:55 volumio kernel: ACPI: EC: GPE=0x38 Aug 29 06:53:55 volumio kernel: ACPI: \_SB_.PCI0.LPCB.EC0_: Boot DSDT EC initialization complete Aug 29 06:53:55 volumio kernel: ACPI: \_SB_.PCI0.LPCB.EC0_: EC: Used to handle transactions and events Aug 29 06:53:55 volumio kernel: iommu: Default domain type: Translated Aug 29 06:53:55 volumio kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 29 06:53:55 volumio kernel: SCSI subsystem initialized Aug 29 06:53:55 volumio kernel: libata version 3.00 loaded. Aug 29 06:53:55 volumio kernel: ACPI: bus type USB registered Aug 29 06:53:55 volumio kernel: usbcore: registered new interface driver usbfs Aug 29 06:53:55 volumio kernel: usbcore: registered new interface driver hub Aug 29 06:53:55 volumio kernel: usbcore: registered new device driver usb Aug 29 06:53:55 volumio kernel: efivars: Registered efivars operations Aug 29 06:53:55 volumio kernel: PCI: Using ACPI for IRQ routing Aug 29 06:53:55 volumio kernel: PCI: pci_cache_line_size set to 64 bytes Aug 29 06:53:55 volumio kernel: e820: reserve RAM buffer [mem 0x00058000-0x0005ffff] Aug 29 06:53:55 volumio kernel: e820: reserve RAM buffer [mem 0x0009e000-0x0009ffff] Aug 29 06:53:55 volumio kernel: e820: reserve RAM buffer [mem 0xd7b09000-0xd7ffffff] Aug 29 06:53:55 volumio kernel: e820: reserve RAM buffer [mem 0xd828b000-0xdbffffff] Aug 29 06:53:55 volumio kernel: e820: reserve RAM buffer [mem 0xd9305000-0xdbffffff] Aug 29 06:53:55 volumio kernel: e820: reserve RAM buffer [mem 0xdb4a7000-0xdbffffff] Aug 29 06:53:55 volumio kernel: e820: reserve RAM buffer [mem 0x11fe00000-0x11fffffff] Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: vgaarb: bridge control possible Aug 29 06:53:55 volumio kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Aug 29 06:53:55 volumio kernel: vgaarb: loaded Aug 29 06:53:55 volumio kernel: clocksource: Switched to clocksource tsc-early Aug 29 06:53:55 volumio kernel: VFS: Disk quotas dquot_6.6.0 Aug 29 06:53:55 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 29 06:53:55 volumio kernel: pnp: PnP ACPI init Aug 29 06:53:55 volumio kernel: system 00:00: [mem 0xfed40000-0xfed44fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0x0680-0x069f] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0xffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0xffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0xffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0x1c00-0x1cfe] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0x1d00-0x1dfe] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0x1e00-0x1efe] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0x1f00-0x1ffe] has been reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0x1800-0x18fe] could not be reserved Aug 29 06:53:55 volumio kernel: system 00:01: [io 0x164e-0x164f] has been reserved Aug 29 06:53:55 volumio kernel: system 00:03: [io 0x04d0-0x04d1] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe102000-0xfe102fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe104000-0xfe104fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe106000-0xfe106fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe10c000-0xfe10cfff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe10e000-0xfe10efff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe112000-0xfe112fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe111000-0xfe111007] has been reserved Aug 29 06:53:55 volumio kernel: system 00:04: [mem 0xfe111014-0xfe111fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfed1c000-0xfed1ffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfed10000-0xfed17fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfed18000-0xfed18fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfed19000-0xfed19fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xf8000000-0xfbffffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfed20000-0xfed3ffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfed90000-0xfed93fff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfed45000-0xfed8ffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xff000000-0xffffffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xfee00000-0xfeefffff] could not be reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xf7fdf000-0xf7fdffff] has been reserved Aug 29 06:53:55 volumio kernel: system 00:05: [mem 0xf7fe0000-0xf7feffff] has been reserved Aug 29 06:53:55 volumio kernel: pnp: PnP ACPI: found 6 devices Aug 29 06:53:55 volumio kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 29 06:53:55 volumio kernel: NET: Registered PF_INET protocol family Aug 29 06:53:55 volumio kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 29 06:53:55 volumio kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 29 06:53:55 volumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 29 06:53:55 volumio kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 29 06:53:55 volumio kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 29 06:53:55 volumio kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 29 06:53:55 volumio kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 29 06:53:55 volumio kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 29 06:53:55 volumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000fffff window] Aug 29 06:53:55 volumio kernel: pci_bus 0000:00: resource 7 [mem 0xdf200000-0xfeafffff window] Aug 29 06:53:55 volumio kernel: PCI: CLS 64 bytes, default 64 Aug 29 06:53:55 volumio kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Aug 29 06:53:55 volumio kernel: software IO TLB: mapped [mem 0x00000000d3b09000-0x00000000d7b09000] (64MB) Aug 29 06:53:55 volumio kernel: Trying to unpack rootfs image as initramfs... Aug 29 06:53:55 volumio kernel: RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer Aug 29 06:53:55 volumio kernel: RAPL PMU: hw unit of domain pp0-core 2^-14 Joules Aug 29 06:53:55 volumio kernel: RAPL PMU: hw unit of domain package 2^-14 Joules Aug 29 06:53:55 volumio kernel: RAPL PMU: hw unit of domain dram 2^-14 Joules Aug 29 06:53:55 volumio kernel: RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules Aug 29 06:53:55 volumio kernel: Initialise system trusted keyrings Aug 29 06:53:55 volumio kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Aug 29 06:53:55 volumio kernel: Key type asymmetric registered Aug 29 06:53:55 volumio kernel: Asymmetric key parser 'x509' registered Aug 29 06:53:55 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 29 06:53:55 volumio kernel: io scheduler mq-deadline registered Aug 29 06:53:55 volumio kernel: io scheduler kyber registered Aug 29 06:53:55 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 29 06:53:55 volumio kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Aug 29 06:53:55 volumio kernel: ACPI: AC: AC Adapter [AC0] (off-line) Aug 29 06:53:55 volumio kernel: input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:00/PNP0C0D:00/input/input0 Aug 29 06:53:55 volumio kernel: ACPI: button: Lid Switch [LID0] Aug 29 06:53:55 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 Aug 29 06:53:55 volumio kernel: ACPI: button: Power Button [PWRB] Aug 29 06:53:55 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Aug 29 06:53:55 volumio kernel: ACPI: button: Power Button [PWRF] Aug 29 06:53:55 volumio kernel: ACPI: thermal: [Firmware Bug]: Invalid critical threshold (-274000) Aug 29 06:53:55 volumio kernel: ACPI: thermal: [Firmware Bug]: No valid trip points! Aug 29 06:53:55 volumio kernel: thermal LNXTHERM:00: registered as thermal_zone0 Aug 29 06:53:55 volumio kernel: ACPI: thermal: Thermal Zone [TZ0] (-268 C) Aug 29 06:53:55 volumio kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Aug 29 06:53:55 volumio kernel: hpet_acpi_add: no address or irqs in _CRS Aug 29 06:53:55 volumio kernel: Linux agpgart interface v0.103 Aug 29 06:53:55 volumio kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Aug 29 06:53:55 volumio kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 Aug 29 06:53:55 volumio kernel: xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x000000000004b810 Aug 29 06:53:55 volumio kernel: ehci-pci 0000:00:1d.0: EHCI Host Controller Aug 29 06:53:55 volumio kernel: ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 2 Aug 29 06:53:55 volumio kernel: ehci-pci 0000:00:1d.0: debug port 2 Aug 29 06:53:55 volumio kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Aug 29 06:53:55 volumio kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 3 Aug 29 06:53:55 volumio kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.0 SuperSpeed Aug 29 06:53:55 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Aug 29 06:53:55 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 29 06:53:55 volumio kernel: usb usb1: Product: xHCI Host Controller Aug 29 06:53:55 volumio kernel: usb usb1: Manufacturer: Linux 6.12.57-volumio xhci-hcd Aug 29 06:53:55 volumio kernel: usb usb1: SerialNumber: 0000:00:14.0 Aug 29 06:53:55 volumio kernel: hub 1-0:1.0: USB hub found Aug 29 06:53:55 volumio kernel: hub 1-0:1.0: 9 ports detected Aug 29 06:53:55 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Aug 29 06:53:55 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 29 06:53:55 volumio kernel: usb usb3: Product: xHCI Host Controller Aug 29 06:53:55 volumio kernel: usb usb3: Manufacturer: Linux 6.12.57-volumio xhci-hcd Aug 29 06:53:55 volumio kernel: usb usb3: SerialNumber: 0000:00:14.0 Aug 29 06:53:55 volumio kernel: hub 3-0:1.0: USB hub found Aug 29 06:53:55 volumio kernel: hub 3-0:1.0: 4 ports detected Aug 29 06:53:55 volumio kernel: usb: port power management may be unreliable Aug 29 06:53:55 volumio kernel: usbcore: registered new interface driver usb-storage Aug 29 06:53:55 volumio kernel: i8042: PNP: No PS/2 controller found. Aug 29 06:53:55 volumio kernel: i8042: Probing ports directly. Aug 29 06:53:55 volumio kernel: i8042: No controller found Aug 29 06:53:55 volumio kernel: mousedev: PS/2 mouse device common for all mice Aug 29 06:53:55 volumio kernel: rtc_cmos 00:02: RTC can wake from S4 Aug 29 06:53:55 volumio kernel: rtc_cmos 00:02: registered as rtc0 Aug 29 06:53:55 volumio kernel: ehci-pci 0000:00:1d.0: irq 23, io mem 0xf7c1b000 Aug 29 06:53:55 volumio kernel: rtc_cmos 00:02: setting system clock to 2026-08-29T10:53:40 UTC (1788000820) Aug 29 06:53:55 volumio kernel: rtc_cmos 00:02: alarms up to one month, y3k, 242 bytes nvram Aug 29 06:53:55 volumio kernel: i2c_dev: i2c /dev entries driver Aug 29 06:53:55 volumio kernel: intel_pstate: Intel P-state driver initializing Aug 29 06:53:55 volumio kernel: efifb: probing for efifb Aug 29 06:53:55 volumio kernel: efifb: framebuffer at 0xe0000000, using 8128k, total 8128k Aug 29 06:53:55 volumio kernel: efifb: mode is 1920x1080x32, linelength=7680, pages=1 Aug 29 06:53:55 volumio kernel: efifb: scrolling: redraw Aug 29 06:53:55 volumio kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Aug 29 06:53:55 volumio kernel: Console: switching to colour frame buffer device 240x67 Aug 29 06:53:55 volumio kernel: fb0: EFI VGA frame buffer device Aug 29 06:53:55 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Aug 29 06:53:55 volumio kernel: NET: Registered PF_INET6 protocol family Aug 29 06:53:55 volumio kernel: Segment Routing with IPv6 Aug 29 06:53:55 volumio kernel: In-situ OAM (IOAM) with IPv6 Aug 29 06:53:55 volumio kernel: mip6: Mobile IPv6 Aug 29 06:53:55 volumio kernel: NET: Registered PF_PACKET protocol family Aug 29 06:53:55 volumio kernel: mpls_gso: MPLS GSO support Aug 29 06:53:55 volumio kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance' Aug 29 06:53:55 volumio kernel: microcode: Current revision: 0x00000017 Aug 29 06:53:55 volumio kernel: IPI shorthand broadcast: enabled Aug 29 06:53:55 volumio kernel: sched_clock: Marking stable (332003460, 204228)->(344013034, -11805346) Aug 29 06:53:55 volumio kernel: Loading compiled-in X.509 certificates Aug 29 06:53:55 volumio kernel: ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00 Aug 29 06:53:55 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Aug 29 06:53:55 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 29 06:53:55 volumio kernel: usb usb2: Product: EHCI Host Controller Aug 29 06:53:55 volumio kernel: usb usb2: Manufacturer: Linux 6.12.57-volumio ehci_hcd Aug 29 06:53:55 volumio kernel: usb usb2: SerialNumber: 0000:00:1d.0 Aug 29 06:53:55 volumio kernel: clk: Disabling unused clocks Aug 29 06:53:55 volumio kernel: PM: genpd: Disabling unused power domains Aug 29 06:53:55 volumio kernel: hub 2-0:1.0: USB hub found Aug 29 06:53:55 volumio kernel: hub 2-0:1.0: 2 ports detected Aug 29 06:53:55 volumio kernel: ACPI: battery: Slot [BAT0] (battery present) Aug 29 06:53:55 volumio kernel: usb 1-2: new high-speed USB device number 2 using xhci_hcd Aug 29 06:53:55 volumio kernel: usb 2-1: new high-speed USB device number 2 using ehci-pci Aug 29 06:53:55 volumio kernel: usb 1-2: New USB device found, idVendor=1286, idProduct=2043, bcdDevice=40.00 Aug 29 06:53:55 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 29 06:53:55 volumio kernel: usb 1-2: Product: Marvell Wireless Device Aug 29 06:53:55 volumio kernel: usb 1-2: Manufacturer: Marvell Aug 29 06:53:55 volumio kernel: usb 1-2: SerialNumber: 0000000000000000 Aug 29 06:53:55 volumio kernel: usb 2-1: New USB device found, idVendor=8087, idProduct=8000, bcdDevice= 0.04 Aug 29 06:53:55 volumio kernel: usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 29 06:53:55 volumio kernel: hub 2-1:1.0: USB hub found Aug 29 06:53:55 volumio kernel: hub 2-1:1.0: 8 ports detected Aug 29 06:53:55 volumio kernel: usb 1-3: new full-speed USB device number 3 using xhci_hcd Aug 29 06:53:55 volumio kernel: Freeing initrd memory: 80732K Aug 29 06:53:55 volumio kernel: Freeing unused kernel image (initmem) memory: 3128K Aug 29 06:53:55 volumio kernel: Write protecting the kernel read-only data: 22528k Aug 29 06:53:55 volumio kernel: Freeing unused kernel image (rodata/data gap) memory: 824K Aug 29 06:53:55 volumio kernel: Run /init as init process Aug 29 06:53:55 volumio kernel: with arguments: Aug 29 06:53:55 volumio kernel: /init Aug 29 06:53:55 volumio kernel: splash Aug 29 06:53:55 volumio kernel: with environment: Aug 29 06:53:55 volumio kernel: HOME=/ Aug 29 06:53:55 volumio kernel: TERM=linux Aug 29 06:53:55 volumio kernel: bootdelay=5 Aug 29 06:53:55 volumio kernel: biosdevname=0 Aug 29 06:53:55 volumio kernel: imgpart=UUID=70a86aca-25df-40d1-8d1b-e7528535975f Aug 29 06:53:55 volumio kernel: bootpart=UUID=34D3-6B91 Aug 29 06:53:55 volumio kernel: datapart=UUID=612766c6-6c19-4a14-93db-507079e22c91 Aug 29 06:53:55 volumio kernel: hwdevice=x86 Aug 29 06:53:55 volumio kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Aug 29 06:53:55 volumio kernel: imgfile=/volumio_current.sqsh Aug 29 06:53:55 volumio kernel: use_kmsg=no Aug 29 06:53:55 volumio kernel: usb 1-3: New USB device found, idVendor=045e, idProduct=07a9, bcdDevice= 2.05 Aug 29 06:53:55 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 29 06:53:55 volumio kernel: usb 1-3: Product: SAM Aug 29 06:53:55 volumio kernel: usb 1-3: Manufacturer: MICROSOFT Aug 29 06:53:55 volumio kernel: usb 1-3: SerialNumber: 0.1.0000 Aug 29 06:53:55 volumio kernel: ahci 0000:00:1f.2: version 3.0 Aug 29 06:53:55 volumio kernel: ACPI: bus type drm_connector registered Aug 29 06:53:55 volumio kernel: i801_smbus 0000:00:1f.3: SPD Write Disable is set Aug 29 06:53:55 volumio kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Aug 29 06:53:55 volumio kernel: ahci 0000:00:1f.2: AHCI vers 0001.0300, 32 command slots, 6 Gbps, SATA mode Aug 29 06:53:55 volumio kernel: ahci 0000:00:1f.2: 1/3 ports implemented (port mask 0x1) Aug 29 06:53:55 volumio kernel: ahci 0000:00:1f.2: flags: 64bit ncq pm led clo only pio slum part deso sadm sds apst Aug 29 06:53:55 volumio kernel: scsi host0: ahci Aug 29 06:53:55 volumio kernel: scsi host1: ahci Aug 29 06:53:55 volumio kernel: scsi host2: ahci Aug 29 06:53:55 volumio kernel: ata1: SATA max UDMA/133 abar m2048@0xf7c1a000 port 0xf7c1a100 irq 41 lpm-pol 3 Aug 29 06:53:55 volumio kernel: ata2: DUMMY Aug 29 06:53:55 volumio kernel: ata3: DUMMY Aug 29 06:53:55 volumio kernel: usb 1-4: new full-speed USB device number 4 using xhci_hcd Aug 29 06:53:55 volumio kernel: usb 1-4: New USB device found, idVendor=03eb, idProduct=8209, bcdDevice=27.32 Aug 29 06:53:55 volumio kernel: usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 29 06:53:55 volumio kernel: usb 1-4: Product: Atmel maXTouch Digitizer Aug 29 06:53:55 volumio kernel: usb 1-4: Manufacturer: Atmel Aug 29 06:53:55 volumio kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Aug 29 06:53:55 volumio kernel: ata1.00: ATA-8: LITEONIT LMT-19nmBGA-128G-DS, DM728D1, max UDMA/133 Aug 29 06:53:55 volumio kernel: ata1.00: 250069680 sectors, multi 16: LBA48 NCQ (depth 32), AA Aug 29 06:53:55 volumio kernel: ata1.00: configured for UDMA/133 Aug 29 06:53:55 volumio kernel: scsi 0:0:0:0: Direct-Access ATA LITEONIT LMT-19n 8D1 PQ: 0 ANSI: 5 Aug 29 06:53:55 volumio kernel: sd 0:0:0:0: [sda] 250069680 512-byte logical blocks: (128 GB/119 GiB) Aug 29 06:53:55 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Aug 29 06:53:55 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 Aug 29 06:53:55 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Aug 29 06:53:55 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Aug 29 06:53:55 volumio kernel: tsc: Refined TSC clocksource calibration: 2294.687 MHz Aug 29 06:53:55 volumio kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x21139a22526, max_idle_ns: 440795252169 ns Aug 29 06:53:55 volumio kernel: clocksource: Switched to clocksource tsc Aug 29 06:53:55 volumio kernel: sda: sda1 sda2 sda3 Aug 29 06:53:55 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Aug 29 06:53:55 volumio kernel: snd_hda_intel 0000:00:03.0: enabling device (0000 -> 0002) Aug 29 06:53:55 volumio kernel: snd_hda_intel 0000:00:1b.0: enabling device (0000 -> 0002) Aug 29 06:53:55 volumio kernel: usb 1-5: new high-speed USB device number 5 using xhci_hcd Aug 29 06:53:55 volumio kernel: snd_hda_codec_generic hdaudioC0D0: autoconfig for Generic: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker Aug 29 06:53:55 volumio kernel: snd_hda_codec_generic hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Aug 29 06:53:55 volumio kernel: snd_hda_codec_generic hdaudioC0D0: hp_outs=1 (0x15/0x0/0x0/0x0/0x0) Aug 29 06:53:55 volumio kernel: snd_hda_codec_generic hdaudioC0D0: mono: mono_out=0x0 Aug 29 06:53:55 volumio kernel: snd_hda_codec_generic hdaudioC0D0: inputs: Aug 29 06:53:55 volumio kernel: snd_hda_codec_generic hdaudioC0D0: Internal Mic=0x12 Aug 29 06:53:55 volumio kernel: snd_hda_codec_generic hdaudioC0D0: Mic=0x1a Aug 29 06:53:55 volumio kernel: usb 1-5: New USB device found, idVendor=045e, idProduct=0794, bcdDevice= 3.06 Aug 29 06:53:55 volumio kernel: usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 29 06:53:55 volumio kernel: usb 1-5: Product: Front LifeCam Aug 29 06:53:55 volumio kernel: usb 1-5: Manufacturer: Microsoft Aug 29 06:53:55 volumio kernel: usb 1-6: new high-speed USB device number 6 using xhci_hcd Aug 29 06:53:55 volumio kernel: usb 1-6: New USB device found, idVendor=045e, idProduct=0795, bcdDevice= 3.07 Aug 29 06:53:55 volumio kernel: usb 1-6: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 29 06:53:55 volumio kernel: usb 1-6: Product: Rear LifeCam Aug 29 06:53:55 volumio kernel: usb 1-6: Manufacturer: Microsoft Aug 29 06:53:55 volumio kernel: input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input3 Aug 29 06:53:55 volumio kernel: input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input4 Aug 29 06:53:55 volumio kernel: i915 0000:00:02.0: [drm] Found HASWELL/ULT (device ID 0a16) display version 7.00 stepping N/A Aug 29 06:53:55 volumio kernel: Console: switching to colour dummy device 80x25 Aug 29 06:53:55 volumio kernel: i915 0000:00:02.0: vgaarb: deactivate vga console Aug 29 06:53:55 volumio kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem Aug 29 06:53:55 volumio kernel: usbcore: registered new interface driver usbhid Aug 29 06:53:55 volumio kernel: usbhid: USB HID core driver Aug 29 06:53:55 volumio kernel: hid-generic 0003:045E:07A9.0001: hiddev0,hidraw0: USB HID v1.11 Device [MICROSOFT SAM] on usb-0000:00:14.0-3/input0 Aug 29 06:53:55 volumio kernel: input: MICROSOFT SAM Stylus as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.1/0003:045E:07A9.0002/input/input5 Aug 29 06:53:55 volumio kernel: input: MICROSOFT SAM Consumer Control as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.1/0003:045E:07A9.0002/input/input6 Aug 29 06:53:55 volumio kernel: input: MICROSOFT SAM Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.1/0003:045E:07A9.0002/input/input7 Aug 29 06:53:55 volumio kernel: input: MICROSOFT SAM Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.1/0003:045E:07A9.0002/input/input8 Aug 29 06:53:55 volumio kernel: hid-generic 0003:045E:07A9.0002: input,hidraw1: USB HID v1.11 Keyboard [MICROSOFT SAM] on usb-0000:00:14.0-3/input1 Aug 29 06:53:55 volumio kernel: input: MICROSOFT SAM Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.2/0003:045E:07A9.0003/input/input9 Aug 29 06:53:55 volumio kernel: [drm] Initialized i915 1.6.0 for 0000:00:02.0 on minor 0 Aug 29 06:53:55 volumio kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Aug 29 06:53:55 volumio kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input14 Aug 29 06:53:55 volumio kernel: input: MICROSOFT SAM Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.2/0003:045E:07A9.0003/input/input10 Aug 29 06:53:55 volumio kernel: input: MICROSOFT SAM as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.2/0003:045E:07A9.0003/input/input11 Aug 29 06:53:55 volumio kernel: hid-generic 0003:045E:07A9.0003: input,hiddev1,hidraw2: USB HID v1.11 Keyboard [MICROSOFT SAM] on usb-0000:00:14.0-3/input2 Aug 29 06:53:55 volumio kernel: input: Atmel Atmel maXTouch Digitizer as /devices/pci0000:00/0000:00:14.0/usb1/1-4/1-4:1.0/0003:03EB:8209.0004/input/input15 Aug 29 06:53:55 volumio kernel: hid-generic 0003:03EB:8209.0004: input,hidraw3: USB HID v1.11 Device [Atmel Atmel maXTouch Digitizer] on usb-0000:00:14.0-4/input0 Aug 29 06:53:55 volumio kernel: hid-generic 0003:03EB:8209.0005: hiddev2,hidraw4: USB HID v1.11 Device [Atmel Atmel maXTouch Digitizer] on usb-0000:00:14.0-4/input1 Aug 29 06:53:55 volumio kernel: fbcon: i915drmfb (fb0) is primary device Aug 29 06:53:55 volumio kernel: input: Atmel Atmel maXTouch Digitizer as /devices/pci0000:00/0000:00:14.0/usb1/1-4/1-4:1.0/0003:03EB:8209.0004/input/input16 Aug 29 06:53:55 volumio kernel: hid-multitouch 0003:03EB:8209.0004: input,hidraw3: USB HID v1.11 Device [Atmel Atmel maXTouch Digitizer] on usb-0000:00:14.0-4/input0 Aug 29 06:53:55 volumio kernel: Console: switching to colour frame buffer device 240x67 Aug 29 06:53:55 volumio kernel: snd_hda_intel 0000:00:03.0: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) Aug 29 06:53:55 volumio kernel: input: HDA Intel HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:03.0/sound/card1/input17 Aug 29 06:53:55 volumio kernel: input: HDA Intel HDMI HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:03.0/sound/card1/input18 Aug 29 06:53:55 volumio kernel: input: HDA Intel HDMI HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:03.0/sound/card1/input19 Aug 29 06:53:55 volumio kernel: i915 0000:00:02.0: [drm] fb0: i915drmfb frame buffer device Aug 29 06:53:55 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 29 06:53:55 volumio kernel: fuse: init (API version 7.41) Aug 29 06:53:55 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Aug 29 06:53:55 volumio kernel: sdhci: Copyright(c) Pierre Ossman Aug 29 06:53:55 volumio kernel: usbcore: registered new interface driver uas Aug 29 06:53:55 volumio kernel: [drm] radeon kernel modesetting enabled. Aug 29 06:53:55 volumio kernel: [drm] amdgpu kernel modesetting enabled. Aug 29 06:53:55 volumio kernel: scsi host3: pata_legacy Aug 29 06:53:55 volumio kernel: ata4: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 lpm-pol 0 Aug 29 06:53:55 volumio kernel: scsi host3: pata_legacy Aug 29 06:53:55 volumio kernel: ata4: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 lpm-pol 0 Aug 29 06:53:55 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 29 06:53:55 volumio kernel: EXT4-fs (sda2): recovery complete Aug 29 06:53:55 volumio kernel: EXT4-fs (sda2): mounted filesystem 70a86aca-25df-40d1-8d1b-e7528535975f r/w with ordered data mode. Quota mode: none. Aug 29 06:53:55 volumio kernel: loop: module loaded Aug 29 06:53:55 volumio kernel: block device autoloading is deprecated and will be removed. Aug 29 06:53:55 volumio kernel: loop0: detected capacity change from 0 to 2331568 Aug 29 06:53:55 volumio kernel: EXT4-fs (sda3): recovery complete Aug 29 06:53:55 volumio kernel: EXT4-fs (sda3): mounted filesystem 612766c6-6c19-4a14-93db-507079e22c91 r/w with ordered data mode. Quota mode: none. Aug 29 06:53:55 volumio systemd[1]: Inserted module 'autofs4' Aug 29 06:53:55 volumio systemd[1]: systemd 252.39-1~deb12u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Aug 29 06:53:55 volumio systemd[1]: Detected architecture x86-64. Aug 29 06:53:55 volumio systemd[1]: Hostname set to . Aug 29 06:53:55 volumio systemd-gpt-auto-generator[516]: EFI loader partition unknown, exiting. Aug 29 06:53:55 volumio systemd-gpt-auto-generator[516]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Aug 29 06:53:55 volumio systemd[1]: Queued start job for default target graphical.target. Aug 29 06:53:55 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 29 06:53:55 volumio systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 29 06:53:55 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 29 06:53:55 volumio systemd[1]: Created slice user.slice - User and Session Slice. Aug 29 06:53:55 volumio systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 29 06:53:55 volumio systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 29 06:53:55 volumio systemd[1]: Expecting device dev-disk-by\x2duuid-34D3\x2d6B91.device - /dev/disk/by-uuid/34D3-6B91... Aug 29 06:53:55 volumio systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 29 06:53:55 volumio systemd[1]: Reached target slices.target - Slice Units. Aug 29 06:53:55 volumio systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 29 06:53:55 volumio systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Aug 29 06:53:55 volumio systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Aug 29 06:53:55 volumio systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Aug 29 06:53:55 volumio systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Aug 29 06:53:55 volumio systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 29 06:53:55 volumio systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 29 06:53:55 volumio systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 29 06:53:55 volumio systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 29 06:53:55 volumio systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 29 06:53:55 volumio systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 29 06:53:55 volumio systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 29 06:53:55 volumio systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 29 06:53:55 volumio systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Aug 29 06:53:55 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 29 06:53:55 volumio systemd[1]: Reached target swap.target - Swaps. Aug 29 06:53:55 volumio systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Aug 29 06:53:55 volumio systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 29 06:53:55 volumio systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 29 06:53:55 volumio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 29 06:53:55 volumio systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 29 06:53:55 volumio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 29 06:53:55 volumio systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 29 06:53:55 volumio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 29 06:53:55 volumio kernel: pstore: Using crash dump compression: deflate Aug 29 06:53:55 volumio systemd[1]: Starting systemd-journald.service - Journal Service... Aug 29 06:53:55 volumio systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 29 06:53:55 volumio systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 29 06:53:55 volumio systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 29 06:53:55 volumio systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 29 06:53:55 volumio systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 29 06:53:55 volumio systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 29 06:53:55 volumio systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 29 06:53:55 volumio systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 29 06:53:55 volumio systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 29 06:53:55 volumio systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 29 06:53:55 volumio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 29 06:53:55 volumio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 29 06:53:55 volumio systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 29 06:53:55 volumio systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 29 06:53:55 volumio systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 29 06:53:55 volumio systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 29 06:53:55 volumio systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 29 06:53:55 volumio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 29 06:53:55 volumio systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 29 06:53:55 volumio systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 29 06:53:55 volumio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 29 06:53:55 volumio systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 29 06:53:55 volumio systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 29 06:53:55 volumio systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Aug 29 06:53:55 volumio systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 29 06:53:55 volumio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Aug 29 06:53:55 volumio systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Aug 29 06:53:55 volumio systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 29 06:53:55 volumio systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 29 06:53:55 volumio systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 29 06:53:55 volumio kernel: pstore: Registered efi_pstore as persistent store backend Aug 29 06:53:55 volumio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 29 06:53:55 volumio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 29 06:53:55 volumio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 29 06:53:55 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Aug 29 06:53:55 volumio systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 29 06:53:55 volumio systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Aug 29 06:53:55 volumio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Aug 29 06:53:55 volumio systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 29 06:53:55 volumio systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 29 06:53:55 volumio systemd-journald[543]: Journal started Aug 29 06:53:55 volumio systemd-journald[543]: Runtime Journal (/run/log/journal/30175a60cf76629f74f78d9769c2cd99) is 3.7M, max 30.0M, 26.2M free. Aug 29 06:53:55 volumio fake-hwclock[534]: Current system time: 2026-08-29 10:53:55 Aug 29 06:53:55 volumio fake-hwclock[534]: fake-hwclock saved clock information is in the past: 2026-08-29 10:53:33 Aug 29 06:53:55 volumio fake-hwclock[534]: To set system time to this saved clock anyway, use "force" Aug 29 06:53:55 volumio systemd[1]: Started systemd-journald.service - Journal Service. Aug 29 06:53:55 volumio systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 29 06:53:55 volumio systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 29 06:53:55 volumio systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 29 06:53:55 volumio systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 29 06:53:55 volumio systemd-udevd[567]: Using default interface naming scheme 'v252'. Aug 29 06:53:55 volumio systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Aug 29 06:53:55 volumio systemd-udevd[567]: /etc/udev/rules.d/10-local.rules:6 Invalid key/value pair, ignoring. Aug 29 06:53:55 volumio systemd-udevd[567]: /etc/udev/rules.d/10-local.rules:7 Invalid key/value pair, ignoring. Aug 29 06:53:55 volumio systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 29 06:53:55 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Aug 29 06:53:55 volumio systemd[1]: Found device dev-disk-by\x2duuid-34D3\x2d6B91.device - LITEONIT_LMT-19nmBGA-128G-DS boot. Aug 29 06:53:55 volumio systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-34D3\x2d6B91.service - File System Check on /dev/disk/by-uuid/34D3-6B91... Aug 29 06:53:55 volumio systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Aug 29 06:53:55 volumio systemd-fsck[618]: fsck.fat 4.2 (2021-01-31) Aug 29 06:53:55 volumio systemd-fsck[618]: /dev/sda1: 18 files, 23294/93500 clusters Aug 29 06:53:55 volumio systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-34D3\x2d6B91.service - File System Check on /dev/disk/by-uuid/34D3-6B91. Aug 29 06:53:55 volumio systemd[1]: Created slice system-systemd\x2dbacklight.slice - Slice /system/systemd-backlight. Aug 29 06:53:55 volumio systemd[1]: Starting systemd-backlight@backlight:intel_backlight.service - Load/Save Screen Backlight Brightness of backlight:intel_backlight... Aug 29 06:53:56 volumio kernel: hid-sensor-hub 0003:045E:07A9.0001: hiddev0,hidraw0: USB HID v1.11 Device [MICROSOFT SAM] on usb-0000:00:14.0-3/input0 Aug 29 06:53:56 volumio systemd[1]: Finished systemd-backlight@backlight:intel_backlight.service - Load/Save Screen Backlight Brightness of backlight:intel_backlight. Aug 29 06:53:56 volumio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 29 06:53:56 volumio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 29 06:53:56 volumio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 29 06:53:56 volumio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Aug 29 06:53:56 volumio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Aug 29 06:53:56 volumio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 29 06:53:56 volumio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 29 06:53:56 volumio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 29 06:53:56 volumio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 29 06:53:56 volumio systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 29 06:53:56 volumio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 29 06:53:56 volumio systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 29 06:53:56 volumio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 29 06:53:56 volumio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 29 06:53:56 volumio kernel: cryptd: max_cpu_qlen set to 1000 Aug 29 06:53:56 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 29 06:53:56 volumio kernel: AES CTR mode by8 optimization enabled Aug 29 06:53:56 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 29 06:53:56 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Aug 29 06:53:56 volumio kernel: usbcore: registered new interface driver mwifiex_usb Aug 29 06:53:56 volumio kernel: usb 1-2: start to download FW... Aug 29 06:53:56 volumio systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Aug 29 06:53:56 volumio systemd[1]: Mounting boot.mount - /boot... Aug 29 06:53:56 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 29 06:53:56 volumio systemd[1]: Mounting tmp.mount - /tmp... Aug 29 06:53:56 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 29 06:53:56 volumio systemd[1]: Mounting var-log.mount - /var/log... Aug 29 06:53:56 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 29 06:53:56 volumio systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Aug 29 06:53:56 volumio systemd[1]: Mounted boot.mount - /boot. Aug 29 06:53:56 volumio systemd[1]: Mounted tmp.mount - /tmp. Aug 29 06:53:56 volumio systemd[1]: Mounted var-log.mount - /var/log. Aug 29 06:53:56 volumio systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Aug 29 06:53:56 volumio systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Aug 29 06:53:56 volumio systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 29 06:53:56 volumio systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Aug 29 06:53:56 volumio systemd[1]: Reached target local-fs.target - Local File Systems. Aug 29 06:53:56 volumio systemd[1]: Starting networking.service - Raise network interfaces... Aug 29 06:53:56 volumio systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Aug 29 06:53:56 volumio systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Aug 29 06:53:56 volumio systemd-journald[543]: Runtime Journal (/run/log/journal/30175a60cf76629f74f78d9769c2cd99) is 3.7M, max 30.0M, 26.2M free. Aug 29 06:53:56 volumio systemd-journald[543]: Received client request to flush runtime journal. Aug 29 06:53:56 volumio systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Aug 29 06:53:56 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). Aug 29 06:53:56 volumio systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 29 06:53:56 volumio systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 29 06:53:56 volumio systemd[1]: Received SIGRTMIN+20 from PID 404 (plymouthd). Aug 29 06:53:56 volumio systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Aug 29 06:53:56 volumio systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 723 (systemd-binfmt) Aug 29 06:53:56 volumio systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Aug 29 06:53:56 volumio systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Aug 29 06:53:56 volumio ifup[728]: command failed: No such device (-19) Aug 29 06:53:56 volumio systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 29 06:53:56 volumio systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Aug 29 06:53:56 volumio systemd[1]: Starting rpcbind.service - RPC bind portmap service... Aug 29 06:53:56 volumio systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 29 06:53:56 volumio systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Aug 29 06:53:56 volumio systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Aug 29 06:53:56 volumio systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Aug 29 06:53:56 volumio systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 29 06:53:56 volumio systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 29 06:53:56 volumio ifup[757]: command failed: No such device (-19) Aug 29 06:53:56 volumio haveged[741]: haveged: command socket is listening at fd 3 Aug 29 06:53:56 volumio systemd[1]: Started rpcbind.service - RPC bind portmap service. Aug 29 06:53:56 volumio systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Aug 29 06:53:56 volumio ifup[780]: command failed: No such device (-19) Aug 29 06:53:56 volumio systemd[1]: Finished networking.service - Raise network interfaces. Aug 29 06:53:56 volumio haveged[741]: haveged: ver: 1.9.14; arch: x86; vend: GenuineIntel; build: (gcc 12.2.0 ITV); collect: 128K Aug 29 06:53:56 volumio haveged[741]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 32010/53875 Aug 29 06:53:56 volumio haveged[741]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00133 Aug 29 06:53:56 volumio haveged[741]: haveged: fills: 0, generated: 0 Aug 29 06:53:57 volumio kernel: usb 1-2: info: FW download over, size 553940 bytes Aug 29 06:53:57 volumio kernel: usb 1-2: info: _mwifiex_fw_dpc: unregister device Aug 29 06:53:57 volumio kernel: usb 1-2: USB disconnect, device number 2 Aug 29 06:53:57 volumio systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Aug 29 06:53:57 volumio systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Aug 29 06:53:57 volumio systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Aug 29 06:53:57 volumio systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Aug 29 06:53:57 volumio systemd[1]: Reached target sysinit.target - System Initialization. Aug 29 06:53:57 volumio systemd[1]: Started acpid.path - ACPI Events Check. Aug 29 06:53:57 volumio systemd[1]: Started apt-daily.timer - Daily apt download activities. Aug 29 06:53:57 volumio systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Aug 29 06:53:57 volumio systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Aug 29 06:53:57 volumio systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Aug 29 06:53:57 volumio systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Aug 29 06:53:57 volumio systemd[1]: Started man-db.timer - Daily man-db regeneration. Aug 29 06:53:57 volumio systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Aug 29 06:53:57 volumio systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Aug 29 06:53:57 volumio systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 29 06:53:57 volumio systemd[1]: Reached target paths.target - Path Units. Aug 29 06:53:57 volumio systemd[1]: Reached target timers.target - Timer Units. Aug 29 06:53:57 volumio systemd[1]: Listening on acpid.socket - ACPID Listen Socket. Aug 29 06:53:57 volumio systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Aug 29 06:53:57 volumio systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 29 06:53:57 volumio systemd[1]: Reached target sockets.target - Socket Units. Aug 29 06:53:57 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). Aug 29 06:53:57 volumio systemd[1]: Reached target basic.target - Basic System. Aug 29 06:53:57 volumio systemd[1]: Started acpid.service - ACPI event daemon. Aug 29 06:53:57 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). Aug 29 06:53:57 volumio systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Aug 29 06:53:57 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 29 06:53:57 volumio systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 29 06:53:57 volumio systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Aug 29 06:53:57 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). Aug 29 06:53:57 volumio systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Aug 29 06:53:57 volumio systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Aug 29 06:53:57 volumio systemd[1]: Started iptables.service - Volumio Iptables Module. Aug 29 06:53:57 volumio systemd[1]: Starting systemd-logind.service - User Login Management... Aug 29 06:53:57 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Aug 29 06:53:57 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 06:53:57 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Aug 29 06:53:57 volumio systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Aug 29 06:53:57 volumio systemd[1]: Starting wireless.service - Wireless Services... Aug 29 06:53:57 volumio avahi-daemon[850]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Aug 29 06:53:57 volumio avahi-daemon[850]: Successfully dropped root privileges. Aug 29 06:53:57 volumio avahi-daemon[850]: avahi-daemon 0.8 starting up. Aug 29 06:53:57 volumio kernel: RPC: Registered named UNIX socket transport module. Aug 29 06:53:57 volumio kernel: RPC: Registered udp transport module. Aug 29 06:53:57 volumio kernel: RPC: Registered tcp transport module. Aug 29 06:53:57 volumio kernel: RPC: Registered tcp-with-tls transport module. Aug 29 06:53:57 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 29 06:53:57 volumio systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Aug 29 06:53:57 volumio systemd[1]: Reached target rpc_pipefs.target. Aug 29 06:53:57 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). Aug 29 06:53:57 volumio systemd[1]: Reached target nfs-client.target - NFS client services. Aug 29 06:53:57 volumio systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 29 06:53:57 volumio systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 29 06:53:57 volumio systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Aug 29 06:53:57 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). Aug 29 06:53:57 volumio thd[856]: Unable to parse trigger line: Aug 29 06:53:57 volumio thd[856]: Unable to parse trigger line: Aug 29 06:53:57 volumio systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Aug 29 06:53:57 volumio systemd[1]: Reached target sound.target - Sound Card. Aug 29 06:53:57 volumio systemd[1]: e2scrub_reap.service: Deactivated successfully. Aug 29 06:53:57 volumio systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Aug 29 06:53:57 volumio thd[856]: Device /dev/input/event12 not suitable. Aug 29 06:53:57 volumio dhcpcd[853]: dhcpcd-9.4.1 starting Aug 29 06:53:57 volumio dhcpcd[876]: dev: loaded udev Aug 29 06:53:57 volumio systemd-logind[855]: New seat seat0. Aug 29 06:53:57 volumio dhcpcd[876]: DUID 00:04:23:d3:ba:aa:83:1c:bb:b0:20:1d:15:30:30:ab:3a:14 Aug 29 06:53:57 volumio systemd-logind[855]: Watching system buttons on /dev/input/event2 (Power Button) Aug 29 06:53:57 volumio systemd-logind[855]: Watching system buttons on /dev/input/event0 (Lid Switch) Aug 29 06:53:57 volumio systemd-logind[855]: Watching system buttons on /dev/input/event1 (Power Button) Aug 29 06:53:57 volumio systemd-logind[855]: Watching system buttons on /dev/input/event9 (MICROSOFT SAM Keyboard) Aug 29 06:53:57 volumio dhcpcd[876]: no valid interfaces found Aug 29 06:53:57 volumio dhcpcd[853]: no valid interfaces found Aug 29 06:53:57 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 29 06:53:57 volumio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Aug 29 06:53:57 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Aug 29 06:53:57 volumio systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Aug 29 06:53:57 volumio systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 29 06:53:57 volumio avahi-daemon[850]: Successfully called chroot(). Aug 29 06:53:57 volumio avahi-daemon[850]: Successfully dropped remaining capabilities. Aug 29 06:53:57 volumio avahi-daemon[850]: No service file found in /etc/avahi/services. Aug 29 06:53:57 volumio dbus-daemon[851]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 29 06:53:57 volumio systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Aug 29 06:53:57 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 29 06:53:57 volumio systemd[1]: Started systemd-logind.service - User Login Management. Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event0 to thd using --add... Aug 29 06:53:57 volumio avahi-daemon[850]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 29 06:53:57 volumio avahi-daemon[850]: New relevant interface lo.IPv4 for mDNS. Aug 29 06:53:57 volumio avahi-daemon[850]: Network interface enumeration completed. Aug 29 06:53:57 volumio avahi-daemon[850]: Registering new address record for 127.0.0.1 on lo.IPv4. Aug 29 06:53:57 volumio acpid[848]: starting up with netlink and the input layer Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event1 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event10 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event11 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event12 to thd using --add... Aug 29 06:53:57 volumio thd[856]: Device /dev/input/event12 not suitable. Aug 29 06:53:57 volumio acpid[848]: 3 rules loaded Aug 29 06:53:57 volumio acpid[848]: waiting for events: event logging is off Aug 29 06:53:57 volumio systemd[1]: iptables.service: Deactivated successfully. Aug 29 06:53:57 volumio loadcpufreq[866]: Loading cpufreq kernel modules...done (acpi-cpufreq). Aug 29 06:53:57 volumio systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event13 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event14 to thd using --add... Aug 29 06:53:57 volumio systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event15 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event16 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event2 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event3 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event4 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event5 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event6 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event7 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event8 to thd using --add... Aug 29 06:53:57 volumio th-udev-rebind.sh[895]: Rebinding /dev/input/event9 to thd using --add... Aug 29 06:53:57 volumio systemd[1]: th-udev-rebind.service: Deactivated successfully. Aug 29 06:53:57 volumio systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Aug 29 06:53:57 volumio wpa_supplicant[900]: Successfully initialized wpa_supplicant Aug 29 06:53:57 volumio systemd[1]: Started wpa_supplicant.service - WPA supplicant. Aug 29 06:53:57 volumio systemd[1]: Reached target network.target - Network. Aug 29 06:53:57 volumio systemd[1]: Reached target network-online.target - Network is Online. Aug 29 06:53:57 volumio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Aug 29 06:53:57 volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... Aug 29 06:53:57 volumio systemd[1]: Starting ntpsec.service - Network Time Service... Aug 29 06:53:58 volumio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Aug 29 06:53:58 volumio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Aug 29 06:53:58 volumio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Aug 29 06:53:58 volumio sm-notify[954]: Version 2.6.2 starting Aug 29 06:53:58 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 29 06:53:58 volumio cpufrequtils[930]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Aug 29 06:53:58 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 06:53:58 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Aug 29 06:53:58 volumio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 29 06:53:58 volumio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Aug 29 06:53:58 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 29 06:53:58 volumio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Aug 29 06:53:58 volumio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Aug 29 06:53:58 volumio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Aug 29 06:53:58 volumio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 29 06:53:58 volumio welcome[971]: Resolved ip:[0] Aug 29 06:53:58 volumio ifplugd[946]: Network Interface Plugging Daemon...skip eth0...done. Aug 29 06:53:58 volumio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Aug 29 06:53:58 volumio ntpd[967]: INIT: ntpd ntpsec-1.2.2: Starting Aug 29 06:53:58 volumio ntp-systemd-wrapper[967]: 2026-08-29T06:53:58 ntpd[967]: INIT: ntpd ntpsec-1.2.2: Starting Aug 29 06:53:58 volumio ntp-systemd-wrapper[967]: 2026-08-29T06:53:58 ntpd[967]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Aug 29 06:53:58 volumio ntpd[967]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Aug 29 06:53:58 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Aug 29 06:53:58 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Aug 29 06:53:58 volumio volumio-remote-updater[858]: Volumio OTA Client: 1.7.0 Aug 29 06:53:58 volumio systemd[1]: Started ntpsec.service - Network Time Service. Aug 29 06:53:58 volumio volumio-remote-updater[858]: Error: No active session Aug 29 06:53:58 volumio systemd[1]: Received SIGRTMIN+21 from PID 404 (plymouthd). Aug 29 06:53:58 volumio volumio-remote-updater[858]: [2026-08-29 06:53:58] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 29 06:53:58 volumio volumio-remote-updater[858]: [2026-08-29 06:53:58] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 29 06:53:58 volumio volumio-remote-updater[858]: [2026-08-29 06:53:58] [error] handle_connect error: Connection refused Aug 29 06:53:58 volumio kernel: usb 1-2: new high-speed USB device number 7 using xhci_hcd Aug 29 06:53:58 volumio upmpdcli[857]: Could not open config: /tmp/upmpdcli.conf Aug 29 06:53:58 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 29 06:53:58 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 06:53:58 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 06:53:58 volumio systemd[1]: Received SIGRTMIN+21 from PID 404 (plymouthd). Aug 29 06:53:58 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Aug 29 06:53:58 volumio ntpd[991]: INIT: precision = 0.141 usec (-23) Aug 29 06:53:58 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Aug 29 06:53:58 volumio ntpd[991]: INIT: successfully locked into RAM Aug 29 06:53:58 volumio ntpd[991]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Aug 29 06:53:58 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 29 06:53:58 volumio systemd[1]: Reached target getty.target - Login Prompts. Aug 29 06:53:58 volumio ntpd[991]: CONFIG: restrict nopeer ignored Aug 29 06:53:58 volumio ntpd[991]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 29 06:53:58 volumio ntpd[991]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Aug 29 06:53:58 volumio ntpd[991]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 63 days ago Aug 29 06:53:58 volumio ntpd[991]: INIT: Using SO_TIMESTAMPNS(ns) Aug 29 06:53:58 volumio ntpd[991]: IO: Listen and drop on 0 v6wildcard [::]:123 Aug 29 06:53:58 volumio ntpd[991]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 29 06:53:58 volumio ntpd[991]: IO: Listen normally on 2 lo 127.0.0.1:123 Aug 29 06:53:58 volumio ntpd[991]: IO: Listening on routing socket on fd #19 for interface updates Aug 29 06:53:58 volumio ntpd[991]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Aug 29 06:53:58 volumio ntpd[991]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Aug 29 06:53:58 volumio ntpd[991]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120 Aug 29 06:53:58 volumio ntpd[991]: NTSc: Using system default root certificates. Aug 29 06:53:58 volumio sshd[1000]: Server listening on 0.0.0.0 port 22. Aug 29 06:53:58 volumio sshd[1000]: Server listening on :: port 22. Aug 29 06:53:58 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Aug 29 06:53:58 volumio kernel: usb 1-2: New USB device found, idVendor=1286, idProduct=2044, bcdDevice=32.01 Aug 29 06:53:58 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 29 06:53:58 volumio kernel: usb 1-2: Product: Bluetooth and Wireless LAN Composite Device Aug 29 06:53:58 volumio kernel: usb 1-2: Manufacturer: Marvell Aug 29 06:53:58 volumio kernel: usb 1-2: SerialNumber: 281878CBCD47 Aug 29 06:53:58 volumio kernel: usb 1-2: WLAN FW is active Aug 29 06:53:58 volumio rfkill[1006]: unblock set for type bluetooth Aug 29 06:53:58 volumio rfkill[1008]: unblock set for type bluetooth Aug 29 06:53:58 volumio ntpd[991]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Aug 29 06:53:58 volumio kernel: Bluetooth: Core ver 2.22 Aug 29 06:53:58 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Aug 29 06:53:58 volumio kernel: Bluetooth: HCI device and connection manager initialized Aug 29 06:53:58 volumio kernel: Bluetooth: HCI socket layer initialized Aug 29 06:53:58 volumio kernel: Bluetooth: L2CAP socket layer initialized Aug 29 06:53:58 volumio kernel: Bluetooth: SCO socket layer initialized Aug 29 06:53:58 volumio kernel: usbcore: registered new interface driver btusb Aug 29 06:53:58 volumio kernel: Bluetooth: hci0: unexpected event for opcode 0x0000 Aug 29 06:53:58 volumio kernel: Bluetooth: hci0: unexpected event for opcode 0x0000 Aug 29 06:53:58 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Aug 29 06:53:58 volumio (udev-worker)[583]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Aug 29 06:53:58 volumio rfkill[1041]: unblock set for type bluetooth Aug 29 06:53:58 volumio kernel: usb 1-2: host_mlme: disable, key_api: 0 Aug 29 06:53:58 volumio kernel: usb 1-2: CMD_RESP: cmd 0x242 error, result=0x2 Aug 29 06:53:58 volumio kernel: usb 1-2: mwifiex_process_cmdresp: cmd 0x242 failed during initialization Aug 29 06:53:58 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Aug 29 06:53:58 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... Aug 29 06:53:58 volumio kernel: usb 1-2: info: MWIFIEX VERSION: mwifiex 1.0 (14.68.29.p60) Aug 29 06:53:58 volumio kernel: usb 1-2: driver_version = mwifiex 1.0 (14.68.29.p60) Aug 29 06:53:58 volumio (udev-worker)[573]: Network interface NamePolicy= disabled on kernel command line. Aug 29 06:53:58 volumio kernel: usb 1-2 wlan0: renamed from mlan0 Aug 29 06:53:58 volumio avahi-daemon[850]: Server startup complete. Host name is volumio.local. Local service cookie is 4191665022. Aug 29 06:53:58 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Aug 29 06:53:58 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Aug 29 06:53:58 volumio bluetoothd[1049]: Bluetooth daemon 5.83 Aug 29 06:53:58 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Aug 29 06:53:58 volumio sh[1088]: ifup: interface wlan0 already configured Aug 29 06:53:58 volumio sh[1089]: wlan0=wlan0 Aug 29 06:53:58 volumio systemd[1]: Started bluetooth.service - Bluetooth service. Aug 29 06:53:58 volumio systemd[1]: Starting bluealsa.service - BlueALSA service... Aug 29 06:53:58 volumio bluetoothd[1049]: Starting SDP server Aug 29 06:53:58 volumio bluetoothd[1049]: Excluding (cli) sap Aug 29 06:53:58 volumio bluetoothd[1049]: Excluding (cli) scanparam Aug 29 06:53:58 volumio bluetoothd[1049]: Excluding (cli) midi Aug 29 06:53:58 volumio bluetoothd[1049]: Excluding (cli) battery Aug 29 06:53:58 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 29 06:53:58 volumio kernel: Bluetooth: BNEP filters: protocol multicast Aug 29 06:53:58 volumio kernel: Bluetooth: BNEP socket layer initialized Aug 29 06:53:58 volumio kernel: Bluetooth: MGMT ver 1.23 Aug 29 06:53:58 volumio dbus-daemon[851]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.6' (uid=0 pid=1049 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 29 06:53:58 volumio bluetoothd[1049]: Bluetooth management interface 1.23 initialized Aug 29 06:53:58 volumio bluetoothd[1049]: Battery Provider Manager created Aug 29 06:53:58 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 29 06:53:58 volumio kernel: NET: Registered PF_ALG protocol family Aug 29 06:53:58 volumio dhcpcd[876]: wlan0: waiting for carrier Aug 29 06:53:58 volumio dhcpcd[876]: wlan0: carrier acquired Aug 29 06:53:58 volumio dhcpcd[876]: wlan0: IAID 78:cb:cd:47 Aug 29 06:53:58 volumio dhcpcd[876]: wlan0: adding address fe80::d8f7:c124:1842:5079 Aug 29 06:53:58 volumio dhcpcd[876]: ipv6_addaddr1: Permission denied Aug 29 06:53:58 volumio dhcpcd[876]: wlan0: IAID 78:cb:cd:47 Aug 29 06:53:58 volumio dhcpcd[876]: wlan0: carrier lost Aug 29 06:53:58 volumio dbus-daemon[851]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 29 06:53:58 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 29 06:53:58 volumio bluetoothd[1049]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Aug 29 06:53:58 volumio bluetoothd[1049]: bap: Operation not supported (95) Aug 29 06:53:58 volumio bluetoothd[1049]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Aug 29 06:53:59 volumio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Aug 29 06:53:59 volumio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Aug 29 06:53:59 volumio bluealsa[1090]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Aug 29 06:53:59 volumio bluealsa[1090]: main.c:663: Starting main dispatching loop Aug 29 06:53:59 volumio systemd[1]: Started bluealsa.service - BlueALSA service. Aug 29 06:53:59 volumio bluealsa[1090]: main.c:118: Acquired D-Bus service name: org.bluealsa Aug 29 06:53:59 volumio bluealsa[1090]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Aug 29 06:53:59 volumio systemd[1]: Reached target bluetooth.target - Bluetooth Support. Aug 29 06:53:59 volumio bluealsa[1090]: bluez.c:153: Registering media application: /org/bluez/hci0 Aug 29 06:53:59 volumio bluealsa[1090]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Aug 29 06:53:59 volumio bluealsa[1090]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Aug 29 06:53:59 volumio bluealsa[1090]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Aug 29 06:53:59 volumio bluealsa[1090]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Aug 29 06:53:59 volumio bluetoothd[1049]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 29 06:53:59 volumio bluetoothd[1049]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 29 06:53:59 volumio bluetoothd[1049]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 29 06:53:59 volumio bluetoothd[1049]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 29 06:53:59 volumio nmbd[1123]: [2026/08/29 06:53:59.200344, 0] ../../source3/nmbd/nmbd.c:901(main) Aug 29 06:53:59 volumio nmbd[1123]: nmbd version 4.17.12-Debian started. Aug 29 06:53:59 volumio nmbd[1123]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 29 06:53:59 volumio nmbd[1123]: [2026/08/29 06:53:59.203644, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 29 06:53:59 volumio nmbd[1123]: started asyncdns process 1127 Aug 29 06:53:59 volumio nmbd[1123]: [2026/08/29 06:53:59.204006, 0] ../../lib/util/become_daemon.c:150(daemon_status) Aug 29 06:53:59 volumio nmbd[1123]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 29 06:53:59 volumio nmbd[1123]: [2026/08/29 06:53:59.204034, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Aug 29 06:53:59 volumio nmbd[1123]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 29 06:53:59 volumio wireless.js[859]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Aug 29 06:53:59 volumio wireless.js[859]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 29 06:53:59 volumio ntpd[991]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:53:59 volumio ntpd[991]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 29 06:53:59 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Aug 29 06:53:59 volumio ntpd[991]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Aug 29 06:53:59 volumio sudo[1149]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 29 06:53:59 volumio sudo[1149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:53:59 volumio sudo[1149]: pam_unix(sudo:session): session closed for user root Aug 29 06:53:59 volumio sudo[1151]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 29 06:53:59 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:53:59 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Aug 29 06:53:59 volumio wireless.js[859]: WIRELESS.JS - INFO: Cleaning previous... Aug 29 06:53:59 volumio sudo[1154]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 29 06:53:59 volumio sudo[1154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:53:59 volumio sudo[1154]: pam_unix(sudo:session): session closed for user root Aug 29 06:53:59 volumio wireless.js[859]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 29 06:53:59 volumio wireless.js[859]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Aug 29 06:53:59 volumio wireless.js[859]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 28:18:78:cb:cd:47) Aug 29 06:53:59 volumio sudo[1161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 29 06:53:59 volumio sudo[1161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:53:59 volumio sudo[1161]: pam_unix(sudo:session): session closed for user root Aug 29 06:53:59 volumio sudo[1169]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 29 06:53:59 volumio sudo[1169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:00 volumio ntpd[991]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:54:00 volumio ntpd[991]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 29 06:54:00 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Aug 29 06:54:00 volumio ntpd[991]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Aug 29 06:54:01 volumio sudo[1169]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:01 volumio wireless.js[859]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: US Aug 29 06:54:01 volumio sudo[1182]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Aug 29 06:54:01 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:01 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:01 volumio wireless.js[859]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: US Aug 29 06:54:01 volumio wireless.js[859]: WIRELESS.JS - INFO: Start wireless flow Aug 29 06:54:01 volumio wireless.js[859]: WIRELESS.JS - INFO: Stopped hotspot (if there).. Aug 29 06:54:01 volumio sudo[1190]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 29 06:54:01 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:01 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:01 volumio sudo[1192]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 29 06:54:01 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:01 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:01 volumio wireless.js[859]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 29 06:54:01 volumio wireless.js[859]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: 28:18:78:cb:cd:47, USB: true) Aug 29 06:54:01 volumio wpa_supplicant[1198]: Successfully initialized wpa_supplicant Aug 29 06:54:01 volumio sudo[1204]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 29 06:54:01 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:01 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:01 volumio ntpd[991]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:54:01 volumio ntpd[991]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 29 06:54:01 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Aug 29 06:54:01 volumio ntpd[991]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Aug 29 06:54:02 volumio wireless.js[859]: WIRELESS.JS - INFO: DHCP IP fallback Aug 29 06:54:02 volumio wireless.js[859]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor Aug 29 06:54:02 volumio wireless.js[859]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 Aug 29 06:54:02 volumio ntpd[991]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:54:02 volumio ntpd[991]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 29 06:54:02 volumio ntpd[991]: DNS: dns_check: DNS error: -11, System error Aug 29 06:54:02 volumio ntpd[991]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Aug 29 06:54:02 volumio wireless.js[859]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) Aug 29 06:54:03 volumio volumio-remote-updater[858]: [2026-08-29 06:54:03] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 29 06:54:03 volumio volumio-remote-updater[858]: [2026-08-29 06:54:03] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 29 06:54:03 volumio volumio-remote-updater[858]: [2026-08-29 06:54:03] [error] handle_connect error: Connection refused Aug 29 06:54:03 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 29 06:54:04 volumio wpa_supplicant[1199]: wlan0: Trying to associate with d2:21:f9:3a:1f:f7 (SSID='Openwrt' freq=5765 MHz) Aug 29 06:54:04 volumio kernel: usb 1-2: info: trying to associate to bssid d2:21:f9:3a:1f:f7 Aug 29 06:54:04 volumio wpa_supplicant[1199]: wlan0: Associated with d2:21:f9:3a:1f:f7 Aug 29 06:54:04 volumio wpa_supplicant[1199]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 29 06:54:04 volumio kernel: usb 1-2: assoc: associated with d2:21:f9:3a:1f:f7 Aug 29 06:54:04 volumio kernel: usb 1-2: info: associated to bssid d2:21:f9:3a:1f:f7 successfully Aug 29 06:54:04 volumio kernel: usb 1-2: key: send EAPOL to d2:21:f9:3a:1f:f7 Aug 29 06:54:04 volumio wpa_supplicant[1199]: wlan0: WPA: Key negotiation completed with d2:21:f9:3a:1f:f7 [PTK=CCMP GTK=CCMP] Aug 29 06:54:04 volumio wpa_supplicant[1199]: wlan0: CTRL-EVENT-CONNECTED - Connection to d2:21:f9:3a:1f:f7 completed [id=0 id_str=] Aug 29 06:54:04 volumio dhcpcd[876]: wlan0: carrier acquired Aug 29 06:54:04 volumio dhcpcd[876]: wlan0: connected to Access Point: Openwrt Aug 29 06:54:04 volumio bash[961]: setdatetime-helper: all HTTPS Date fallbacks failed Aug 29 06:54:04 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 29 06:54:04 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 29 06:54:04 volumio kernel: usb 1-2: key: send EAPOL to d2:21:f9:3a:1f:f7 Aug 29 06:54:04 volumio dhcpcd[876]: wlan0: IAID 78:cb:cd:47 Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 1512ms) Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to d2:21:f9:3a:1f:f7 Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: USB WiFi Capabilities: Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: Chipset: 1-2 Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: AP mode: Yes Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: Concurrent: Yes Aug 29 06:54:04 volumio wireless.js[859]: WIRELESS.JS - INFO: Restarting dhcpcd.service for reliable DHCP Aug 29 06:54:04 volumio sudo[1257]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart dhcpcd.service Aug 29 06:54:04 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:04 volumio systemd[1]: Stopping dhcpcd.service - DHCP Client Daemon on all interfaces... Aug 29 06:54:04 volumio dhcpcd[1259]: sending signal TERM to pid 875 Aug 29 06:54:04 volumio dhcpcd[1259]: waiting for pid 875 to exit Aug 29 06:54:04 volumio dhcpcd[1259]: sending signal TERM to pid 875 Aug 29 06:54:04 volumio dhcpcd[1259]: waiting for pid 875 to exit Aug 29 06:54:04 volumio dhcpcd[876]: received SIGTERM, stopping Aug 29 06:54:04 volumio dhcpcd[876]: wlan0: removing interface Aug 29 06:54:04 volumio systemd[1]: dhcpcd.service: Deactivated successfully. Aug 29 06:54:04 volumio systemd[1]: Stopped dhcpcd.service - DHCP Client Daemon on all interfaces. Aug 29 06:54:04 volumio systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Aug 29 06:54:04 volumio dhcpcd[1261]: dhcpcd-9.4.1 starting Aug 29 06:54:04 volumio dhcpcd[1264]: dev: loaded udev Aug 29 06:54:04 volumio dhcpcd[1264]: DUID 00:04:23:d3:ba:aa:83:1c:bb:b0:20:1d:15:30:30:ab:3a:14 Aug 29 06:54:04 volumio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Aug 29 06:54:04 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:04 volumio dhcpcd[1264]: wlan0: connected to Access Point: Openwrt Aug 29 06:54:04 volumio dhcpcd[1264]: wlan0: IAID 78:cb:cd:47 Aug 29 06:54:04 volumio dhcpcd[1264]: wlan0: adding address fe80::6a29:ed59:547:1c99 Aug 29 06:54:04 volumio dhcpcd[1264]: ipv6_addaddr1: Permission denied Aug 29 06:54:04 volumio dhcpcd[1264]: wlan0: soliciting an IPv6 router Aug 29 06:54:05 volumio dhcpcd[1264]: wlan0: rebinding lease of 192.168.120.114 Aug 29 06:54:05 volumio dhcpcd[1264]: wlan0: probing address 192.168.120.114/24 Aug 29 06:54:06 volumio wireless.js[859]: WIRELESS.JS - INFO: Start ap Aug 29 06:54:06 volumio wireless.js[859]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 29 06:54:06 volumio systemd[1]: Started wireless.service - Wireless Services. Aug 29 06:54:06 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Aug 29 06:54:06 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Aug 29 06:54:06 volumio systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Aug 29 06:54:06 volumio systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Aug 29 06:54:06 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Aug 29 06:54:06 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Aug 29 06:54:06 volumio volumio-cpu-tweak[1287]: Setting RT Priority for mpd Aug 29 06:54:06 volumio volumio-cpu-tweak[1316]: pid 35's current scheduling policy: SCHED_OTHER Aug 29 06:54:06 volumio volumio-cpu-tweak[1316]: pid 35's current scheduling priority: 0 Aug 29 06:54:06 volumio volumio-cpu-tweak[1287]: Setting MPD Affinity Aug 29 06:54:06 volumio volumio-cpu-tweak[1318]: pid 3's current affinity mask: f Aug 29 06:54:06 volumio volumio-cpu-tweak[1287]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 29 06:54:06 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Aug 29 06:54:06 volumio soundcard-init.sh[1352]: Card 0 Chip Realtek Generic Name HDA Intel PCH Aug 29 06:54:06 volumio soundcard-init.sh[1364]: Simple mixer control 'Master',0 Aug 29 06:54:06 volumio soundcard-init.sh[1364]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Aug 29 06:54:06 volumio soundcard-init.sh[1364]: Playback channels: Mono Aug 29 06:54:06 volumio soundcard-init.sh[1364]: Limits: Playback 0 - 87 Aug 29 06:54:06 volumio soundcard-init.sh[1364]: Mono: Playback 65 [75%] [-16.50dB] [on] Aug 29 06:54:06 volumio startx[1345]: X.Org X Server 1.21.1.7 Aug 29 06:54:06 volumio startx[1345]: X Protocol Version 11, Revision 0 Aug 29 06:54:06 volumio startx[1345]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Aug 29 06:54:06 volumio startx[1345]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=70a86aca-25df-40d1-8d1b-e7528535975f bootpart=UUID=34D3-6B91 datapart=UUID=612766c6-6c19-4a14-93db-507079e22c91 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Aug 29 06:54:06 volumio startx[1345]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support) Aug 29 06:54:06 volumio startx[1345]: Current version of pixman: 0.42.2 Aug 29 06:54:06 volumio startx[1345]: Before reporting problems, check http://wiki.x.org Aug 29 06:54:06 volumio startx[1345]: to make sure that you have the latest version. Aug 29 06:54:06 volumio startx[1345]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 29 06:54:06 volumio startx[1345]: (++) from command line, (!!) notice, (II) informational, Aug 29 06:54:06 volumio startx[1345]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 29 06:54:06 volumio startx[1345]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Aug 29 06:54:06 2026 Aug 29 06:54:06 volumio soundcard-init.sh[1352]: Card 1 Chip Intel Haswell HDMI Name HDA Intel HDMI Aug 29 06:54:06 volumio startx[1345]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 29 06:54:06 volumio systemd[1]: soundcard-init.service: Deactivated successfully. Aug 29 06:54:07 volumio wireless.js[859]: WIRELESS.JS - INFO: trying... Aug 29 06:54:07 volumio sudo[1386]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 06:54:07 volumio sudo[1386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:07 volumio sudo[1386]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:07 volumio sudo[1390]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:07 volumio sudo[1390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:07 volumio sudo[1390]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:07 volumio wireless.js[859]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 29 06:54:07 volumio volumio[1274]: info: ------------------------------------------- Aug 29 06:54:07 volumio volumio[1274]: info: ----- Volumio3 ---- Aug 29 06:54:07 volumio volumio[1274]: info: ------------------------------------------- Aug 29 06:54:07 volumio volumio[1274]: info: ----- System startup ---- Aug 29 06:54:07 volumio volumio[1274]: info: ------------------------------------------- Aug 29 06:54:08 volumio volumio[1274]: info: MYVOLUMIO Environment detected Aug 29 06:54:08 volumio volumio[1274]: info: Plugin folders cleanup Aug 29 06:54:08 volumio volumio[1274]: info: Scanning into folder /volumio/app/plugins/ Aug 29 06:54:08 volumio volumio[1274]: info: Scanning category audio_interface Aug 29 06:54:08 volumio volumio[1274]: info: Scanning category miscellanea Aug 29 06:54:08 volumio volumio[1274]: info: Scanning category music_service Aug 29 06:54:08 volumio volumio[1274]: info: Scanning category plugins.json Aug 29 06:54:08 volumio volumio[1274]: info: Scanning category system_controller Aug 29 06:54:08 volumio volumio[1274]: info: Scanning category user_interface Aug 29 06:54:08 volumio volumio[1274]: info: Scanning into folder /data/plugins/ Aug 29 06:54:08 volumio volumio[1274]: info: Plugin folders cleanup completed Aug 29 06:54:08 volumio volumio[1274]: info: ------------------------------------------- Aug 29 06:54:08 volumio volumio[1274]: info: ----- Core plugins startup ---- Aug 29 06:54:08 volumio volumio[1274]: info: ------------------------------------------- Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 06:54:08 volumio volumio[1274]: info: Adding plugin upnp to MyMusic Plugins Aug 29 06:54:08 volumio volumio[1274]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 06:54:08 volumio volumio[1274]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugins from folder /data/plugins/ Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "system"... Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "appearance"... Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "network"... Aug 29 06:54:08 volumio volumio[1274]: info: Refreshing Cached IP Addresses Aug 29 06:54:08 volumio sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 06:54:08 volumio sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:08 volumio sudo[1429]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:08 volumio wireless.js[859]: WIRELESS.JS - INFO: trying... Aug 29 06:54:08 volumio sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:08 volumio sudo[1429]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "services"... Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "volumio5onboarding"... Aug 29 06:54:08 volumio sudo[1427]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:08 volumio sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 06:54:08 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "alsa_controller"... Aug 29 06:54:08 volumio sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 06:54:08 volumio sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:08 volumio sudo[1464]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:08 volumio sudo[1468]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:08 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:08 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:08 volumio volumio[1274]: warn: Unable to locate the audio output device FiiO USB Audio Class 2.0 DAC. Please configure a valid output device. Aug 29 06:54:08 volumio wireless.js[859]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 29 06:54:08 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "wizard"... Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "networkfs"... Aug 29 06:54:08 volumio volumio[1274]: info: Starting Udev Watcher for removable devices Aug 29 06:54:08 volumio volumio[1274]: info: Ignoring mount for partition: boot Aug 29 06:54:08 volumio volumio[1274]: info: Ignoring mount for partition: volumio Aug 29 06:54:08 volumio volumio[1274]: info: Ignoring mount for partition: volumio_data Aug 29 06:54:08 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 06:54:08 volumio sudo[1475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangwy,password=Hwylhy380),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.2/NasShare/Music /mnt/NAS/NAS Aug 29 06:54:08 volumio sudo[1475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "volumio_command_line_client"... Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "upnp"... Aug 29 06:54:08 volumio volumio[1274]: info: [1788000848793] Starting Upmpd Daemon Aug 29 06:54:08 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "my_music"... Aug 29 06:54:08 volumio volumio[1274]: info: Loading plugin "mpd"... Aug 29 06:54:08 volumio kernel: netfs: FS-Cache loaded Aug 29 06:54:08 volumio kernel: Key type dns_resolver registered Aug 29 06:54:08 volumio sudo[1475]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:08 volumio kernel: Key type cifs.spnego registered Aug 29 06:54:08 volumio kernel: Key type cifs.idmap registered Aug 29 06:54:08 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Aug 29 06:54:08 volumio kernel: CIFS: Attempting to mount //192.168.10.2/NasShare/Music Aug 29 06:54:08 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 29 06:54:08 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 29 06:54:09 volumio volumio[1274]: info: Loading plugin "upnp_browser"... Aug 29 06:54:09 volumio wireless.js[859]: WIRELESS.JS - INFO: trying... Aug 29 06:54:09 volumio sudo[1571]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 06:54:09 volumio sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:09 volumio sudo[1571]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:09 volumio sudo[1574]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:09 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:09 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:09 volumio wireless.js[859]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 29 06:54:09 volumio volumio[1274]: info: Starting UPNP Browser Aug 29 06:54:09 volumio volumio[1274]: info: Loading plugin "alarm-clock"... Aug 29 06:54:09 volumio volumio[1274]: info: Loading plugin "airplay_emulation"... Aug 29 06:54:09 volumio volumio[1274]: info: Starting Shairport Sync Aug 29 06:54:09 volumio volumio[1274]: info: Loading plugin "last_100"... Aug 29 06:54:09 volumio volumio[1274]: info: Loading plugin "webradio"... Aug 29 06:54:09 volumio volumio[1274]: info: Loading plugin "i2s_dacs"... Aug 29 06:54:09 volumio volumio[1274]: info: I2S DAC not set, start Auto-detection Aug 29 06:54:09 volumio volumio[1274]: info: Loading plugin "volumiodiscovery"... Aug 29 06:54:10 volumio volumio[1274]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 06:54:10 volumio volumio[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 06:54:10 volumio volumio[1274]: *** WARNING *** For more information see Aug 29 06:54:10 volumio volumio[1274]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 06:54:10 volumio volumio[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 06:54:10 volumio volumio[1274]: *** WARNING *** For more information see Aug 29 06:54:10 volumio node[1274]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 06:54:10 volumio node[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 06:54:10 volumio node[1274]: *** WARNING *** For more information see Aug 29 06:54:10 volumio node[1274]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 06:54:10 volumio node[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 06:54:10 volumio node[1274]: *** WARNING *** For more information see Aug 29 06:54:10 volumio volumio[1274]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 06:54:10 volumio volumio[1274]: info: Discovery: Started advertising with name: Volumio Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 06:54:10 volumio volumio[1274]: info: Loading plugin "outputs"... Aug 29 06:54:10 volumio volumio[1274]: info: Loading plugin "albumart"... Aug 29 06:54:10 volumio volumio[1274]: info: Plugin example_plugin is not enabled Aug 29 06:54:10 volumio volumio[1274]: info: Loading plugin "inputs"... Aug 29 06:54:10 volumio volumio[1274]: info: Loading plugin "updater_comm"... Aug 29 06:54:10 volumio volumio[1587]: Forking 3 albumart workers Aug 29 06:54:10 volumio volumio[1274]: info: Plugin mpdemulation is not enabled Aug 29 06:54:10 volumio volumio[1274]: info: Loading plugin "rest_api"... Aug 29 06:54:10 volumio volumio[1274]: info: Loading plugin "websocket"... Aug 29 06:54:10 volumio volumio[1274]: info: Starting Socket.io Server version 1.7.4 Aug 29 06:54:10 volumio volumio[1274]: info: Loading i18n strings for locale zh Aug 29 06:54:10 volumio volumio[1274]: Updating browse sources language Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 06:54:10 volumio volumio[1598]: Starting albumart workers Aug 29 06:54:10 volumio volumio[1599]: Starting albumart workers Aug 29 06:54:10 volumio volumio[1597]: Starting albumart workers Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::initPlayerControls Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: Express server listening on port 3000 Aug 29 06:54:10 volumio volumio[1274]: [Metrics] WebUI: 3s 119.57ms Aug 29 06:54:10 volumio volumio[1274]: info: Setting Device type: x86 Aug 29 06:54:10 volumio volumio[1274]: info: CoreStateMachine::resetVolumioState Aug 29 06:54:10 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 06:54:10 volumio volumio[1274]: info: CoreStateMachine::pushState Aug 29 06:54:10 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 29 06:54:10 volumio volumio[1274]: info: Completed loading Core Plugins Aug 29 06:54:10 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Aug 29 06:54:10 volumio volumio[1274]: info: Volumio Network Manager: Network status updated: 0 Aug 29 06:54:10 volumio volumio[1274]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Aug 29 06:54:10 volumio volumio[1274]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 29 06:54:10 volumio volumio-remote-updater[858]: [2026-08-29 06:54:10] [connect] Successful connection Aug 29 06:54:10 volumio wireless.js[859]: WIRELESS.JS - INFO: trying... Aug 29 06:54:10 volumio volumio[1274]: info: Reloading queue from file Aug 29 06:54:10 volumio volumio-remote-updater[858]: [2026-08-29 06:54:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788000850 101 Aug 29 06:54:10 volumio volumio[1274]: 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 Aug 29 06:54:10 volumio volumio[1274]: info: CoreStateMachine::setRepeat null single undefined Aug 29 06:54:10 volumio volumio[1274]: info: CoreStateMachine::pushState Aug 29 06:54:10 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Aug 29 06:54:10 volumio volumio[1274]: info: CoreStateMachine::setRandom null Aug 29 06:54:10 volumio volumio[1274]: info: CoreStateMachine::pushState Aug 29 06:54:10 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Aug 29 06:54:10 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Aug 29 06:54:10 volumio volumio[1274]: info: Output device has changed, restarting MPD Aug 29 06:54:10 volumio sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 06:54:10 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:10 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:10 volumio sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 06:54:10 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:10 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:10 volumio sudo[1680]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:10 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:10 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:10 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:10 volumio sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 06:54:10 volumio sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:10 volumio wireless.js[859]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 29 06:54:10 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 06:54:10 volumio volumio[1274]: info: ___________ START PLUGINS ___________ Aug 29 06:54:10 volumio volumio[1274]: info: ControllerMpd::onStart: Initializing MPD Aug 29 06:54:10 volumio volumio[1274]: info: Creating MPD Configuration file Aug 29 06:54:10 volumio sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 29 06:54:10 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 06:54:10 volumio volumio[1274]: info: [1788000850776] CoreMusicLibrary::Adding element 媒体服务器 Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 06:54:10 volumio volumio[1274]: info: UPNP Browser: Client initialized successfully Aug 29 06:54:10 volumio sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:10 volumio sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:10 volumio sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 06:54:10 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:10 volumio sudo[1690]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:10 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 06:54:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 06:54:10 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 06:54:10 volumio volumio[1274]: info: [1788000850813] CoreMusicLibrary::Adding element Last_100 Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 06:54:10 volumio volumio[1274]: info: [1788000850816] CoreMusicLibrary::Adding element Webradio Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 06:54:10 volumio volumio[1274]: info: Initializing BBC Radios Aug 29 06:54:10 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 29 06:54:10 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 06:54:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 06:54:10 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 06:54:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 06:54:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 06:54:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 06:54:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 06:54:10 volumio volumio[1274]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:10 volumio dhcpcd[1264]: wlan0: leased 192.168.120.114 for 1800 seconds Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 06:54:10 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:10 volumio volumio[1274]: info: Volumio Calling Home Aug 29 06:54:10 volumio dhcpcd[1264]: wlan0: adding route to 192.168.120.0/24 Aug 29 06:54:10 volumio avahi-daemon[850]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.120.114. Aug 29 06:54:10 volumio avahi-daemon[850]: New relevant interface wlan0.IPv4 for mDNS. Aug 29 06:54:10 volumio avahi-daemon[850]: Registering new address record for 192.168.120.114 on wlan0.IPv4. Aug 29 06:54:10 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 29 06:54:10 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 29 06:54:10 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 29 06:54:10 volumio dhcpcd[1264]: wlan0: adding default route via 192.168.120.1 Aug 29 06:54:10 volumio sudo[1711]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 06:54:10 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 29 06:54:10 volumio sudo[1711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:10 volumio sudo[1746]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 29 06:54:10 volumio sudo[1711]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:10 volumio welcome[1735]: Resolved ip:[1] 192.168.120.114 Aug 29 06:54:10 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 29 06:54:10 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 29 06:54:10 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Aug 29 06:54:11 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Aug 29 06:54:11 volumio volumio[1274]: Unhandled rejection Error: No sockets available, cannot start. Aug 29 06:54:11 volumio volumio[1274]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 29 06:54:11 volumio volumio[1274]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 29 06:54:11 volumio volumio[1274]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 29 06:54:11 volumio volumio[1274]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 29 06:54:11 volumio volumio[1274]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 29 06:54:11 volumio volumio[1274]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 29 06:54:11 volumio volumio[1274]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 29 06:54:11 volumio volumio[1274]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 29 06:54:11 volumio volumio[1274]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Aug 29 06:54:11 volumio volumio[1274]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 29 06:54:11 volumio volumio[1274]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 29 06:54:11 volumio volumio[1274]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 29 06:54:11 volumio volumio[1274]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 29 06:54:11 volumio volumio[1274]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 29 06:54:11 volumio volumio[1274]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 29 06:54:11 volumio volumio[1274]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 29 06:54:11 volumio volumio[1274]: info: MPD Permissions set Aug 29 06:54:11 volumio volumio[1274]: info: MPD Permissions set Aug 29 06:54:11 volumio volumio[1274]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.131-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:11 volumio volumio[1274]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 29 06:54:11 volumio volumio[1274]: info: Starting Shairport Sync Aug 29 06:54:11 volumio volumio[1274]: info: Starting Shairport Sync Aug 29 06:54:11 volumio volumio[1274]: info: Starting Shairport Sync Aug 29 06:54:11 volumio volumio[1274]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 29 06:54:11 volumio sudo[1787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 06:54:11 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:11 volumio sudo[1784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 06:54:11 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:11 volumio sudo[1784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:11 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:11 volumio sudo[1789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 06:54:11 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 06:54:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 06:54:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 06:54:11 volumio systemd[1]: shairport-sync.service: Consumed 1.800s CPU time. Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.216-04:00 level=INFO msg="system info for 5cdc86ea72bc8c81e26952be9b027257" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 29 06:54:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 06:54:11 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:11 volumio volumio[1274]: info: Shairport-Sync Started Aug 29 06:54:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 06:54:11 volumio volumio[1274]: Error adding Membership: Error: addMembership EINVAL Aug 29 06:54:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 06:54:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 06:54:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 06:54:11 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:11 volumio sudo[1784]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:11 volumio volumio[1274]: info: Shairport-Sync Started Aug 29 06:54:11 volumio volumio[1274]: info: Shairport-Sync Started Aug 29 06:54:11 volumio winbindd[1760]: [2026/08/29 06:54:11.297161, 0] ../../source3/winbindd/winbindd.c:1440(main) Aug 29 06:54:11 volumio winbindd[1760]: winbindd version 4.17.12-Debian started. Aug 29 06:54:11 volumio winbindd[1760]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 29 06:54:11 volumio winbindd[1760]: [2026/08/29 06:54:11.304502, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Aug 29 06:54:11 volumio winbindd[1760]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 29 06:54:11 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Aug 29 06:54:11 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Aug 29 06:54:11 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:11 volumio smbd[1825]: [2026/08/29 06:54:11.583969, 0] ../../source3/smbd/server.c:1741(main) Aug 29 06:54:11 volumio smbd[1825]: smbd version 4.17.12-Debian started. Aug 29 06:54:11 volumio smbd[1825]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.627-04:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 29 06:54:11 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:11 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:11 volumio volumio-remote-updater[858]: Test mode disabled Aug 29 06:54:11 volumio volumio-remote-updater[858]: Alpha mode disabled Aug 29 06:54:11 volumio volumio-remote-updater[858]: Alpha legacy test mode disabled Aug 29 06:54:11 volumio wireless.js[859]: WIRELESS.JS - INFO: trying... Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: adding 0e7bf662-b5f6-4b3b-943f-0a5516904cfc Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Found device Volumio Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:11 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: this is already registered, 0e7bf662-b5f6-4b3b-943f-0a5516904cfc Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Found device Volumio Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:11 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:11 volumio sudo[1841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 06:54:11 volumio sudo[1841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:11 volumio sudo[1841]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:11 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:11 volumio sudo[1848]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:11 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:11 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:11 volumio wireless.js[859]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.120.114, ipV6 is undefined Aug 29 06:54:11 volumio wireless.js[859]: WIRELESS.JS - INFO: Connected to SSID: Openwrt Aug 29 06:54:11 volumio wireless.js[859]: WIRELESS.JS - INFO: It's done! AP Aug 29 06:54:11 volumio wireless.js[859]: WIRELESS.JS - INFO: Restarting avahi-daemon... Aug 29 06:54:11 volumio volumio[1274]: info: Volumio called home Aug 29 06:54:11 volumio sudo[1853]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Aug 29 06:54:11 volumio sudo[1853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 06:54:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 06:54:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 06:54:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 06:54:11 volumio avahi-daemon[850]: Got SIGTERM, quitting. Aug 29 06:54:11 volumio avahi-daemon[850]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.120.114. Aug 29 06:54:11 volumio avahi-daemon[850]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 29 06:54:11 volumio avahi-daemon[850]: avahi-daemon 0.8 exiting. Aug 29 06:54:11 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 29 06:54:11 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Aug 29 06:54:11 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 29 06:54:11 volumio volumio[1274]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 06:54:11 volumio volumio[1274]: error: Discovery: Advertisement error: Error: dns service error: unknown Aug 29 06:54:11 volumio volumio[1274]: error: Discovery: advertisement error: Error: dns service error: unknown Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Stopping Advertising Immediately Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Stopping existing advertisement Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Browse raised the following error Error: dns service error: unknown Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Restarting Browsing Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.864-04:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.864-04:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.864-04:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:11 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 29 06:54:11 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:11 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:11 volumio avahi-daemon[1855]: Process 850 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Aug 29 06:54:11 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:11 volumio avahi-daemon[1855]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Aug 29 06:54:11 volumio avahi-daemon[1855]: Successfully dropped root privileges. Aug 29 06:54:11 volumio avahi-daemon[1855]: avahi-daemon 0.8 starting up. Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 06:54:11 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 06:54:11 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 29 06:54:11 volumio avahi-daemon[1855]: Successfully called chroot(). Aug 29 06:54:11 volumio avahi-daemon[1855]: Successfully dropped remaining capabilities. Aug 29 06:54:11 volumio avahi-daemon[1855]: No service file found in /etc/avahi/services. Aug 29 06:54:11 volumio avahi-daemon[1855]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.120.114. Aug 29 06:54:11 volumio avahi-daemon[1855]: New relevant interface wlan0.IPv4 for mDNS. Aug 29 06:54:11 volumio avahi-daemon[1855]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.879-04:00 level=INFO msg="enabling local network discovery" Aug 29 06:54:11 volumio avahi-daemon[1855]: New relevant interface lo.IPv4 for mDNS. Aug 29 06:54:11 volumio avahi-daemon[1855]: Network interface enumeration completed. Aug 29 06:54:11 volumio avahi-daemon[1855]: Registering new address record for 192.168.120.114 on wlan0.IPv4. Aug 29 06:54:11 volumio avahi-daemon[1855]: Registering new address record for 127.0.0.1 on lo.IPv4. Aug 29 06:54:11 volumio sudo[1853]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 06:54:11 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:11.898-04:00 level=INFO msg="enabling BLE discovery" Aug 29 06:54:11 volumio wireless.js[859]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 29 06:54:12 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:12 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:12 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:12 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:12 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:12 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:12 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:12 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 06:54:12 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 06:54:12 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Aug 29 06:54:12 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Aug 29 06:54:12 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Aug 29 06:54:12 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 29 06:54:12 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 29 06:54:12 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 29 06:54:12 volumio avahi-daemon[1855]: Server startup complete. Host name is volumio.local. Local service cookie is 3276756255. Aug 29 06:54:12 volumio mpd[1749]: 2026-08-29T06:54:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 06:54:12 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 06:54:12 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:12 volumio sudo[1677]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:12 volumio systemd[1]: Startup finished in 1.979s (firmware) + 2.601s (loader) + 14.490s (kernel) + 18.288s (userspace) = 37.359s. Aug 29 06:54:12 volumio volumio[1274]: info: Completed starting Core Plugins Aug 29 06:54:12 volumio volumio[1274]: info: ------------------------------------------- Aug 29 06:54:12 volumio volumio[1274]: info: ----- MyVolumio plugins startup ---- Aug 29 06:54:12 volumio volumio[1274]: info: ------------------------------------------- Aug 29 06:54:12 volumio volumio[1274]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 06:54:13 volumio volumio[1274]: error: MPD error: The expression evaluated to a falsy value: Aug 29 06:54:13 volumio volumio[1274]: assert.ok(self.idling) Aug 29 06:54:13 volumio volumio[1274]: error: The expression evaluated to a falsy value: Aug 29 06:54:13 volumio volumio[1274]: assert.ok(self.idling) Aug 29 06:54:13 volumio volumio[1274]: error: updateQueue error: null Aug 29 06:54:13 volumio volumio[1274]: info: MPD running with PID1749 Aug 29 06:54:13 volumio volumio[1274]: ,establishing connection Aug 29 06:54:13 volumio volumio[1274]: error: updateQueue error: null Aug 29 06:54:13 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 29 06:54:13 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 06:54:13 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 06:54:13 volumio upmpdcli[1965]: Could not open config: /tmp/upmpdcli.conf Aug 29 06:54:13 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 06:54:13 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 06:54:13 volumio ntpd[991]: IO: Listen normally on 3 wlan0 192.168.120.114:123 Aug 29 06:54:13 volumio ntpd[991]: IO: new interface(s) found: waking up resolver Aug 29 06:54:13 volumio ntpd[991]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:54:13 volumio ntpd[991]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 29 06:54:13 volumio ntpd[991]: DNS: Pool taking: 45.83.234.123 Aug 29 06:54:13 volumio ntpd[991]: DNS: Pool taking: 198.137.202.32 Aug 29 06:54:13 volumio ntpd[991]: DNS: Pool taking: 208.70.148.101 Aug 29 06:54:13 volumio ntpd[991]: DNS: Pool taking: 149.28.200.179 Aug 29 06:54:13 volumio ntpd[991]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 29 06:54:14 volumio volumio[1274]: 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/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 06:54:14 volumio ntpd[991]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:54:14 volumio ntpd[991]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 172.235.154.118 Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 45.33.53.84 Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 173.249.203.227 Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 207.58.172.126 Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 2001:19f0:6401:400:5400:4ff:fec3:522a Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 2602:ff23:50:3c2::1 Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 2607:f710:35::29c:0:5 Aug 29 06:54:14 volumio ntpd[991]: DNS: Pool taking: 2a05:dfc1:cb1:201:: Aug 29 06:54:14 volumio ntpd[991]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 29 06:54:14 volumio volumio[1274]: 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/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:14 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 06:54:14 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:14 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:14 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:14 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:14 volumio volumio[1274]: info: Listing playlists Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 06:54:14 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 06:54:14 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:14.930-04:00 level=ERROR msg="failed to read from connection" component=conn/multi error="http: Server closed" Aug 29 06:54:14 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:14.930-04:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ws+tcp addr=[::]:7331 Aug 29 06:54:14 volumio volumio5-onboarding[1706]: time=2026-08-29T06:54:14.933-04:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=28:18:78:CB:CD:48%00 Aug 29 06:54:14 volumio volumio5-onboarding[1706]: failed to run app: failed to run mDNS advertiser: failed to create service: failed to add service to entry group: avahi: Bad state Aug 29 06:54:14 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 29 06:54:14 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 29 06:54:15 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 29 06:54:15 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 29 06:54:15 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.098-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 29 06:54:15 volumio volumio[1274]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 29 06:54:15 volumio volumio[1274]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 29 06:54:15 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:15 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:15 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.118-04:00 level=INFO msg="system info for 5cdc86ea72bc8c81e26952be9b027257" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.134-04:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 29 06:54:15 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:15 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:15 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:15 volumio volumio-remote-updater[858]: Test mode disabled Aug 29 06:54:15 volumio volumio-remote-updater[858]: Alpha mode disabled Aug 29 06:54:15 volumio volumio-remote-updater[858]: Alpha legacy test mode disabled Aug 29 06:54:15 volumio volumio[1274]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.325-04:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.326-04:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.326-04:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:15 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:15 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:15 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.331-04:00 level=INFO msg="enabling local network discovery" Aug 29 06:54:15 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:15.336-04:00 level=INFO msg="enabling BLE discovery" Aug 29 06:54:15 volumio ntpd[991]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:54:15 volumio ntpd[991]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 29 06:54:15 volumio ntpd[991]: DNS: Pool taking: 69.10.208.170 Aug 29 06:54:15 volumio ntpd[991]: DNS: Pool taking: 50.251.160.20 Aug 29 06:54:15 volumio ntpd[991]: DNS: Pool taking: 199.85.8.46 Aug 29 06:54:15 volumio ntpd[991]: DNS: Pool taking: 198.71.50.75 Aug 29 06:54:15 volumio ntpd[991]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 29 06:54:15 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:15 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:15 volumio sudo[2009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangwy,password=Hwylhy380),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.2/NasShare/Music /mnt/NAS/NAS Aug 29 06:54:15 volumio sudo[2009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:15 volumio kernel: CIFS: Attempting to mount //192.168.10.2/NasShare/Music Aug 29 06:54:16 volumio volumio5-onboarding[1995]: time=2026-08-29T06:54:16.127-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 06:54:16 volumio sudo[2009]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:16 volumio ntpd[991]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 06:54:16 volumio ntpd[991]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 29 06:54:16 volumio ntpd[991]: DNS: Pool taking: 172.233.155.39 Aug 29 06:54:16 volumio ntpd[991]: DNS: Pool taking: 73.238.61.241 Aug 29 06:54:16 volumio ntpd[991]: DNS: Pool taking: 172.234.37.140 Aug 29 06:54:16 volumio ntpd[991]: DNS: Pool taking: 50.205.57.38 Aug 29 06:54:16 volumio ntpd[991]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 29 06:54:16 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:16 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:16 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:16 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:16 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:16 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:16 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:16 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:16 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 06:54:16 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 06:54:16 volumio volumio[1274]: info: Discovery: Restarting Advertising Aug 29 06:54:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:17 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:17 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:17 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 06:54:17 volumio volumio[1274]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 29 06:54:18 volumio volumio[1274]: info: Volumio Network Manager: Network status updated: 2 Aug 29 06:54:18 volumio sudo[2042]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 06:54:18 volumio sudo[2042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:18 volumio sudo[2044]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:18 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:18 volumio sudo[2044]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:18 volumio sudo[2042]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:18 volumio sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 06:54:18 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:19 volumio ntpd[991]: CLOCK: time stepped by 0.498786 Aug 29 06:54:19 volumio ntpd[991]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 29 06:54:21 volumio volumio[1274]: info: Adding plugin bluetooth to MyMusic Plugins Aug 29 06:54:21 volumio volumio[1274]: info: Adding plugin multiroom to MyMusic Plugins Aug 29 06:54:21 volumio volumio[1274]: info: Adding plugin metavolumio to MyMusic Plugins Aug 29 06:54:21 volumio volumio[1274]: info: Adding plugin cd_controller to MyMusic Plugins Aug 29 06:54:21 volumio volumio[1274]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 29 06:54:21 volumio volumio[1274]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 29 06:54:21 volumio volumio[1274]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 29 06:54:21 volumio volumio[1274]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 29 06:54:22 volumio volumio[1274]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 29 06:54:22 volumio volumio[1274]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 29 06:54:22 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:22 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:22 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:22 volumio volumio[1274]: info: Starting MyVolumio Remote Streaming Endpoints Aug 29 06:54:22 volumio volumio[1274]: info: MyVolumio login type: Token Aug 29 06:54:22 volumio volumio[1274]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 29 06:54:22 volumio volumio[1274]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 29 06:54:22 volumio volumio[1274]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 29 06:54:22 volumio volumio[1274]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 29 06:54:22 volumio volumio[1274]: info: Streaming services startup Aug 29 06:54:22 volumio volumio[1274]: info: Starting Streaming Daemon Aug 29 06:54:22 volumio sudo[2056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 06:54:22 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:22 volumio volumio[1274]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 29 06:54:22 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:22 volumio volumio[1274]: info: Discovery: Started advertising with name: Volumio Aug 29 06:54:22 volumio volumio[1274]: error: Cannot start Volumio Streaming Daemon Aug 29 06:54:22 volumio volumio[1274]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 06:54:22 volumio volumio[1274]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 06:54:22 volumio volumio[1274]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 29 06:54:23 volumio volumio[1274]: info: MyVolumio token set successfully Aug 29 06:54:23 volumio volumio[1274]: info: MYVOLUMIO: Adding device Aug 29 06:54:23 volumio volumio[1274]: info: MYVOLUMIO: Evaluating Server Aug 29 06:54:23 volumio volumio[1274]: info: MyVolumio status changed Aug 29 06:54:23 volumio volumio[1274]: info: Streaming services startup Aug 29 06:54:23 volumio volumio[1274]: info: Starting Streaming Daemon Aug 29 06:54:23 volumio volumio[1274]: info: Removing browser output: myVolumio user plan is not superstar Aug 29 06:54:23 volumio volumio[1274]: info: Removing audio output: Aug 29 06:54:23 volumio volumio[1274]: info: Stoppping Tunnel 1 Aug 29 06:54:23 volumio sudo[2086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 06:54:23 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:23 volumio sudo[2088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 29 06:54:23 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:23 volumio sudo[2086]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:23 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. Aug 29 06:54:23 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. Aug 29 06:54:23 volumio volumio[1274]: error: Cannot start Volumio Streaming Daemon Aug 29 06:54:23 volumio volumio[1274]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 06:54:23 volumio volumio[1274]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 06:54:23 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. Aug 29 06:54:23 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. Aug 29 06:54:23 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. Aug 29 06:54:23 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. Aug 29 06:54:23 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. Aug 29 06:54:23 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. Aug 29 06:54:23 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:23 volumio volumio[1274]: info: Remote SSH Stopped Aug 29 06:54:23 volumio volumio[1274]: info: Discovery: this is already registered, 0e7bf662-b5f6-4b3b-943f-0a5516904cfc Aug 29 06:54:23 volumio volumio[1274]: info: Discovery: Found device Volumio Aug 29 06:54:23 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:23 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:23 volumio volumio[1274]: info: Discovery: this is already registered, 0e7bf662-b5f6-4b3b-943f-0a5516904cfc Aug 29 06:54:23 volumio volumio[1274]: info: Discovery: Found device Volumio Aug 29 06:54:23 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:23 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 06:54:25 volumio volumio[1274]: info: Setting Geolocation for MyVolumio to us4 Aug 29 06:54:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:25 volumio volumio[1274]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 29 06:54:26 volumio volumio[1274]: info: Updating MyVolumio device info Aug 29 06:54:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:26 volumio volumio[1274]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 29 06:54:26 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 29 06:54:28 volumio sudo[2103]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 06:54:28 volumio sudo[2103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:28 volumio sudo[2105]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:28 volumio sudo[2105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:28 volumio sudo[2105]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:28 volumio sudo[2103]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:29 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 29 06:54:29 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 06:54:29 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 06:54:29 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:29 volumio volumio[1274]: info: Upmpdcli Daemon Started Aug 29 06:54:29 volumio volumio[1274]: verbose: New Socket.io Connection to 192.168.120.114 from 192.168.120.120 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.6.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 06:54:29 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 29 06:54:29 volumio volumio[1274]: info: MYVOLUMIO: Adding device Aug 29 06:54:29 volumio volumio[1274]: info: MYVOLUMIO: Evaluating Server Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:30 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 06:54:30 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:30 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:30 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:30 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:30 volumio volumio[1274]: info: Listing playlists Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 06:54:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 06:54:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:31 volumio volumio[1274]: info: Setting Geolocation for MyVolumio to us1 Aug 29 06:54:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:31 volumio upmpdcli[2165]: writing RSA key Aug 29 06:54:32 volumio volumio[1274]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 29 06:54:32 volumio sudo[2172]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 06:54:32 volumio sudo[2172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:32 volumio sudo[2174]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 06:54:32 volumio sudo[2174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 06:54:32 volumio sudo[2174]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:32 volumio sudo[2172]: pam_unix(sudo:session): session closed for user root Aug 29 06:54:32 volumio volumio[1274]: verbose: New Socket.io Connection to 192.168.120.114 from 192.168.120.120 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.6.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 06:54:32 volumio volumio[1274]: info: Updating MyVolumio device info Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:32 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 06:54:32 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:32 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:32 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:32 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:32 volumio volumio[1274]: info: Listing playlists Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 06:54:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 06:54:33 volumio volumio[1274]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 06:54:33 volumio volumio[1274]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 06:54:33 volumio volumio[1274]: info: Received Get System Version Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 06:54:33 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:33 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:33 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:33 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:33 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:34 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:34 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:34 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:34 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 06:54:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 06:54:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 06:54:36 volumio volumio[1274]: info: Received Get System Info Aug 29 06:54:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 06:54:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 06:54:36 volumio volumio[1274]: info: Discovery: Getting this device information Aug 29 06:54:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:36 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 06:54:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 06:54:37 volumio volumio[1274]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 29 06:54:37 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Aug 29 06:54:37 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 29 06:54:41 volumio volumio[1274]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Aug 29 06:54:41 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:41 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 06:54:41 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:41 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 06:54:41 volumio volumio-remote-updater[858]: Test mode disabled Aug 29 06:54:41 volumio volumio-remote-updater[858]: Alpha mode disabled Aug 29 06:54:41 volumio volumio-remote-updater[858]: Alpha legacy test mode disabled Aug 29 06:54:41 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 06:54:41 volumio volumio[1274]: info: CoreCommandRouter::volumioGetQueue Aug 29 06:54:41 volumio volumio[1274]: info: CoreStateMachine::getQueue Aug 29 06:54:41 volumio volumio[1274]: info: CorePlayQueue::getQueue Aug 29 06:54:41 volumio volumio[1274]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 06:54:41 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 06:54:42 volumio volumio[1274]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 29 06:54:42 volumio volumio[1274]: info: Completed starting MyVolumio Plugin Aug 29 06:54:42 volumio volumio[1274]: [Metrics] CommandRouter: 34s 125.42ms Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::volumiosetStartupVolume Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::Close All Modals sent Aug 29 06:54:42 volumio volumio[1274]: info: CoreCommandRouter::Close All Modals sent Aug 29 06:54:42 volumio volumio[1274]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 29 06:54:42 volumio volumio[1274]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for DAC Aug 29 06:54:42 volumio volumio[1274]: aplay: main:831: audio open error: No such device Aug 29 06:54:43 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 29 06:54:43 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 06:54:43 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 29 06:54:43 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 29 06:54:43 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 06:54:43 volumio volumio[1274]: info: CURURI: artists:// Aug 29 06:54:43 volumio volumio[1274]: info: Preload queue cleared Aug 29 06:54:43 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 29 06:54:49 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 29 06:54:49 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 29 06:54:49 volumio volumio[1274]: info: BOOT COMPLETED Aug 29 06:54:49 volumio volumio[1274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 06:54:49 volumio volumio[1274]: TypeError: Cannot read properties of null (reading 'slice') Aug 29 06:54:49 volumio volumio[1274]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Aug 29 06:54:49 volumio volumio[1274]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Aug 29 06:54:49 volumio volumio[1274]: at Object.onceWrapper (node:events:629:26) Aug 29 06:54:49 volumio volumio[1274]: at ClientRequest.emit (node:events:514:28) Aug 29 06:54:49 volumio volumio[1274]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Aug 29 06:54:49 volumio volumio[1274]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Aug 29 06:54:49 volumio volumio[1274]: at TLSSocket.socketOnData (node:_http_client:535:22) Aug 29 06:54:49 volumio volumio[1274]: at TLSSocket.emit (node:events:514:28) Aug 29 06:54:49 volumio volumio[1274]: at addChunk (node:internal/streams/readable:343:12) Aug 29 06:54:49 volumio volumio[1274]: at readableAddChunk (node:internal/streams/readable:316:9) Aug 29 06:54:49 volumio volumio[1274]: at Readable.push (node:internal/streams/readable:253:10) Aug 29 06:54:49 volumio volumio[1274]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Aug 29 06:54:49 volumio volumio[1274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 06:54:49 volumio sudo[2281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 06:53' Aug 29 06:54:49 volumio sudo[2281]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"