Jun 17 16:48:56 volprox kernel: Linux version 6.12.30-volumio (andrew@rig) (gcc (Ubuntu 14.2.0-4ubuntu2~24.04) 14.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Wed May 28 19:37:30 BST 2025 Jun 17 16:48:56 volprox kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=f9122924-079c-42eb-9700-f39ff249b20f bootpart=UUID=9F79-DB23 datapart=UUID=235821d1-628a-4647-a5fe-6c10402b8c33 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Jun 17 16:48:56 volprox kernel: KERNEL supported cpus: Jun 17 16:48:56 volprox kernel: Intel GenuineIntel Jun 17 16:48:56 volprox kernel: AMD AuthenticAMD Jun 17 16:48:56 volprox kernel: Hygon HygonGenuine Jun 17 16:48:56 volprox kernel: Centaur CentaurHauls Jun 17 16:48:56 volprox kernel: zhaoxin Shanghai Jun 17 16:48:56 volprox kernel: BIOS-provided physical RAM map: Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bffb9fff] usable Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x00000000bffba000-0x00000000bfffffff] reserved Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Jun 17 16:48:56 volprox kernel: BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable Jun 17 16:48:56 volprox kernel: NX (Execute Disable) protection: active Jun 17 16:48:56 volprox kernel: APIC: Static calls initialized Jun 17 16:48:56 volprox kernel: SMBIOS 2.8 present. Jun 17 16:48:56 volprox kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 Jun 17 16:48:56 volprox kernel: DMI: Memory slots populated: 1/1 Jun 17 16:48:56 volprox kernel: Hypervisor detected: KVM Jun 17 16:48:56 volprox kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Jun 17 16:48:56 volprox kernel: kvm-clock: using sched offset of 3713904591248 cycles Jun 17 16:48:56 volprox kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jun 17 16:48:56 volprox kernel: tsc: Detected 3911.998 MHz processor Jun 17 16:48:56 volprox kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 17 16:48:56 volprox kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 17 16:48:56 volprox kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000 Jun 17 16:48:56 volprox kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Jun 17 16:48:56 volprox kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 17 16:48:56 volprox kernel: last_pfn = 0xbffba max_arch_pfn = 0x400000000 Jun 17 16:48:56 volprox kernel: found SMP MP-table at [mem 0x000f5440-0x000f544f] Jun 17 16:48:56 volprox kernel: RAMDISK: [mem 0x7b351000-0x7fffffff] Jun 17 16:48:56 volprox kernel: ACPI: Early table checksum verification disabled Jun 17 16:48:56 volprox kernel: ACPI: RSDP 0x00000000000F5240 000014 (v00 BOCHS ) Jun 17 16:48:56 volprox kernel: ACPI: RSDT 0x00000000BFFE3BEE 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 17 16:48:56 volprox kernel: ACPI: FACP 0x00000000BFFE39D0 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 17 16:48:56 volprox kernel: ACPI: DSDT 0x00000000BFFDF040 004990 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 17 16:48:56 volprox kernel: ACPI: FACS 0x00000000BFFDF000 000040 Jun 17 16:48:56 volprox kernel: ACPI: APIC 0x00000000BFFE3A44 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jun 17 16:48:56 volprox kernel: ACPI: SSDT 0x00000000BFFE3AC4 0000CA (v01 BOCHS VMGENID 00000001 BXPC 00000001) Jun 17 16:48:56 volprox kernel: ACPI: HPET 0x00000000BFFE3B8E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 17 16:48:56 volprox kernel: ACPI: WAET 0x00000000BFFE3BC6 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 17 16:48:56 volprox kernel: ACPI: Reserving FACP table memory at [mem 0xbffe39d0-0xbffe3a43] Jun 17 16:48:56 volprox kernel: ACPI: Reserving DSDT table memory at [mem 0xbffdf040-0xbffe39cf] Jun 17 16:48:56 volprox kernel: ACPI: Reserving FACS table memory at [mem 0xbffdf000-0xbffdf03f] Jun 17 16:48:56 volprox kernel: ACPI: Reserving APIC table memory at [mem 0xbffe3a44-0xbffe3ac3] Jun 17 16:48:56 volprox kernel: ACPI: Reserving SSDT table memory at [mem 0xbffe3ac4-0xbffe3b8d] Jun 17 16:48:56 volprox kernel: ACPI: Reserving HPET table memory at [mem 0xbffe3b8e-0xbffe3bc5] Jun 17 16:48:56 volprox kernel: ACPI: Reserving WAET table memory at [mem 0xbffe3bc6-0xbffe3bed] Jun 17 16:48:56 volprox kernel: Zone ranges: Jun 17 16:48:56 volprox kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 17 16:48:56 volprox kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jun 17 16:48:56 volprox kernel: Normal [mem 0x0000000100000000-0x000000013fffffff] Jun 17 16:48:56 volprox kernel: Movable zone start for each node Jun 17 16:48:56 volprox kernel: Early memory node ranges Jun 17 16:48:56 volprox kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Jun 17 16:48:56 volprox kernel: node 0: [mem 0x0000000000100000-0x00000000bffb9fff] Jun 17 16:48:56 volprox kernel: node 0: [mem 0x0000000100000000-0x000000013fffffff] Jun 17 16:48:56 volprox kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] Jun 17 16:48:56 volprox kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 17 16:48:56 volprox kernel: On node 0, zone DMA: 97 pages in unavailable ranges Jun 17 16:48:56 volprox kernel: On node 0, zone Normal: 70 pages in unavailable ranges Jun 17 16:48:56 volprox kernel: ACPI: PM-Timer IO Port: 0x608 Jun 17 16:48:56 volprox kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Jun 17 16:48:56 volprox kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Jun 17 16:48:56 volprox kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jun 17 16:48:56 volprox kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Jun 17 16:48:56 volprox kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 17 16:48:56 volprox kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Jun 17 16:48:56 volprox kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Jun 17 16:48:56 volprox kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 17 16:48:56 volprox kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Jun 17 16:48:56 volprox kernel: CPU topo: Max. logical packages: 1 Jun 17 16:48:56 volprox kernel: CPU topo: Max. logical dies: 1 Jun 17 16:48:56 volprox kernel: CPU topo: Max. dies per package: 1 Jun 17 16:48:56 volprox kernel: CPU topo: Max. threads per core: 1 Jun 17 16:48:56 volprox kernel: CPU topo: Num. cores per package: 2 Jun 17 16:48:56 volprox kernel: CPU topo: Num. threads per package: 2 Jun 17 16:48:56 volprox kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Jun 17 16:48:56 volprox kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Jun 17 16:48:56 volprox kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Jun 17 16:48:56 volprox kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] Jun 17 16:48:56 volprox kernel: PM: hibernation: Registered nosave memory: [mem 0xbffba000-0xffffffff] Jun 17 16:48:56 volprox kernel: [mem 0xc0000000-0xfeffbfff] available for PCI devices Jun 17 16:48:56 volprox kernel: Booting paravirtualized kernel on KVM Jun 17 16:48:56 volprox kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Jun 17 16:48:56 volprox kernel: setup_percpu: NR_CPUS:64 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Jun 17 16:48:56 volprox kernel: percpu: Embedded 59 pages/cpu s201432 r8192 d32040 u1048576 Jun 17 16:48:56 volprox kernel: pcpu-alloc: s201432 r8192 d32040 u1048576 alloc=1*2097152 Jun 17 16:48:56 volprox kernel: pcpu-alloc: [0] 0 1 Jun 17 16:48:56 volprox kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=f9122924-079c-42eb-9700-f39ff249b20f bootpart=UUID=9F79-DB23 datapart=UUID=235821d1-628a-4647-a5fe-6c10402b8c33 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Jun 17 16:48:56 volprox kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=f9122924-079c-42eb-9700-f39ff249b20f bootpart=UUID=9F79-DB23 datapart=UUID=235821d1-628a-4647-a5fe-6c10402b8c33 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Jun 17 16:48:56 volprox kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 17 16:48:56 volprox kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 17 16:48:56 volprox kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048408 Jun 17 16:48:56 volprox kernel: mem auto-init: stack:all(pattern), heap alloc:off, heap free:off Jun 17 16:48:56 volprox kernel: software IO TLB: area num 2. Jun 17 16:48:56 volprox kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jun 17 16:48:56 volprox kernel: Kernel/User page tables isolation: enabled Jun 17 16:48:56 volprox kernel: ftrace: allocating 43661 entries in 171 pages Jun 17 16:48:56 volprox kernel: ftrace: allocated 171 pages with 5 groups Jun 17 16:48:56 volprox kernel: Dynamic Preempt: voluntary Jun 17 16:48:56 volprox kernel: rcu: Preemptible hierarchical RCU implementation. Jun 17 16:48:56 volprox kernel: rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2. Jun 17 16:48:56 volprox kernel: Trampoline variant of Tasks RCU enabled. Jun 17 16:48:56 volprox kernel: Rude variant of Tasks RCU enabled. Jun 17 16:48:56 volprox kernel: Tracing variant of Tasks RCU enabled. Jun 17 16:48:56 volprox kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Jun 17 16:48:56 volprox kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jun 17 16:48:56 volprox kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jun 17 16:48:56 volprox kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jun 17 16:48:56 volprox kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jun 17 16:48:56 volprox kernel: NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16 Jun 17 16:48:56 volprox kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 17 16:48:56 volprox kernel: Console: colour VGA+ 80x25 Jun 17 16:48:56 volprox kernel: printk: legacy console [tty0] enabled Jun 17 16:48:56 volprox kernel: ACPI: Core revision 20240827 Jun 17 16:48:56 volprox kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Jun 17 16:48:56 volprox kernel: APIC: Switch to symmetric I/O mode setup Jun 17 16:48:56 volprox kernel: x2apic enabled Jun 17 16:48:56 volprox kernel: APIC: Switched APIC routing to: physical x2apic Jun 17 16:48:56 volprox kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Jun 17 16:48:56 volprox kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x70c7414a6ec, max_idle_ns: 881590470838 ns Jun 17 16:48:56 volprox kernel: Calibrating delay loop (skipped) preset value.. 7823.99 BogoMIPS (lpj=15647992) Jun 17 16:48:56 volprox kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Jun 17 16:48:56 volprox kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Jun 17 16:48:56 volprox kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 17 16:48:56 volprox kernel: Spectre V2 : Mitigation: Retpolines Jun 17 16:48:56 volprox kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 17 16:48:56 volprox kernel: Speculative Store Bypass: Vulnerable Jun 17 16:48:56 volprox kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Jun 17 16:48:56 volprox kernel: MMIO Stale Data: Unknown: No mitigations Jun 17 16:48:56 volprox kernel: ITS: Mitigation: Aligned branch/return thunks Jun 17 16:48:56 volprox kernel: x86/fpu: x87 FPU will use FXSAVE Jun 17 16:48:56 volprox kernel: Freeing SMP alternatives memory: 36K Jun 17 16:48:56 volprox kernel: pid_max: default: 32768 minimum: 301 Jun 17 16:48:56 volprox kernel: LSM: initializing lsm=capability,yama Jun 17 16:48:56 volprox kernel: Yama: becoming mindful. Jun 17 16:48:56 volprox kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 17 16:48:56 volprox kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 17 16:48:56 volprox kernel: smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0xf, model: 0x6b, stepping: 0x1) Jun 17 16:48:56 volprox kernel: Performance Events: unsupported Netburst CPU model 107 no PMU driver, software events only. Jun 17 16:48:56 volprox kernel: signal: max sigframe size: 1440 Jun 17 16:48:56 volprox kernel: rcu: Hierarchical SRCU implementation. Jun 17 16:48:56 volprox kernel: rcu: Max phase no-delay instances is 1000. Jun 17 16:48:56 volprox kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jun 17 16:48:56 volprox kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jun 17 16:48:56 volprox kernel: smp: Bringing up secondary CPUs ... Jun 17 16:48:56 volprox kernel: smpboot: x86: Booting SMP configuration: Jun 17 16:48:56 volprox kernel: .... node #0, CPUs: #1 Jun 17 16:48:56 volprox kernel: smp: Brought up 1 node, 2 CPUs Jun 17 16:48:56 volprox kernel: smpboot: Total of 2 processors activated (15647.99 BogoMIPS) Jun 17 16:48:56 volprox kernel: Memory: 3942060K/4193632K available (16384K kernel code, 2020K rwdata, 5352K rodata, 3048K init, 928K bss, 246348K reserved, 0K cma-reserved) Jun 17 16:48:56 volprox kernel: devtmpfs: initialized Jun 17 16:48:56 volprox kernel: x86/mm: Memory block size: 128MB Jun 17 16:48:56 volprox kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jun 17 16:48:56 volprox kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jun 17 16:48:56 volprox kernel: pinctrl core: initialized pinctrl subsystem Jun 17 16:48:56 volprox kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 17 16:48:56 volprox kernel: audit: initializing netlink subsys (disabled) Jun 17 16:48:56 volprox kernel: audit: type=2000 audit(1750146529.735:1): state=initialized audit_enabled=0 res=1 Jun 17 16:48:56 volprox kernel: thermal_sys: Registered thermal governor 'fair_share' Jun 17 16:48:56 volprox kernel: thermal_sys: Registered thermal governor 'bang_bang' Jun 17 16:48:56 volprox kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 17 16:48:56 volprox kernel: thermal_sys: Registered thermal governor 'user_space' Jun 17 16:48:56 volprox kernel: cpuidle: using governor ladder Jun 17 16:48:56 volprox kernel: cpuidle: using governor menu Jun 17 16:48:56 volprox kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 17 16:48:56 volprox kernel: PCI: Using configuration type 1 for base access Jun 17 16:48:56 volprox kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 17 16:48:56 volprox kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 17 16:48:56 volprox kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 17 16:48:56 volprox kernel: ACPI: Added _OSI(Module Device) Jun 17 16:48:56 volprox kernel: ACPI: Added _OSI(Processor Device) Jun 17 16:48:56 volprox kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jun 17 16:48:56 volprox kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 17 16:48:56 volprox kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded Jun 17 16:48:56 volprox kernel: ACPI: Interpreter enabled Jun 17 16:48:56 volprox kernel: ACPI: PM: (supports S0 S5) Jun 17 16:48:56 volprox kernel: ACPI: Using IOAPIC for interrupt routing Jun 17 16:48:56 volprox kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 17 16:48:56 volprox kernel: PCI: Using E820 reservations for host bridge windows Jun 17 16:48:56 volprox kernel: ACPI: Enabled 3 GPEs in block 00 to 0F Jun 17 16:48:56 volprox kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jun 17 16:48:56 volprox kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Jun 17 16:48:56 volprox kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Jun 17 16:48:56 volprox kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Jun 17 16:48:56 volprox kernel: acpiphp: Slot [3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [7] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [16] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [17] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [18] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [6] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [8] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [9] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [10] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [11] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [12] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [13] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [14] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [15] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [19] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [20] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [21] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [22] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [23] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [24] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [25] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [26] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [27] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [28] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [29] registered Jun 17 16:48:56 volprox kernel: PCI host bridge to bus 0000:00 Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: root bus resource [mem 0x140000000-0x1bfffffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jun 17 16:48:56 volprox kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:01.1: BAR 4 [io 0xf0a0-0xf0af] Jun 17 16:48:56 volprox kernel: pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk Jun 17 16:48:56 volprox kernel: pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk Jun 17 16:48:56 volprox kernel: pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk Jun 17 16:48:56 volprox kernel: pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk Jun 17 16:48:56 volprox kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:01.2: BAR 4 [io 0xf040-0xf05f] Jun 17 16:48:56 volprox kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Jun 17 16:48:56 volprox kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: BAR 0 [mem 0xfc000000-0xfcffffff pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: BAR 2 [mem 0xfeab8000-0xfeab8fff] Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: ROM [mem 0xfea80000-0xfea8ffff pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:03.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:03.0: BAR 0 [io 0xf000-0xf03f] Jun 17 16:48:56 volprox kernel: pci 0000:00:03.0: BAR 4 [mem 0xfd600000-0xfd603fff 64bit pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: BAR 0 [mem 0xfeab9000-0xfeab90ff 64bit] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: bridge window [mem 0xfd400000-0xfd5fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:07.0: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:07.0: BAR 4 [io 0xf060-0xf07f] Jun 17 16:48:56 volprox kernel: pci 0000:00:07.0: BAR 5 [mem 0xfeaba000-0xfeabafff] Jun 17 16:48:56 volprox kernel: pci 0000:00:10.0: [8086:a2af] type 00 class 0x0c0330 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:10.0: BAR 0 [mem 0xfea90000-0xfea9ffff 64bit] Jun 17 16:48:56 volprox kernel: pci 0000:00:11.0: [8086:a2f0] type 00 class 0x040300 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:11.0: BAR 0 [mem 0xfeab0000-0xfeab3fff 64bit] Jun 17 16:48:56 volprox kernel: pci 0000:00:11.0: BAR 4 [mem 0xfeaa0000-0xfeaaffff 64bit] Jun 17 16:48:56 volprox kernel: pci 0000:00:12.0: [8086:10d3] type 00 class 0x020000 PCIe Endpoint Jun 17 16:48:56 volprox kernel: pci 0000:00:12.0: BAR 0 [mem 0xfea40000-0xfea5ffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:12.0: BAR 1 [mem 0xfea60000-0xfea7ffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:12.0: BAR 2 [io 0xf080-0xf09f] Jun 17 16:48:56 volprox kernel: pci 0000:00:12.0: BAR 3 [mem 0xfeab4000-0xfeab7fff] Jun 17 16:48:56 volprox kernel: pci 0000:00:12.0: ROM [mem 0xfea00000-0xfea3ffff pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:12.0: enabling Extended Tags Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: BAR 0 [mem 0xfeabb000-0xfeabb0ff 64bit] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: bridge window [mem 0xfd200000-0xfd3fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: BAR 0 [mem 0xfeabc000-0xfeabc0ff 64bit] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci_bus 0000:01: extended config space not accessible Jun 17 16:48:56 volprox kernel: acpiphp: Slot [0] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [1-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [3-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [4-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [5] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [6-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [7-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [8-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [9-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [10-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [11-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [12-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [13-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [14-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [15-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [16-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [17-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [18-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [19-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [20-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [21-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [22-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [23-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [24-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [25-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [26-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [27-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [28-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [29-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [30] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [31] registered Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Jun 17 16:48:56 volprox kernel: pci_bus 0000:02: extended config space not accessible Jun 17 16:48:56 volprox kernel: acpiphp: Slot [27-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [0-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [1-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [2-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [3-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [4-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [5-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [6-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [7-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [8-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [9-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [10-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [11-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [12-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [13-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [14-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [15-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [16-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [17-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [18-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [19-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [20-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [21-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [22-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [23-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [24-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [25-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [26-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [28-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [29-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [30-2] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [31-2] registered Jun 17 16:48:56 volprox kernel: pci 0000:02:1b.0: [1b36:000d] type 00 class 0x0c0330 conventional PCI endpoint Jun 17 16:48:56 volprox kernel: pci 0000:02:1b.0: BAR 0 [mem 0xfe600000-0xfe603fff 64bit] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Jun 17 16:48:56 volprox kernel: pci_bus 0000:03: extended config space not accessible Jun 17 16:48:56 volprox kernel: acpiphp: Slot [0-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [1-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [2-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [3-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [4-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [5-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [6-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [7-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [8-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [9-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [10-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [11-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [12-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [13-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [14-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [15-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [16-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [17-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [18-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [19-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [20-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [21-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [22-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [23-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [24-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [25-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [26-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [27-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [28-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [29-4] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [30-3] registered Jun 17 16:48:56 volprox kernel: acpiphp: Slot [31-3] registered Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: on NUMA node 0 Jun 17 16:48:56 volprox kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Jun 17 16:48:56 volprox kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Jun 17 16:48:56 volprox kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Jun 17 16:48:56 volprox kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Jun 17 16:48:56 volprox kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Jun 17 16:48:56 volprox kernel: iommu: Default domain type: Translated Jun 17 16:48:56 volprox kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 17 16:48:56 volprox kernel: SCSI subsystem initialized Jun 17 16:48:56 volprox kernel: libata version 3.00 loaded. Jun 17 16:48:56 volprox kernel: ACPI: bus type USB registered Jun 17 16:48:56 volprox kernel: usbcore: registered new interface driver usbfs Jun 17 16:48:56 volprox kernel: usbcore: registered new interface driver hub Jun 17 16:48:56 volprox kernel: usbcore: registered new device driver usb Jun 17 16:48:56 volprox kernel: PCI: Using ACPI for IRQ routing Jun 17 16:48:56 volprox kernel: PCI: pci_cache_line_size set to 64 bytes Jun 17 16:48:56 volprox kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Jun 17 16:48:56 volprox kernel: e820: reserve RAM buffer [mem 0xbffba000-0xbfffffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: vgaarb: bridge control possible Jun 17 16:48:56 volprox kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jun 17 16:48:56 volprox kernel: vgaarb: loaded Jun 17 16:48:56 volprox kernel: hpet: 3 channels of 0 reserved for per-cpu timers Jun 17 16:48:56 volprox kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Jun 17 16:48:56 volprox kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Jun 17 16:48:56 volprox kernel: clocksource: Switched to clocksource kvm-clock Jun 17 16:48:56 volprox kernel: VFS: Disk quotas dquot_6.6.0 Jun 17 16:48:56 volprox kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 17 16:48:56 volprox kernel: pnp: PnP ACPI init Jun 17 16:48:56 volprox kernel: pnp 00:02: [dma 2] Jun 17 16:48:56 volprox kernel: pnp: PnP ACPI: found 4 devices Jun 17 16:48:56 volprox kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 17 16:48:56 volprox kernel: NET: Registered PF_INET protocol family Jun 17 16:48:56 volprox kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jun 17 16:48:56 volprox kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jun 17 16:48:56 volprox kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 17 16:48:56 volprox kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jun 17 16:48:56 volprox kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jun 17 16:48:56 volprox kernel: TCP: Hash tables configured (established 32768 bind 32768) Jun 17 16:48:56 volprox kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 17 16:48:56 volprox kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 17 16:48:56 volprox kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:05.0: bridge window [mem 0xfd400000-0xfd5fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1e.0: bridge window [mem 0xfd200000-0xfd3fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff] Jun 17 16:48:56 volprox kernel: pci 0000:00:1f.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:00: resource 8 [mem 0x140000000-0x1bfffffff window] Jun 17 16:48:56 volprox kernel: pci_bus 0000:01: resource 0 [io 0xe000-0xefff] Jun 17 16:48:56 volprox kernel: pci_bus 0000:01: resource 1 [mem 0xfe800000-0xfe9fffff] Jun 17 16:48:56 volprox kernel: pci_bus 0000:01: resource 2 [mem 0xfd400000-0xfd5fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci_bus 0000:02: resource 0 [io 0xd000-0xdfff] Jun 17 16:48:56 volprox kernel: pci_bus 0000:02: resource 1 [mem 0xfe600000-0xfe7fffff] Jun 17 16:48:56 volprox kernel: pci_bus 0000:02: resource 2 [mem 0xfd200000-0xfd3fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci_bus 0000:03: resource 0 [io 0xc000-0xcfff] Jun 17 16:48:56 volprox kernel: pci_bus 0000:03: resource 1 [mem 0xfe400000-0xfe5fffff] Jun 17 16:48:56 volprox kernel: pci_bus 0000:03: resource 2 [mem 0xfd000000-0xfd1fffff 64bit pref] Jun 17 16:48:56 volprox kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Jun 17 16:48:56 volprox kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Jun 17 16:48:56 volprox kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Jun 17 16:48:56 volprox kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Jun 17 16:48:56 volprox kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Jun 17 16:48:56 volprox kernel: PCI: CLS 64 bytes, default 64 Jun 17 16:48:56 volprox kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jun 17 16:48:56 volprox kernel: software IO TLB: mapped [mem 0x00000000bbfba000-0x00000000bffba000] (64MB) Jun 17 16:48:56 volprox kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x70c7414a6ec, max_idle_ns: 881590470838 ns Jun 17 16:48:56 volprox kernel: Trying to unpack rootfs image as initramfs... Jun 17 16:48:56 volprox kernel: Initialise system trusted keyrings Jun 17 16:48:56 volprox kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Jun 17 16:48:56 volprox kernel: Key type asymmetric registered Jun 17 16:48:56 volprox kernel: Asymmetric key parser 'x509' registered Jun 17 16:48:56 volprox kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jun 17 16:48:56 volprox kernel: io scheduler mq-deadline registered Jun 17 16:48:56 volprox kernel: io scheduler kyber registered Jun 17 16:48:56 volprox kernel: ledtrig-cpu: registered to indicate activity on CPUs Jun 17 16:48:56 volprox kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.S28_) Jun 17 16:48:56 volprox kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Jun 17 16:48:56 volprox kernel: shpchp 0000:00:05.0: Cannot get control of SHPC hotplug Jun 17 16:48:56 volprox kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF0_) Jun 17 16:48:56 volprox kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Jun 17 16:48:56 volprox kernel: shpchp 0000:00:1e.0: Cannot get control of SHPC hotplug Jun 17 16:48:56 volprox kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF8_) Jun 17 16:48:56 volprox kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Jun 17 16:48:56 volprox kernel: shpchp 0000:00:1f.0: Cannot get control of SHPC hotplug Jun 17 16:48:56 volprox kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Jun 17 16:48:56 volprox kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Jun 17 16:48:56 volprox kernel: ACPI: button: Power Button [PWRF] Jun 17 16:48:56 volprox kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Jun 17 16:48:56 volprox kernel: Linux agpgart interface v0.103 Jun 17 16:48:56 volprox kernel: ata_piix 0000:00:01.1: version 2.13 Jun 17 16:48:56 volprox kernel: scsi host0: ata_piix Jun 17 16:48:56 volprox kernel: scsi host1: ata_piix Jun 17 16:48:56 volprox kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xf0a0 irq 14 lpm-pol 0 Jun 17 16:48:56 volprox kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xf0a8 irq 15 lpm-pol 0 Jun 17 16:48:56 volprox kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Jun 17 16:48:56 volprox kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Jun 17 16:48:56 volprox kernel: uhci_hcd 0000:00:01.2: detected 2 ports Jun 17 16:48:56 volprox kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000f040 Jun 17 16:48:56 volprox kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12 Jun 17 16:48:56 volprox kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 17 16:48:56 volprox kernel: usb usb1: Product: UHCI Host Controller Jun 17 16:48:56 volprox kernel: usb usb1: Manufacturer: Linux 6.12.30-volumio uhci_hcd Jun 17 16:48:56 volprox kernel: usb usb1: SerialNumber: 0000:00:01.2 Jun 17 16:48:56 volprox kernel: hub 1-0:1.0: USB hub found Jun 17 16:48:56 volprox kernel: hub 1-0:1.0: 2 ports detected Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:00:10.0: xHCI Host Controller Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 2 Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:00:10.0: hcc params 0x200077c1 hci version 0x100 quirks 0x0000000000009810 Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:00:10.0: xHCI Host Controller Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 3 Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:00:10.0: Host supports USB 3.0 SuperSpeed Jun 17 16:48:56 volprox kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jun 17 16:48:56 volprox kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 17 16:48:56 volprox kernel: usb usb2: Product: xHCI Host Controller Jun 17 16:48:56 volprox kernel: usb usb2: Manufacturer: Linux 6.12.30-volumio xhci-hcd Jun 17 16:48:56 volprox kernel: usb usb2: SerialNumber: 0000:00:10.0 Jun 17 16:48:56 volprox kernel: hub 2-0:1.0: USB hub found Jun 17 16:48:56 volprox kernel: hub 2-0:1.0: 12 ports detected Jun 17 16:48:56 volprox kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jun 17 16:48:56 volprox kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 17 16:48:56 volprox kernel: usb usb3: Product: xHCI Host Controller Jun 17 16:48:56 volprox kernel: usb usb3: Manufacturer: Linux 6.12.30-volumio xhci-hcd Jun 17 16:48:56 volprox kernel: usb usb3: SerialNumber: 0000:00:10.0 Jun 17 16:48:56 volprox kernel: hub 3-0:1.0: USB hub found Jun 17 16:48:56 volprox kernel: hub 3-0:1.0: 6 ports detected Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:02:1b.0: xHCI Host Controller Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:02:1b.0: new USB bus registered, assigned bus number 4 Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:02:1b.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:02:1b.0: xHCI Host Controller Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:02:1b.0: new USB bus registered, assigned bus number 5 Jun 17 16:48:56 volprox kernel: xhci_hcd 0000:02:1b.0: Host supports USB 3.0 SuperSpeed Jun 17 16:48:56 volprox kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jun 17 16:48:56 volprox kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 17 16:48:56 volprox kernel: usb usb4: Product: xHCI Host Controller Jun 17 16:48:56 volprox kernel: usb usb4: Manufacturer: Linux 6.12.30-volumio xhci-hcd Jun 17 16:48:56 volprox kernel: usb usb4: SerialNumber: 0000:02:1b.0 Jun 17 16:48:56 volprox kernel: hub 4-0:1.0: USB hub found Jun 17 16:48:56 volprox kernel: hub 4-0:1.0: 15 ports detected Jun 17 16:48:56 volprox kernel: usb usb5: We don't know the algorithms for LPM for this host, disabling LPM. Jun 17 16:48:56 volprox kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jun 17 16:48:56 volprox kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 17 16:48:56 volprox kernel: usb usb5: Product: xHCI Host Controller Jun 17 16:48:56 volprox kernel: usb usb5: Manufacturer: Linux 6.12.30-volumio xhci-hcd Jun 17 16:48:56 volprox kernel: usb usb5: SerialNumber: 0000:02:1b.0 Jun 17 16:48:56 volprox kernel: hub 5-0:1.0: USB hub found Jun 17 16:48:56 volprox kernel: hub 5-0:1.0: 15 ports detected Jun 17 16:48:56 volprox kernel: usbcore: registered new interface driver usb-storage Jun 17 16:48:56 volprox kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Jun 17 16:48:56 volprox kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Jun 17 16:48:56 volprox kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Jun 17 16:48:56 volprox kernel: mousedev: PS/2 mouse device common for all mice Jun 17 16:48:56 volprox kernel: rtc_cmos 00:03: RTC can wake from S4 Jun 17 16:48:56 volprox kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 Jun 17 16:48:56 volprox kernel: rtc_cmos 00:03: registered as rtc0 Jun 17 16:48:56 volprox kernel: rtc_cmos 00:03: setting system clock to 2025-06-17T07:48:50 UTC (1750146530) Jun 17 16:48:56 volprox kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Jun 17 16:48:56 volprox kernel: i2c_dev: i2c /dev entries driver Jun 17 16:48:56 volprox kernel: intel_pstate: CPU model not supported Jun 17 16:48:56 volprox kernel: hid: raw HID events driver (C) Jiri Kosina Jun 17 16:48:56 volprox kernel: NET: Registered PF_INET6 protocol family Jun 17 16:48:56 volprox kernel: Segment Routing with IPv6 Jun 17 16:48:56 volprox kernel: In-situ OAM (IOAM) with IPv6 Jun 17 16:48:56 volprox kernel: mip6: Mobile IPv6 Jun 17 16:48:56 volprox kernel: NET: Registered PF_PACKET protocol family Jun 17 16:48:56 volprox kernel: mpls_gso: MPLS GSO support Jun 17 16:48:56 volprox kernel: IPI shorthand broadcast: enabled Jun 17 16:48:56 volprox kernel: sched_clock: Marking stable (413860730, 6493479)->(423414790, -3060581) Jun 17 16:48:56 volprox kernel: Loading compiled-in X.509 certificates Jun 17 16:48:56 volprox kernel: clk: Disabling unused clocks Jun 17 16:48:56 volprox kernel: PM: genpd: Disabling unused power domains Jun 17 16:48:56 volprox kernel: ata2: found unknown device (class 0) Jun 17 16:48:56 volprox kernel: ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Jun 17 16:48:56 volprox kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Jun 17 16:48:56 volprox kernel: usb 1-1: new full-speed USB device number 2 using uhci_hcd Jun 17 16:48:56 volprox kernel: usb 2-8: new high-speed USB device number 2 using xhci_hcd Jun 17 16:48:56 volprox kernel: usb 1-1: not running at top speed; connect to a high speed hub Jun 17 16:48:56 volprox kernel: usb 2-8: New USB device found, idVendor=262a, idProduct=0001, bcdDevice= 0.02 Jun 17 16:48:56 volprox kernel: usb 2-8: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jun 17 16:48:56 volprox kernel: usb 2-8: Product: USB HIFI Audio Jun 17 16:48:56 volprox kernel: usb 2-8: Manufacturer: Pawpaw Jun 17 16:48:56 volprox kernel: usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 Jun 17 16:48:56 volprox kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 Jun 17 16:48:56 volprox kernel: usb 1-1: Product: QEMU USB Tablet Jun 17 16:48:56 volprox kernel: usb 1-1: Manufacturer: QEMU Jun 17 16:48:56 volprox kernel: usb 1-1: SerialNumber: 28754-0000:00:01.2-1 Jun 17 16:48:56 volprox kernel: Freeing initrd memory: 78524K Jun 17 16:48:56 volprox kernel: Freeing unused kernel image (initmem) memory: 3048K Jun 17 16:48:56 volprox kernel: Write protecting the kernel read-only data: 22528k Jun 17 16:48:56 volprox kernel: Freeing unused kernel image (rodata/data gap) memory: 792K Jun 17 16:48:56 volprox kernel: Run /init as init process Jun 17 16:48:56 volprox kernel: with arguments: Jun 17 16:48:56 volprox kernel: /init Jun 17 16:48:56 volprox kernel: splash Jun 17 16:48:56 volprox kernel: with environment: Jun 17 16:48:56 volprox kernel: HOME=/ Jun 17 16:48:56 volprox kernel: TERM=linux Jun 17 16:48:56 volprox kernel: BOOT_IMAGE=vmlinuz Jun 17 16:48:56 volprox kernel: biosdevname=0 Jun 17 16:48:56 volprox kernel: imgpart=UUID=f9122924-079c-42eb-9700-f39ff249b20f Jun 17 16:48:56 volprox kernel: bootpart=UUID=9F79-DB23 Jun 17 16:48:56 volprox kernel: datapart=UUID=235821d1-628a-4647-a5fe-6c10402b8c33 Jun 17 16:48:56 volprox kernel: hwdevice=x86 Jun 17 16:48:56 volprox kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Jun 17 16:48:56 volprox kernel: imgfile=/volumio_current.sqsh Jun 17 16:48:56 volprox kernel: use_kmsg=no Jun 17 16:48:56 volprox kernel: ACPI: bus type drm_connector registered Jun 17 16:48:56 volprox kernel: e1000e: Intel(R) PRO/1000 Network Driver Jun 17 16:48:56 volprox kernel: e1000e: Copyright(c) 1999 - 2015 Intel Corporation. Jun 17 16:48:56 volprox kernel: ahci 0000:00:07.0: version 3.0 Jun 17 16:48:56 volprox kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Jun 17 16:48:56 volprox kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Jun 17 16:48:56 volprox kernel: e1000e 0000:00:12.0: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode Jun 17 16:48:56 volprox kernel: ahci 0000:00:07.0: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Jun 17 16:48:56 volprox kernel: ahci 0000:00:07.0: 6/6 ports implemented (port mask 0x3f) Jun 17 16:48:56 volprox kernel: ahci 0000:00:07.0: flags: 64bit ncq only Jun 17 16:48:56 volprox kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 Jun 17 16:48:56 volprox kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Jun 17 16:48:56 volprox kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 Jun 17 16:48:56 volprox kernel: FDC 0 is a S82078B Jun 17 16:48:56 volprox kernel: usbcore: registered new interface driver usbhid Jun 17 16:48:56 volprox kernel: usbhid: USB HID core driver Jun 17 16:48:56 volprox kernel: bochs-drm 0000:00:02.0: vgaarb: deactivate vga console Jun 17 16:48:56 volprox kernel: scsi host2: ahci Jun 17 16:48:56 volprox kernel: Console: switching to colour dummy device 80x25 Jun 17 16:48:56 volprox kernel: [drm] Found bochs VGA, ID 0xb0c5. Jun 17 16:48:56 volprox kernel: [drm] Framebuffer size 16384 kB @ 0xfc000000, mmio @ 0xfeab8000. Jun 17 16:48:56 volprox kernel: scsi host3: ahci Jun 17 16:48:56 volprox kernel: scsi host4: ahci Jun 17 16:48:56 volprox kernel: scsi host5: ahci Jun 17 16:48:56 volprox kernel: [drm] Found EDID data blob. Jun 17 16:48:56 volprox kernel: scsi host6: ahci Jun 17 16:48:56 volprox kernel: scsi host7: ahci Jun 17 16:48:56 volprox kernel: ata3: SATA max UDMA/133 abar m4096@0xfeaba000 port 0xfeaba100 irq 31 lpm-pol 0 Jun 17 16:48:56 volprox kernel: ata4: SATA max UDMA/133 abar m4096@0xfeaba000 port 0xfeaba180 irq 31 lpm-pol 0 Jun 17 16:48:56 volprox kernel: ata5: SATA max UDMA/133 abar m4096@0xfeaba000 port 0xfeaba200 irq 31 lpm-pol 0 Jun 17 16:48:56 volprox kernel: ata6: SATA max UDMA/133 abar m4096@0xfeaba000 port 0xfeaba280 irq 31 lpm-pol 0 Jun 17 16:48:56 volprox kernel: ata7: SATA max UDMA/133 abar m4096@0xfeaba000 port 0xfeaba300 irq 31 lpm-pol 0 Jun 17 16:48:56 volprox kernel: ata8: SATA max UDMA/133 abar m4096@0xfeaba000 port 0xfeaba380 irq 31 lpm-pol 0 Jun 17 16:48:56 volprox kernel: [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0 Jun 17 16:48:56 volprox kernel: fbcon: bochs-drmdrmfb (fb0) is primary device Jun 17 16:48:56 volprox kernel: Console: switching to colour frame buffer device 160x50 Jun 17 16:48:56 volprox kernel: bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device Jun 17 16:48:56 volprox kernel: hid-generic 0003:262A:0001.0001: No inputs registered, leaving Jun 17 16:48:56 volprox kernel: hid-generic 0003:262A:0001.0001: hidraw0: USB HID v1.00 Device [Pawpaw USB HIFI Audio] on usb-0000:00:10.0-8/input0 Jun 17 16:48:56 volprox kernel: input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0002/input/input6 Jun 17 16:48:56 volprox kernel: hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0 Jun 17 16:48:56 volprox kernel: sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Jun 17 16:48:56 volprox kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jun 17 16:48:56 volprox kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0 Jun 17 16:48:56 volprox kernel: e1000e 0000:00:12.0 eth0: (PCI Express:2.5GT/s:Width x1) bc:24:11:d8:93:66 Jun 17 16:48:56 volprox kernel: e1000e 0000:00:12.0 eth0: Intel(R) PRO/1000 Network Connection Jun 17 16:48:56 volprox kernel: e1000e 0000:00:12.0 eth0: MAC: 3, PHY: 8, PBA No: 000000-000 Jun 17 16:48:56 volprox kernel: ata8: SATA link down (SStatus 0 SControl 300) Jun 17 16:48:56 volprox kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Jun 17 16:48:56 volprox kernel: ata7: SATA link down (SStatus 0 SControl 300) Jun 17 16:48:56 volprox kernel: ata3.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 Jun 17 16:48:56 volprox kernel: ata3.00: 28778496 sectors, multi 16: LBA48 NCQ (depth 32) Jun 17 16:48:56 volprox kernel: ata3.00: applying bridge limits Jun 17 16:48:56 volprox kernel: ata3.00: configured for UDMA/100 Jun 17 16:48:56 volprox kernel: ata5: SATA link down (SStatus 0 SControl 300) Jun 17 16:48:56 volprox kernel: ata6: SATA link down (SStatus 0 SControl 300) Jun 17 16:48:56 volprox kernel: scsi 2:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Jun 17 16:48:56 volprox kernel: ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Jun 17 16:48:56 volprox kernel: ata4.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 Jun 17 16:48:56 volprox kernel: ata4.00: 23011328 sectors, multi 16: LBA48 NCQ (depth 32) Jun 17 16:48:56 volprox kernel: ata4.00: applying bridge limits Jun 17 16:48:56 volprox kernel: ata4.00: configured for UDMA/100 Jun 17 16:48:56 volprox kernel: scsi 3:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Jun 17 16:48:56 volprox kernel: sd 3:0:0:0: [sda] 23011328 512-byte logical blocks: (11.8 GB/11.0 GiB) Jun 17 16:48:56 volprox kernel: sd 3:0:0:0: [sda] Write Protect is off Jun 17 16:48:56 volprox kernel: sd 3:0:0:0: [sda] Mode Sense: 00 3a 00 00 Jun 17 16:48:56 volprox kernel: sd 3:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jun 17 16:48:56 volprox kernel: sd 2:0:0:0: [sdb] 28778496 512-byte logical blocks: (14.7 GB/13.7 GiB) Jun 17 16:48:56 volprox kernel: sd 2:0:0:0: [sdb] Write Protect is off Jun 17 16:48:56 volprox kernel: sd 2:0:0:0: [sdb] Mode Sense: 00 3a 00 00 Jun 17 16:48:56 volprox kernel: sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jun 17 16:48:56 volprox kernel: sd 2:0:0:0: [sdb] Preferred minimum I/O size 512 bytes Jun 17 16:48:56 volprox kernel: sd 3:0:0:0: [sda] Preferred minimum I/O size 512 bytes Jun 17 16:48:56 volprox kernel: sda: sda1 sda2 sda3 Jun 17 16:48:56 volprox kernel: sd 3:0:0:0: [sda] Attached SCSI disk Jun 17 16:48:56 volprox kernel: sdb: sdb1 sdb2 sdb3 Jun 17 16:48:56 volprox kernel: sd 2:0:0:0: [sdb] Attached SCSI disk Jun 17 16:48:56 volprox kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 17 16:48:56 volprox kernel: sdhci: Secure Digital Host Controller Interface driver Jun 17 16:48:56 volprox kernel: sdhci: Copyright(c) Pierre Ossman Jun 17 16:48:56 volprox kernel: fuse: init (API version 7.41) Jun 17 16:48:56 volprox kernel: usbcore: registered new interface driver uas Jun 17 16:48:56 volprox kernel: i915.modeset=1 is deprecated. Please remove it and the 'nomodeset' kernel parameter instead. Jun 17 16:48:56 volprox kernel: [drm] radeon kernel modesetting enabled. Jun 17 16:48:56 volprox kernel: random: crng init done Jun 17 16:48:56 volprox kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jun 17 16:48:56 volprox kernel: EXT4-fs (sda2): recovery complete Jun 17 16:48:56 volprox kernel: EXT4-fs (sda2): mounted filesystem f9122924-079c-42eb-9700-f39ff249b20f r/w with ordered data mode. Quota mode: none. Jun 17 16:48:56 volprox kernel: loop: module loaded Jun 17 16:48:56 volprox kernel: block device autoloading is deprecated and will be removed. Jun 17 16:48:56 volprox kernel: loop0: detected capacity change from 0 to 2317560 Jun 17 16:48:56 volprox kernel: EXT4-fs (sda3): recovery complete Jun 17 16:48:56 volprox kernel: EXT4-fs (sda3): mounted filesystem 235821d1-628a-4647-a5fe-6c10402b8c33 r/w with ordered data mode. Quota mode: none. Jun 17 16:48:56 volprox systemd[1]: Inserted module 'autofs4' Jun 17 16:48:56 volprox systemd[1]: systemd 252.36-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) Jun 17 16:48:56 volprox systemd[1]: Detected virtualization kvm. Jun 17 16:48:56 volprox systemd[1]: Detected architecture x86-64. Jun 17 16:48:56 volprox systemd[1]: Hostname set to . Jun 17 16:48:56 volprox systemd[1]: Queued start job for default target graphical.target. Jun 17 16:48:56 volprox systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 17 16:48:56 volprox systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 17 16:48:56 volprox systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 17 16:48:56 volprox systemd[1]: Created slice user.slice - User and Session Slice. Jun 17 16:48:56 volprox systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 17 16:48:56 volprox systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 17 16:48:56 volprox systemd[1]: Expecting device dev-disk-by\x2duuid-9F79\x2dDB23.device - /dev/disk/by-uuid/9F79-DB23... Jun 17 16:48:56 volprox systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 17 16:48:56 volprox systemd[1]: Reached target slices.target - Slice Units. Jun 17 16:48:56 volprox systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 17 16:48:56 volprox systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Jun 17 16:48:56 volprox systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Jun 17 16:48:56 volprox systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Jun 17 16:48:56 volprox systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Jun 17 16:48:56 volprox systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 17 16:48:56 volprox systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jun 17 16:48:56 volprox systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 17 16:48:56 volprox systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 17 16:48:56 volprox systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 17 16:48:56 volprox systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 17 16:48:56 volprox systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 17 16:48:56 volprox systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 17 16:48:56 volprox systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jun 17 16:48:56 volprox systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 17 16:48:56 volprox systemd[1]: Reached target swap.target - Swaps. Jun 17 16:48:56 volprox systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Jun 17 16:48:56 volprox systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 17 16:48:56 volprox systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 17 16:48:56 volprox systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 17 16:48:56 volprox systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 17 16:48:56 volprox systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 17 16:48:56 volprox systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 17 16:48:56 volprox systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 17 16:48:56 volprox systemd[1]: Starting systemd-journald.service - Journal Service... Jun 17 16:48:56 volprox systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 17 16:48:56 volprox systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 17 16:48:56 volprox systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 17 16:48:56 volprox systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 17 16:48:56 volprox systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 17 16:48:56 volprox systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 17 16:48:56 volprox systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 17 16:48:56 volprox systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 17 16:48:56 volprox systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 17 16:48:56 volprox systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 17 16:48:56 volprox systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Jun 17 16:48:56 volprox systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 17 16:48:56 volprox systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 17 16:48:56 volprox systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 17 16:48:56 volprox systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 17 16:48:56 volprox systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 17 16:48:56 volprox systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 17 16:48:56 volprox systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 17 16:48:56 volprox systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 17 16:48:56 volprox systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 17 16:48:56 volprox systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 17 16:48:56 volprox systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 17 16:48:56 volprox systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 17 16:48:56 volprox systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 17 16:48:56 volprox systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 17 16:48:56 volprox systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 17 16:48:56 volprox systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 17 16:48:56 volprox systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 17 16:48:56 volprox systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 17 16:48:56 volprox systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Jun 17 16:48:56 volprox systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 17 16:48:56 volprox systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 17 16:48:56 volprox systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 17 16:48:56 volprox systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Jun 17 16:48:56 volprox systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 17 16:48:56 volprox systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 17 16:48:56 volprox systemd-journald[484]: Journal started Jun 17 16:48:56 volprox systemd-journald[484]: Runtime Journal (/run/log/journal/532ebd11fccb7018a1d61544683a89a5) is 3.7M, max 30.0M, 26.2M free. Jun 17 16:48:56 volprox fake-hwclock[474]: Current system time: 2025-06-17 07:48:56 Jun 17 16:48:56 volprox fake-hwclock[474]: fake-hwclock saved clock information is in the past: 2025-06-17 07:48:45 Jun 17 16:48:56 volprox fake-hwclock[474]: To set system time to this saved clock anyway, use "force" Jun 17 16:48:56 volprox systemd[1]: dynamicswap.service: Deactivated successfully. Jun 17 16:48:56 volprox systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 17 16:48:56 volprox systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 17 16:48:56 volprox systemd[1]: Started systemd-journald.service - Journal Service. Jun 17 16:48:56 volprox systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 17 16:48:56 volprox systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 17 16:48:56 volprox systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 17 16:48:56 volprox systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 17 16:48:56 volprox systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Jun 17 16:48:56 volprox systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Jun 17 16:48:56 volprox systemd-udevd[506]: Using default interface naming scheme 'v252'. Jun 17 16:48:57 volprox systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 17 16:48:57 volprox systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Jun 17 16:48:57 volprox systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Jun 17 16:48:57 volprox 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). Jun 17 16:48:57 volprox systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Jun 17 16:48:57 volprox systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 17 16:48:57 volprox (udev-worker)[517]: Network interface NamePolicy= disabled on kernel command line. Jun 17 16:48:57 volprox kernel: sr 1:0:0:0: Attached scsi generic sg0 type 5 Jun 17 16:48:57 volprox kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Jun 17 16:48:57 volprox kernel: sd 3:0:0:0: Attached scsi generic sg2 type 0 Jun 17 16:48:57 volprox systemd[1]: Found device dev-disk-by\x2duuid-9F79\x2dDB23.device - QEMU_HARDDISK boot. Jun 17 16:48:57 volprox systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Jun 17 16:48:57 volprox (udev-worker)[513]: mouse0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-9F79\x2dDB23.service - File System Check on /dev/disk/by-uuid/9F79-DB23... Jun 17 16:48:57 volprox systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Jun 17 16:48:57 volprox systemd-fsck[563]: fsck.fat 4.2 (2021-01-31) Jun 17 16:48:57 volprox systemd-fsck[563]: /dev/sda1: 18 files, 22738/93500 clusters Jun 17 16:48:57 volprox (udev-worker)[514]: mouse1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-9F79\x2dDB23.service - File System Check on /dev/disk/by-uuid/9F79-DB23. Jun 17 16:48:57 volprox (udev-worker)[509]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox (udev-worker)[515]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox systemd[1]: Found device sys-subsystem-net-devices-eth0.device - 82574L Gigabit Network Connection. Jun 17 16:48:57 volprox (udev-worker)[528]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox (udev-worker)[523]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox kernel: cryptd: max_cpu_qlen set to 1000 Jun 17 16:48:57 volprox systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 17 16:48:57 volprox (udev-worker)[524]: mouse2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox kernel: usbcore: registered new interface driver snd-usb-audio Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC887-VD: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:line Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: hp_outs=1 (0x1b/0x0/0x0/0x0/0x0) Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: dig-out=0x11/0x0 Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: inputs: Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: Rear Mic=0x18 Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: Front Mic=0x19 Jun 17 16:48:57 volprox kernel: snd_hda_codec_realtek hdaudioC0D0: Line=0x1a Jun 17 16:48:57 volprox kernel: snd_hda_codec_hdmi hdaudioC0D2: No i915 binding for Intel HDMI/DP codec Jun 17 16:48:57 volprox kernel: hdaudio hdaudioC0D2: Unable to configure, disabling Jun 17 16:48:57 volprox kernel: input: HDA Intel PCH Rear Mic as /devices/pci0000:00/0000:00:11.0/sound/card0/input7 Jun 17 16:48:57 volprox kernel: input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:11.0/sound/card0/input8 Jun 17 16:48:57 volprox kernel: input: HDA Intel PCH Line as /devices/pci0000:00/0000:00:11.0/sound/card0/input9 Jun 17 16:48:57 volprox kernel: input: HDA Intel PCH Line Out as /devices/pci0000:00/0000:00:11.0/sound/card0/input10 Jun 17 16:48:57 volprox kernel: input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:11.0/sound/card0/input11 Jun 17 16:48:57 volprox (udev-worker)[519]: event4: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox 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). Jun 17 16:48:57 volprox systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 17 16:48:57 volprox systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 17 16:48:57 volprox systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 17 16:48:57 volprox systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 17 16:48:57 volprox systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 17 16:48:57 volprox systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 17 16:48:57 volprox systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 17 16:48:57 volprox systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 17 16:48:57 volprox systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 17 16:48:57 volprox systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 17 16:48:57 volprox systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 17 16:48:57 volprox 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). Jun 17 16:48:57 volprox systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 17 16:48:57 volprox systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 17 16:48:57 volprox systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 17 16:48:57 volprox systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 17 16:48:57 volprox systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 17 16:48:57 volprox systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 17 16:48:57 volprox systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 17 16:48:57 volprox systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 17 16:48:57 volprox systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 17 16:48:57 volprox systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 17 16:48:57 volprox (udev-worker)[519]: event7: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox (udev-worker)[509]: event5: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox (udev-worker)[518]: event9: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox (udev-worker)[528]: event6: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox (udev-worker)[517]: event8: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 17 16:48:57 volprox (udev-worker)[515]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jun 17 16:48:57 volprox systemd[1]: Mounting boot.mount - /boot... Jun 17 16:48:57 volprox systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 17 16:48:57 volprox systemd[1]: Mounting tmp.mount - /tmp... Jun 17 16:48:57 volprox systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 17 16:48:57 volprox kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jun 17 16:48:57 volprox systemd[1]: Mounting var-log.mount - /var/log... Jun 17 16:48:57 volprox systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Jun 17 16:48:57 volprox systemd[1]: Mounted boot.mount - /boot. Jun 17 16:48:57 volprox systemd[1]: Mounted tmp.mount - /tmp. Jun 17 16:48:57 volprox systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Jun 17 16:48:57 volprox systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Jun 17 16:48:57 volprox systemd[1]: Mounted var-log.mount - /var/log. Jun 17 16:48:57 volprox systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 17 16:48:57 volprox systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Jun 17 16:48:57 volprox systemd[1]: Reached target local-fs.target - Local File Systems. Jun 17 16:48:57 volprox systemd[1]: Started ifup@eth0.service - ifup for eth0. Jun 17 16:48:57 volprox systemd[1]: Starting networking.service - Raise network interfaces... Jun 17 16:48:57 volprox systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Jun 17 16:48:57 volprox systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Jun 17 16:48:57 volprox systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jun 17 16:48:57 volprox systemd-journald[484]: Runtime Journal (/run/log/journal/532ebd11fccb7018a1d61544683a89a5) is 3.7M, max 30.0M, 26.2M free. Jun 17 16:48:57 volprox systemd-journald[484]: Received client request to flush runtime journal. Jun 17 16:48:57 volprox systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 17 16:48:57 volprox systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Jun 17 16:48:57 volprox systemd[1]: Received SIGRTMIN+20 from PID 341 (plymouthd). Jun 17 16:48:57 volprox systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 17 16:48:57 volprox systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 661 (systemd-binfmt) Jun 17 16:48:57 volprox systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 17 16:48:57 volprox systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Jun 17 16:48:57 volprox kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 17 16:48:57 volprox systemd[1]: Starting rpcbind.service - RPC bind portmap service... Jun 17 16:48:57 volprox systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 17 16:48:57 volprox systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 17 16:48:57 volprox kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 17 16:48:57 volprox kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 17 16:48:57 volprox ifup[672]: command failed: No such device (-19) Jun 17 16:48:57 volprox sh[674]: command failed: No such device (-19) Jun 17 16:48:57 volprox systemd[1]: Started rpcbind.service - RPC bind portmap service. Jun 17 16:48:57 volprox systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Jun 17 16:48:57 volprox haveged[677]: haveged: command socket is listening at fd 3 Jun 17 16:48:58 volprox sh[701]: dhcpcd-9.4.1 starting Jun 17 16:48:58 volprox dhcpcd[701]: dhcpcd-9.4.1 starting Jun 17 16:48:58 volprox sh[701]: DUID 00:04:43:5c:66:39:d9:0b:46:78:8d:da:27:66:66:31:a1:b5 Jun 17 16:48:58 volprox dhcpcd[713]: DUID 00:04:43:5c:66:39:d9:0b:46:78:8d:da:27:66:66:31:a1:b5 Jun 17 16:48:58 volprox kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 17 16:48:58 volprox ifup[719]: command failed: No such device (-19) Jun 17 16:48:58 volprox sh[701]: eth0: waiting for carrier Jun 17 16:48:58 volprox dhcpcd[713]: eth0: waiting for carrier Jun 17 16:48:58 volprox kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Jun 17 16:48:58 volprox haveged[677]: haveged: ver: 1.9.14; arch: x86; vend: GenuineIntel; build: (gcc 12.2.0 ITV); collect: 128K Jun 17 16:48:58 volprox haveged[677]: haveged: cpu: (L4 VC); data: 32K (L2 L4 V); inst: 32K (L2 L4 V); idx: 24/40; sz: 32010/53875 Jun 17 16:48:58 volprox haveged[677]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0012 Jun 17 16:48:58 volprox haveged[677]: haveged: fills: 0, generated: 0 Jun 17 16:48:58 volprox systemd[1]: Finished networking.service - Raise network interfaces. Jun 17 16:48:58 volprox sh[701]: eth0: carrier acquired Jun 17 16:48:58 volprox dhcpcd[713]: eth0: carrier acquired Jun 17 16:48:58 volprox sh[701]: eth0: IAID 11:d8:93:66 Jun 17 16:48:58 volprox kernel: e1000e 0000:00:12.0 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx Jun 17 16:48:58 volprox dhcpcd[713]: eth0: IAID 11:d8:93:66 Jun 17 16:48:58 volprox sh[701]: eth0: adding address fe80::4766:cf0c:75a1:cff4 Jun 17 16:48:58 volprox sh[701]: ipv6_addaddr1: Permission denied Jun 17 16:48:58 volprox dhcpcd[713]: eth0: adding address fe80::4766:cf0c:75a1:cff4 Jun 17 16:48:58 volprox dhcpcd[713]: ipv6_addaddr1: Permission denied Jun 17 16:48:58 volprox dhcpcd[713]: eth0: soliciting an IPv6 router Jun 17 16:48:58 volprox sh[701]: eth0: soliciting an IPv6 router Jun 17 16:48:58 volprox systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Jun 17 16:48:58 volprox systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Jun 17 16:48:58 volprox systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Jun 17 16:48:58 volprox systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Jun 17 16:48:58 volprox systemd[1]: Reached target sysinit.target - System Initialization. Jun 17 16:48:58 volprox systemd[1]: Started acpid.path - ACPI Events Check. Jun 17 16:48:58 volprox systemd[1]: Started apt-daily.timer - Daily apt download activities. Jun 17 16:48:58 volprox systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Jun 17 16:48:58 volprox systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Jun 17 16:48:58 volprox systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Jun 17 16:48:58 volprox systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Jun 17 16:48:58 volprox systemd[1]: Started man-db.timer - Daily man-db regeneration. Jun 17 16:48:58 volprox systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Jun 17 16:48:58 volprox systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Jun 17 16:48:58 volprox systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 17 16:48:58 volprox systemd[1]: Reached target paths.target - Path Units. Jun 17 16:48:58 volprox systemd[1]: Reached target timers.target - Timer Units. Jun 17 16:48:58 volprox systemd[1]: Listening on acpid.socket - ACPID Listen Socket. Jun 17 16:48:58 volprox systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Jun 17 16:48:58 volprox systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 17 16:48:58 volprox systemd[1]: Listening on triggerhappy.socket. Jun 17 16:48:58 volprox systemd[1]: Reached target sockets.target - Socket Units. Jun 17 16:48:58 volprox systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jun 17 16:48:58 volprox systemd[1]: Reached target basic.target - Basic System. Jun 17 16:48:58 volprox systemd[1]: Started acpid.service - ACPI event daemon. Jun 17 16:48:58 volprox systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Jun 17 16:48:58 volprox systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Jun 17 16:48:58 volprox systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 17 16:48:58 volprox systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 17 16:48:58 volprox systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Jun 17 16:48:58 volprox systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Jun 17 16:48:58 volprox systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Jun 17 16:48:58 volprox kernel: RPC: Registered named UNIX socket transport module. Jun 17 16:48:58 volprox kernel: RPC: Registered udp transport module. Jun 17 16:48:58 volprox kernel: RPC: Registered tcp transport module. Jun 17 16:48:58 volprox kernel: RPC: Registered tcp-with-tls transport module. Jun 17 16:48:58 volprox kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jun 17 16:48:58 volprox avahi-daemon[775]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 17 16:48:58 volprox systemd[1]: Started iptables.service - Volumio Iptables Module. Jun 17 16:48:58 volprox avahi-daemon[775]: Successfully dropped root privileges. Jun 17 16:48:58 volprox avahi-daemon[775]: avahi-daemon 0.8 starting up. Jun 17 16:48:58 volprox systemd[1]: Starting systemd-logind.service - User Login Management... Jun 17 16:48:58 volprox systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Jun 17 16:48:58 volprox systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 17 16:48:58 volprox systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Jun 17 16:48:58 volprox systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Jun 17 16:48:58 volprox systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Jun 17 16:48:58 volprox systemd[1]: Starting wireless.service - Wireless Services... Jun 17 16:48:58 volprox systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Jun 17 16:48:58 volprox systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Jun 17 16:48:58 volprox systemd[1]: Reached target rpc_pipefs.target. Jun 17 16:48:58 volprox systemd[1]: Reached target sound.target - Sound Card. Jun 17 16:48:58 volprox systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jun 17 16:48:58 volprox systemd[1]: Reached target nfs-client.target - NFS client services. Jun 17 16:48:58 volprox systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 17 16:48:58 volprox systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 17 16:48:58 volprox systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Jun 17 16:48:58 volprox systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jun 17 16:48:58 volprox volumio_rfkill_unblock.sh[786]: Volumio WiFi Soft Blocked check script Jun 17 16:48:58 volprox volumio_rfkill_unblock.sh[786]: Check if rfkill is available Jun 17 16:48:58 volprox systemd[1]: e2scrub_reap.service: Deactivated successfully. Jun 17 16:48:58 volprox systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Jun 17 16:48:58 volprox volumio_rfkill_unblock.sh[786]: Check if rfkill listed devices are already unblocked Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: Jun 17 16:48:58 volprox thd[783]: Unable to parse trigger line: Jun 17 16:48:58 volprox thd[783]: Found socket passed from systemd Jun 17 16:48:58 volprox volumio_rfkill_unblock.sh[786]: Wi-Fi is already unblocked. Jun 17 16:48:58 volprox systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Jun 17 16:48:58 volprox systemd-logind[782]: New seat seat0. Jun 17 16:48:58 volprox systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Jun 17 16:48:58 volprox systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Jun 17 16:48:59 volprox systemd-logind[782]: Watching system buttons on /dev/input/event0 (Power Button) Jun 17 16:48:59 volprox systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 17 16:48:59 volprox systemd-logind[782]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Jun 17 16:48:59 volprox acpid[773]: starting up with netlink and the input layer Jun 17 16:48:59 volprox acpid[773]: 3 rules loaded Jun 17 16:48:59 volprox acpid[773]: waiting for events: event logging is off Jun 17 16:48:59 volprox avahi-daemon[775]: Successfully called chroot(). Jun 17 16:48:59 volprox avahi-daemon[775]: Successfully dropped remaining capabilities. Jun 17 16:48:59 volprox avahi-daemon[775]: Loading service file /services/volumio.service. Jun 17 16:48:59 volprox dhcpcd[797]: dhcpcd-9.4.1 starting Jun 17 16:48:59 volprox systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Jun 17 16:48:59 volprox avahi-daemon[775]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 17 16:48:59 volprox avahi-daemon[775]: New relevant interface lo.IPv4 for mDNS. Jun 17 16:48:59 volprox avahi-daemon[775]: Network interface enumeration completed. Jun 17 16:48:59 volprox avahi-daemon[775]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 17 16:48:59 volprox dbus-daemon[776]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 17 16:48:59 volprox systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 17 16:48:59 volprox systemd[1]: Started systemd-logind.service - User Login Management. Jun 17 16:48:59 volprox dhcpcd[808]: dev: loaded udev Jun 17 16:48:59 volprox dhcpcd[808]: DUID 00:04:43:5c:66:39:d9:0b:46:78:8d:da:27:66:66:31:a1:b5 Jun 17 16:48:59 volprox systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Jun 17 16:48:59 volprox dhcpcd[808]: eth0: IAID 11:d8:93:66 Jun 17 16:48:59 volprox dhcpcd[808]: eth0: adding address fe80::4766:cf0c:75a1:cff4 Jun 17 16:48:59 volprox dhcpcd[808]: ipv6_addaddr1: Permission denied Jun 17 16:48:59 volprox volumio-remote-updater[785]: Volumio OTA Client: 1.7.0 Jun 17 16:48:59 volprox volumio-remote-updater[785]: Error: No active session Jun 17 16:48:59 volprox volumio-remote-updater[785]: [2025-06-17 16:48:59] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 17 16:48:59 volprox volumio-remote-updater[785]: [2025-06-17 16:48:59] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 17 16:48:59 volprox volumio-remote-updater[785]: [2025-06-17 16:48:59] [error] handle_connect error: Connection refused Jun 17 16:48:59 volprox upmpdcli[784]: Could not open config: /tmp/upmpdcli.conf Jun 17 16:48:59 volprox wpa_supplicant[801]: Successfully initialized wpa_supplicant Jun 17 16:48:59 volprox systemd[1]: Started wpa_supplicant.service - WPA supplicant. Jun 17 16:48:59 volprox systemd[1]: Reached target network.target - Network. Jun 17 16:48:59 volprox systemd[1]: Reached target network-online.target - Network is Online. Jun 17 16:48:59 volprox loadcpufreq[788]: Loading cpufreq kernel modules...failed. Jun 17 16:48:59 volprox systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Jun 17 16:48:59 volprox systemd[1]: Starting nmbd.service - Samba NMB Daemon... Jun 17 16:48:59 volprox systemd[1]: Starting ntpsec.service - Network Time Service... Jun 17 16:48:59 volprox systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Jun 17 16:48:59 volprox systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Jun 17 16:48:59 volprox systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Jun 17 16:48:59 volprox systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jun 17 16:48:59 volprox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 17 16:48:59 volprox sm-notify[854]: Version 2.6.2 starting Jun 17 16:48:59 volprox systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Jun 17 16:48:59 volprox systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 17 16:48:59 volprox systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Jun 17 16:48:59 volprox systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 17 16:48:59 volprox systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 17 16:48:59 volprox systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 17 16:48:59 volprox systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Jun 17 16:48:59 volprox dbus-daemon[776]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.4' (uid=0 pid=863 comm="timedatectl show --property=NTPSynchronized --valu") Jun 17 16:48:59 volprox systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Jun 17 16:48:59 volprox systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Jun 17 16:48:59 volprox systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 17 16:48:59 volprox systemd[1]: iptables.service: Deactivated successfully. Jun 17 16:48:59 volprox ntpd[865]: INIT: ntpd ntpsec-1.2.2: Starting Jun 17 16:48:59 volprox ntp-systemd-wrapper[865]: 2025-06-17T16:48:59 ntpd[865]: INIT: ntpd ntpsec-1.2.2: Starting Jun 17 16:48:59 volprox ntpd[865]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 17 16:48:59 volprox ntp-systemd-wrapper[865]: 2025-06-17T16:48:59 ntpd[865]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 17 16:48:59 volprox welcome[870]: Resolved ip:[0] Jun 17 16:48:59 volprox ntpd[877]: INIT: precision = 0.079 usec (-24) Jun 17 16:48:59 volprox systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Jun 17 16:48:59 volprox ntpd[877]: INIT: successfully locked into RAM Jun 17 16:48:59 volprox systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Jun 17 16:48:59 volprox ntpd[877]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Jun 17 16:48:59 volprox systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jun 17 16:48:59 volprox ntpd[877]: CONFIG: restrict nopeer ignored Jun 17 16:48:59 volprox ntpd[877]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 17 16:48:59 volprox ntpd[877]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2025-12-28T00:00Z last=2017-01-01T00:00Z ofs=37 Jun 17 16:48:59 volprox ntpd[877]: INIT: Using SO_TIMESTAMPNS(ns) Jun 17 16:48:59 volprox ntpd[877]: IO: Listen and drop on 0 v6wildcard [::]:123 Jun 17 16:48:59 volprox ntpd[877]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 17 16:48:59 volprox ntpd[877]: IO: Listen normally on 2 lo 127.0.0.1:123 Jun 17 16:48:59 volprox ntpd[877]: IO: Listening on routing socket on fd #19 for interface updates Jun 17 16:48:59 volprox systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 17 16:48:59 volprox systemd[1]: Started ntpsec.service - Network Time Service. Jun 17 16:48:59 volprox systemd[1]: Received SIGRTMIN+21 from PID 341 (plymouthd). Jun 17 16:48:59 volprox systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jun 17 16:48:59 volprox systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jun 17 16:48:59 volprox ntpd[877]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jun 17 16:48:59 volprox ntpd[877]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Jun 17 16:48:59 volprox ntpd[877]: INIT: Running with OpenSSL 3.0.16 11 Feb 2025, 30000100 Jun 17 16:48:59 volprox ntpd[877]: NTSc: Using system default root certificates. Jun 17 16:48:59 volprox systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 17 16:48:59 volprox systemd[1]: Received SIGRTMIN+21 from PID 341 (n/a). Jun 17 16:48:59 volprox ntpd[877]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Jun 17 16:48:59 volprox dhcpcd[808]: eth0: soliciting an IPv6 router Jun 17 16:48:59 volprox ifplugd(eth0)[899]: ifplugd 0.28 initializing. Jun 17 16:48:59 volprox ifplugd(eth0)[899]: Using interface eth0/BC:24:11:D8:93:66 with driver (version: 6.12.30-volumio) Jun 17 16:48:59 volprox ifplugd(eth0)[899]: Using detection mode: SIOCETHTOOL Jun 17 16:48:59 volprox ifplugd(eth0)[899]: Initialization complete, link beat detected. Jun 17 16:48:59 volprox systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 17 16:48:59 volprox sshd[891]: Server listening on 0.0.0.0 port 22. Jun 17 16:48:59 volprox sshd[891]: Server listening on :: port 22. Jun 17 16:48:59 volprox systemd[1]: Reached target getty.target - Login Prompts. Jun 17 16:48:59 volprox systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Jun 17 16:48:59 volprox dbus-daemon[776]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 17 16:48:59 volprox systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 17 16:48:59 volprox bash[858]: Time is already synchronized. Jun 17 16:48:59 volprox systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 17 16:48:59 volprox systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 17 16:48:59 volprox cpufrequtils[876]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Jun 17 16:48:59 volprox systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Jun 17 16:48:59 volprox ifplugd(eth0)[899]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 17 16:48:59 volprox sh[701]: eth0: soliciting a DHCP lease Jun 17 16:48:59 volprox dhcpcd[713]: eth0: soliciting a DHCP lease Jun 17 16:48:59 volprox ifplugd(eth0)[899]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jun 17 16:48:59 volprox sh[701]: eth0: offered 172.30.1.70 from 172.30.1.254 Jun 17 16:48:59 volprox dhcpcd[713]: eth0: offered 172.30.1.70 from 172.30.1.254 Jun 17 16:48:59 volprox sh[701]: eth0: probing address 172.30.1.70/24 Jun 17 16:48:59 volprox dhcpcd[713]: eth0: probing address 172.30.1.70/24 Jun 17 16:48:59 volprox systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Jun 17 16:48:59 volprox systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Jun 17 16:48:59 volprox iw[944]: command failed: No such device (-19) Jun 17 16:48:59 volprox nmbd[937]: [2025/06/17 16:48:59.937075, 0] ../../source3/nmbd/nmbd.c:901(main) Jun 17 16:48:59 volprox nmbd[937]: nmbd version 4.17.12-Debian started. Jun 17 16:48:59 volprox nmbd[937]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 17 16:48:59 volprox nmbd[937]: [2025/06/17 16:48:59.941892, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 17 16:48:59 volprox nmbd[937]: started asyncdns process 950 Jun 17 16:48:59 volprox nmbd[937]: [2025/06/17 16:48:59.942849, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jun 17 16:48:59 volprox iwconfig[947]: Error for wireless request "Set Power Management" (8B2C) : Jun 17 16:48:59 volprox iwconfig[947]: SET failed on device wlan0 ; No such device. Jun 17 16:48:59 volprox nmbd[937]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 17 16:48:59 volprox systemd[1]: wireless.service: Deactivated successfully. Jun 17 16:48:59 volprox systemd[1]: Started wireless.service - Wireless Services. Jun 17 16:48:59 volprox nmbd[937]: [2025/06/17 16:48:59.943470, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jun 17 16:48:59 volprox nmbd[937]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 17 16:48:59 volprox systemd[1]: Started volumio.service - Volumio Backend Module. Jun 17 16:48:59 volprox systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Jun 17 16:48:59 volprox systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Jun 17 16:48:59 volprox systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jun 17 16:48:59 volprox volumio-cpu-tweak[954]: Setting RT Priority for mpd Jun 17 16:48:59 volprox volumio-cpu-tweak[976]: pid 35's current scheduling policy: SCHED_OTHER Jun 17 16:48:59 volprox volumio-cpu-tweak[976]: pid 35's current scheduling priority: 0 Jun 17 16:48:59 volprox volumio-cpu-tweak[954]: Not enough cores to set MPD affinity Jun 17 16:48:59 volprox volumio-cpu-tweak[954]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 17 16:48:59 volprox volumio-cpu-tweak[978]: Error setting new values. Common errors: Jun 17 16:48:59 volprox volumio-cpu-tweak[978]: - Do you have proper administration rights? (super-user?) Jun 17 16:48:59 volprox volumio-cpu-tweak[978]: - Is the governor you requested available and modprobed? Jun 17 16:48:59 volprox volumio-cpu-tweak[978]: - Trying to set an invalid policy? Jun 17 16:48:59 volprox volumio-cpu-tweak[978]: - Trying to set a specific frequency, but userspace governor is not available, Jun 17 16:48:59 volprox volumio-cpu-tweak[978]: for example because of hardware which cannot be set to a specific frequency Jun 17 16:48:59 volprox volumio-cpu-tweak[978]: or because the userspace governor isn't loaded? Jun 17 16:48:59 volprox systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jun 17 16:49:00 volprox avahi-daemon[775]: Server startup complete. Host name is volprox.local. Local service cookie is 2934193399. Jun 17 16:49:00 volprox dhcpcd[808]: eth0: soliciting a DHCP lease Jun 17 16:49:00 volprox soundcard-init.sh[1018]: Simple mixer control 'IEC958',0 Jun 17 16:49:00 volprox soundcard-init.sh[1018]: Capabilities: pswitch pswitch-joined Jun 17 16:49:00 volprox soundcard-init.sh[1018]: Playback channels: Mono Jun 17 16:49:00 volprox soundcard-init.sh[1018]: Mono: Playback [on] Jun 17 16:49:00 volprox systemd[1]: soundcard-init.service: Deactivated successfully. Jun 17 16:49:00 volprox dhcpcd[808]: eth0: offered 172.30.1.70 from 172.30.1.254 Jun 17 16:49:00 volprox dhcpcd[808]: eth0: probing address 172.30.1.70/24 Jun 17 16:49:00 volprox ntpd[877]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:00 volprox ntpd[877]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 17 16:49:00 volprox ntpd[877]: DNS: dns_check: DNS error: -11, System error Jun 17 16:49:00 volprox ntpd[877]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jun 17 16:49:00 volprox volumio[951]: info: ------------------------------------------- Jun 17 16:49:00 volprox volumio[951]: info: ----- Volumio3 ---- Jun 17 16:49:00 volprox volumio[951]: info: ------------------------------------------- Jun 17 16:49:00 volprox volumio[951]: info: ----- System startup ---- Jun 17 16:49:00 volprox volumio[951]: info: ------------------------------------------- Jun 17 16:49:00 volprox volumio[951]: info: MYVOLUMIO Environment detected Jun 17 16:49:00 volprox volumio[951]: info: Plugin folders cleanup Jun 17 16:49:00 volprox volumio[951]: info: Scanning into folder /volumio/app/plugins/ Jun 17 16:49:00 volprox volumio[951]: info: Scanning category audio_interface Jun 17 16:49:00 volprox volumio[951]: info: Scanning category miscellanea Jun 17 16:49:00 volprox volumio[951]: info: Scanning category music_service Jun 17 16:49:00 volprox volumio[951]: info: Scanning category plugins.json Jun 17 16:49:00 volprox volumio[951]: info: Scanning category system_controller Jun 17 16:49:00 volprox volumio[951]: info: Scanning category user_interface Jun 17 16:49:00 volprox volumio[951]: info: Scanning into folder /data/plugins/ Jun 17 16:49:00 volprox volumio[951]: info: Scanning category music_service Jun 17 16:49:00 volprox volumio[951]: info: Scanning category system_controller Jun 17 16:49:00 volprox volumio[951]: info: Scanning category user_interface Jun 17 16:49:00 volprox volumio[951]: info: Plugin folders cleanup completed Jun 17 16:49:00 volprox volumio[951]: info: ------------------------------------------- Jun 17 16:49:00 volprox volumio[951]: info: ----- Core plugins startup ---- Jun 17 16:49:00 volprox volumio[951]: info: ------------------------------------------- Jun 17 16:49:00 volprox volumio[951]: info: Loading plugins from folder /volumio/app/plugins/ Jun 17 16:49:00 volprox volumio[951]: info: Adding plugin upnp to MyMusic Plugins Jun 17 16:49:00 volprox volumio[951]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 17 16:49:00 volprox volumio[951]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 17 16:49:00 volprox volumio[951]: info: Loading plugins from folder /data/plugins/ Jun 17 16:49:00 volprox volumio[951]: info: Loading plugin "system"... Jun 17 16:49:00 volprox volumio[951]: info: Loading plugin "appearance"... Jun 17 16:49:00 volprox avahi-daemon[775]: Service "VolProx" (/services/volumio.service) successfully established. Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "network"... Jun 17 16:49:01 volprox volumio[951]: info: Refreshing Cached IP Addresses Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "services"... Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "alsa_controller"... Jun 17 16:49:01 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 16:49:01 volprox sudo[1048]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 16:49:01 volprox sudo[1046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 16:49:01 volprox sudo[1055]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "wizard"... Jun 17 16:49:01 volprox sudo[1048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:01 volprox sudo[1055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:01 volprox sudo[1046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:01 volprox sudo[1046]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:01 volprox sudo[1048]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:01 volprox sudo[1055]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "networkfs"... Jun 17 16:49:01 volprox volumio[951]: info: Starting Udev Watcher for removable devices Jun 17 16:49:01 volprox sudo[1084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kasiri33,password=Tw22tyim!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.30.1.60/music /mnt/NAS/VolProx Jun 17 16:49:01 volprox sudo[1084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:01 volprox volumio[951]: info: Ignoring mount for partition: boot Jun 17 16:49:01 volprox volumio[951]: info: Ignoring mount for partition: volumio Jun 17 16:49:01 volprox volumio[951]: info: Ignoring mount for partition: volumio_data Jun 17 16:49:01 volprox volumio[951]: info: Ignoring mount for partition: boot Jun 17 16:49:01 volprox volumio[951]: info: Ignoring mount for partition: volumio Jun 17 16:49:01 volprox volumio[951]: info: Ignoring mount for partition: volumio_data Jun 17 16:49:01 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "volumio_command_line_client"... Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "upnp"... Jun 17 16:49:01 volprox ntpd[877]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:01 volprox ntpd[877]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 17 16:49:01 volprox ntpd[877]: DNS: dns_check: DNS error: -11, System error Jun 17 16:49:01 volprox ntpd[877]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jun 17 16:49:01 volprox volumio[951]: info: [1750146541404] Starting Upmpd Daemon Jun 17 16:49:01 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "my_music"... Jun 17 16:49:01 volprox kernel: netfs: FS-Cache loaded Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "mpd"... Jun 17 16:49:01 volprox kernel: Key type dns_resolver registered Jun 17 16:49:01 volprox kernel: Key type cifs.spnego registered Jun 17 16:49:01 volprox kernel: Key type cifs.idmap registered Jun 17 16:49:01 volprox 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. Jun 17 16:49:01 volprox kernel: CIFS: Attempting to mount //172.30.1.60/music Jun 17 16:49:01 volprox kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 17 16:49:01 volprox kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 17 16:49:01 volprox sudo[1084]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "upnp_browser"... Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "alarm-clock"... Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "airplay_emulation"... Jun 17 16:49:01 volprox volumio[951]: info: Starting Shairport Sync Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "last_100"... Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "webradio"... Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "i2s_dacs"... Jun 17 16:49:01 volprox volumio[951]: info: I2S DAC not set, start Auto-detection Jun 17 16:49:01 volprox volumio[951]: info: Loading plugin "volumiodiscovery"... Jun 17 16:49:01 volprox volumio[951]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 16:49:01 volprox node[951]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 16:49:01 volprox volumio[951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 16:49:01 volprox node[951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 16:49:02 volprox volumio[951]: *** WARNING *** For more information see Jun 17 16:49:02 volprox node[951]: *** WARNING *** For more information see Jun 17 16:49:02 volprox volumio[951]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 16:49:02 volprox node[951]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 16:49:02 volprox volumio[951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 16:49:02 volprox node[951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 16:49:02 volprox volumio[951]: *** WARNING *** For more information see Jun 17 16:49:02 volprox node[951]: *** WARNING *** For more information see Jun 17 16:49:02 volprox volumio[951]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 17 16:49:02 volprox volumio[951]: info: Discovery: Started advertising with name: VolProx Jun 17 16:49:02 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 16:49:02 volprox volumio[951]: info: Loading plugin "ytcr"... Jun 17 16:49:02 volprox ntpd[877]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:02 volprox ntpd[877]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 17 16:49:02 volprox ntpd[877]: DNS: dns_check: DNS error: -11, System error Jun 17 16:49:02 volprox ntpd[877]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jun 17 16:49:03 volprox volumio[951]: info: Loading plugin "ytmusic"... Jun 17 16:49:03 volprox ntpd[877]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:03 volprox ntpd[877]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 17 16:49:03 volprox ntpd[877]: DNS: dns_check: DNS error: -11, System error Jun 17 16:49:03 volprox ntpd[877]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "SleepWakePlugin"... Jun 17 16:49:04 volprox volumio-remote-updater[785]: [2025-06-17 16:49:04] [connect] Successful connection Jun 17 16:49:04 volprox volumio[951]: info: Applying required configuration parameters for plugin SleepWakePlugin Jun 17 16:49:04 volprox volumio[951]: info: SleepWakePlugin - onVolumioStart Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "autostart"... Jun 17 16:49:04 volprox volumio[951]: info: Applying required configuration parameters for plugin autostart Jun 17 16:49:04 volprox volumio[951]: info: AutoStart - onVolumioStart - read config.json Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "now_playing"... Jun 17 16:49:04 volprox sh[701]: eth0: leased 172.30.1.70 for 3600 seconds Jun 17 16:49:04 volprox dhcpcd[713]: eth0: leased 172.30.1.70 for 3600 seconds Jun 17 16:49:04 volprox avahi-daemon[775]: Joining mDNS multicast group on interface eth0.IPv4 with address 172.30.1.70. Jun 17 16:49:04 volprox avahi-daemon[775]: New relevant interface eth0.IPv4 for mDNS. Jun 17 16:49:04 volprox avahi-daemon[775]: Registering new address record for 172.30.1.70 on eth0.IPv4. Jun 17 16:49:04 volprox dhcpcd[713]: eth0: adding route to 172.30.1.0/24 Jun 17 16:49:04 volprox sh[701]: eth0: adding route to 172.30.1.0/24 Jun 17 16:49:04 volprox systemd[1]: welcome.service: Deactivated successfully. Jun 17 16:49:04 volprox systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 17 16:49:04 volprox systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 17 16:49:04 volprox systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 17 16:49:04 volprox welcome[1118]: Resolved ip:[1] 172.30.1.70 Jun 17 16:49:04 volprox sh[701]: eth0: adding default route via 172.30.1.254 Jun 17 16:49:04 volprox sh[701]: forked to background, child pid 712 Jun 17 16:49:04 volprox dhcpcd[713]: eth0: adding default route via 172.30.1.254 Jun 17 16:49:04 volprox systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 17 16:49:04 volprox systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 17 16:49:04 volprox ifplugd(eth0)[899]: client: ifup: interface eth0 already configured Jun 17 16:49:04 volprox sh[1162]: eth0=eth0 Jun 17 16:49:04 volprox ifplugd(eth0)[899]: Program executed successfully. Jun 17 16:49:04 volprox ifplugd[835]: Network Interface Plugging Daemon...start eth0...done. Jun 17 16:49:04 volprox systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Jun 17 16:49:04 volprox systemd[1]: Started nmbd.service - Samba NMB Daemon. Jun 17 16:49:04 volprox systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "outputs"... Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "albumart"... Jun 17 16:49:04 volprox volumio[951]: info: Plugin example_plugin is not enabled Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "inputs"... Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "updater_comm"... Jun 17 16:49:04 volprox volumio[1167]: Forking 1 albumart workers Jun 17 16:49:04 volprox volumio[951]: info: Plugin mpdemulation is not enabled Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "rest_api"... Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "websocket"... Jun 17 16:49:04 volprox winbindd[1166]: [2025/06/17 16:49:04.948857, 0] ../../source3/winbindd/winbindd.c:1440(main) Jun 17 16:49:04 volprox volumio[1177]: Starting albumart workers Jun 17 16:49:04 volprox winbindd[1166]: winbindd version 4.17.12-Debian started. Jun 17 16:49:04 volprox winbindd[1166]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 17 16:49:04 volprox volumio[951]: info: Starting Socket.io Server version 1.7.4 Jun 17 16:49:04 volprox volumio[951]: info: Loading plugin "backup_restore"... Jun 17 16:49:04 volprox winbindd[1166]: [2025/06/17 16:49:04.958134, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Jun 17 16:49:04 volprox winbindd[1166]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 17 16:49:04 volprox systemd[1]: Started winbind.service - Samba Winbind Daemon. Jun 17 16:49:04 volprox systemd[1]: Starting smbd.service - Samba SMB Daemon... Jun 17 16:49:05 volprox smbd[1198]: [2025/06/17 16:49:05.195082, 0] ../../source3/smbd/server.c:1741(main) Jun 17 16:49:05 volprox smbd[1198]: smbd version 4.17.12-Debian started. Jun 17 16:49:05 volprox smbd[1198]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 17 16:49:05 volprox volumio[951]: info: Applying required configuration parameters for plugin backup_restore Jun 17 16:49:05 volprox volumio[951]: info: Loading plugin "scheduledrestart"... Jun 17 16:49:05 volprox dhcpcd[808]: eth0: leased 172.30.1.70 for 3600 seconds Jun 17 16:49:05 volprox systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 17 16:49:05 volprox systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 17 16:49:05 volprox systemd[1]: welcome.service: Deactivated successfully. Jun 17 16:49:05 volprox systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 17 16:49:05 volprox systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 17 16:49:05 volprox volumio[951]: info: Applying required configuration parameters for plugin scheduledrestart Jun 17 16:49:05 volprox volumio[951]: info: Loading plugin "Systeminfo"... Jun 17 16:49:05 volprox systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 17 16:49:05 volprox dhcpcd[808]: eth0: adding route to 172.30.1.0/24 Jun 17 16:49:05 volprox dhcpcd[808]: eth0: adding default route via 172.30.1.254 Jun 17 16:49:05 volprox welcome[1224]: Resolved ip:[1] 172.30.1.70 Jun 17 16:49:05 volprox systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 17 16:49:05 volprox systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 17 16:49:05 volprox volumio[951]: info: Loading plugin "randomizer"... Jun 17 16:49:05 volprox systemd[1]: Started smbd.service - Samba SMB Daemon. Jun 17 16:49:05 volprox systemd[1]: Reached target multi-user.target - Multi-User System. Jun 17 16:49:05 volprox systemd[1]: Reached target graphical.target - Graphical Interface. Jun 17 16:49:05 volprox systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jun 17 16:49:05 volprox systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jun 17 16:49:05 volprox systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jun 17 16:49:05 volprox systemd[1]: Startup finished in 6.396s (kernel) + 9.131s (userspace) = 15.528s. Jun 17 16:49:05 volprox volumio[951]: info: Applying required configuration parameters for plugin randomizer Jun 17 16:49:05 volprox volumio[951]: info: Loading i18n strings for locale ko Jun 17 16:49:05 volprox volumio[951]: Updating browse sources language Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::initPlayerControls Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 16:49:05 volprox volumio[951]: Express server listening on port 3000 Jun 17 16:49:05 volprox volumio[951]: [Metrics] WebUI: 5s 355.73ms Jun 17 16:49:05 volprox volumio[951]: info: Setting Device type: x86 Jun 17 16:49:05 volprox volumio[951]: info: CoreStateMachine::resetVolumioState Jun 17 16:49:05 volprox volumio[951]: info: CoreStateMachine::getcurrentVolume Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:05 volprox volumio[951]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 17 16:49:05 volprox volumio[951]: info: Completed loading Core Plugins Jun 17 16:49:05 volprox volumio[951]: info: Preparing to generate the ALSA configuration file Jun 17 16:49:05 volprox volumio[951]: info: Volumio Network Manager: Network status updated: 0 Jun 17 16:49:05 volprox volumio[951]: info: Cannot mount NAS VolProx at system boot, trial number 1 ,retrying in 5 seconds Jun 17 16:49:05 volprox volumio[951]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 17 16:49:05 volprox volumio[951]: wlan0 Interface doesn't support scanning. Jun 17 16:49:05 volprox volumio[951]: info: Cannot use regular scanning, forcing with ap-force Jun 17 16:49:05 volprox sudo[1246]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 17 16:49:05 volprox sudo[1246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:05 volprox sudo[1246]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:05 volprox volumio[951]: command failed: No such device (-19) Jun 17 16:49:05 volprox volumio[951]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 17 16:49:05 volprox volumio[951]: command failed: No such device (-19) Jun 17 16:49:05 volprox volumio[951]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 17 16:49:05 volprox volumio-remote-updater[785]: [2025-06-17 16:49:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750146544 101 Jun 17 16:49:05 volprox volumio[951]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Jun 17 16:49:05 volprox volumio[951]: info: VolumeController:: Volume=100 Mute =false Jun 17 16:49:05 volprox volumio[951]: info: CoreStateMachine::pushState Jun 17 16:49:05 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::volumioPushState Jun 17 16:49:05 volprox volumio[951]: info: CoreStateMachine::updateTrackBlock Jun 17 16:49:05 volprox volumio[951]: info: CorePlayQueue::getTrackBlock Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 16:49:05 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 17 16:49:05 volprox volumio[951]: info: Reloading queue from file Jun 17 16:49:05 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 17 16:49:05 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 17 16:49:05 volprox volumio[951]: info: Asound.conf file unchanged, so no further update is needed Jun 17 16:49:05 volprox volumio[951]: info: Output device has changed, restarting MPD Jun 17 16:49:05 volprox volumio[951]: info: Output device has changed, restarting Shairport Sync Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:05 volprox sudo[1259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 16:49:05 volprox sudo[1259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:05 volprox sudo[1262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 16:49:05 volprox sudo[1262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:05 volprox sudo[1259]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:05 volprox volumio[951]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 16:49:05 volprox volumio[951]: info: ___________ START PLUGINS ___________ Jun 17 16:49:05 volprox volumio[951]: info: ControllerMpd::onStart: Initializing MPD Jun 17 16:49:05 volprox volumio[951]: info: Creating MPD Configuration file Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 16:49:05 volprox volumio[951]: info: [1750146545907] CoreMusicLibrary::Adding element 미디어 서버 Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:05 volprox systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 17 16:49:05 volprox systemd[1]: Starting mpd.service - Music Player Daemon... Jun 17 16:49:05 volprox sudo[1269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:05 volprox sudo[1272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 16:49:05 volprox sudo[1272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:05 volprox sudo[1269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:05 volprox sudo[1269]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:05 volprox systemd[1]: mpd.service: Deactivated successfully. Jun 17 16:49:05 volprox systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 17 16:49:05 volprox systemd[1]: mpd.socket: Deactivated successfully. Jun 17 16:49:05 volprox systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 17 16:49:05 volprox systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 17 16:49:05 volprox systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 17 16:49:05 volprox systemd[1]: Starting mpd.service - Music Player Daemon... Jun 17 16:49:05 volprox volumio[951]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:05 volprox sudo[1281]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 16:49:05 volprox sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 17 16:49:05 volprox volumio[951]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 16:49:05 volprox volumio[951]: info: [1750146545964] CoreMusicLibrary::Adding element Last_100 Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 16:49:05 volprox volumio[951]: info: [1750146545965] CoreMusicLibrary::Adding element Webradio Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:05 volprox sudo[1286]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 17 16:49:05 volprox sudo[1281]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:05 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 16:49:05 volprox volumio[951]: info: Initializing BBC Radios Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 16:49:06 volprox volumio[951]: info: [1750146546022] CoreMusicLibrary::Adding element YouTube Music Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:06 volprox volumio[951]: Cannot find translation for source YouTube Music Jun 17 16:49:06 volprox volumio[951]: info: SleepWakePlugin - onStart Jun 17 16:49:06 volprox volumio[951]: info: SleepWakePlugin - Sleep scheduled in 25913975 milliseconds Jun 17 16:49:06 volprox volumio[951]: info: SleepWakePlugin - Wake scheduled in 47453974 milliseconds Jun 17 16:49:06 volprox volumio[951]: info: AutoStart - onStart Jun 17 16:49:06 volprox volumio[951]: info: [now-playing] ConfigUpdater: config is up to date. Jun 17 16:49:06 volprox volumio[951]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: Asia/Seoul Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 16:49:06 volprox volumio[951]: info: [1750146546049] CoreMusicLibrary::Adding element Randomizer Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:06 volprox volumio[951]: Cannot find translation for source YouTube Music Jun 17 16:49:06 volprox volumio[951]: Cannot find translation for source Randomizer Jun 17 16:49:06 volprox volumio[951]: info: Volumio Calling Home Jun 17 16:49:06 volprox volumio[951]: info: [now-playing] App is listening on port 4004. Jun 17 16:49:06 volprox volumio[951]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 17 16:49:06 volprox volumio[951]: info: CoreStateMachine::setRepeat null single undefined Jun 17 16:49:06 volprox volumio[951]: info: CoreStateMachine::pushState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioPushState Jun 17 16:49:06 volprox volumio[951]: info: CoreStateMachine::setRandom null Jun 17 16:49:06 volprox volumio[951]: info: CoreStateMachine::pushState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioPushState Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70:3000 from 172.30.1.79 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 17 16:49:06 volprox volumio[951]: info: MPD Permissions set Jun 17 16:49:06 volprox volumio[951]: info: MPD Permissions set Jun 17 16:49:06 volprox volumio[951]: info: VolumeController:: Volume=100 Mute =false Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreStateMachine::pushState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioPushState Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 16:49:06 volprox volumio[951]: info: Discovery: Getting this device information Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.29 UA: Mozilla/5.0 (Linux; Android 12; SM-N976N Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70:3000 from 172.30.1.29 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 16:49:06 volprox volumio[951]: info: Discovery: adding 83e5f107-19f4-428e-afde-43003911ea29 Jun 17 16:49:06 volprox volumio[951]: info: Discovery: Found device VolProx Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: Discovery: adding 814b39b3-003f-4f18-a039-3d3b78cba558 Jun 17 16:49:06 volprox volumio[951]: info: Discovery: Found device VolMini Jun 17 16:49:06 volprox volumio[951]: info: Discovery: Connecting to remote: 172.30.1.79 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetVisibleSources Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 16:49:06 volprox volumio[951]: info: Received Get System Info Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 16:49:06 volprox volumio[951]: info: Discovery: Getting this device information Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: Listing playlists Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetQueue Jun 17 16:49:06 volprox volumio[951]: info: CoreStateMachine::getQueue Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getQueue Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetQueue Jun 17 16:49:06 volprox volumio[951]: info: CoreStateMachine::getQueue Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getQueue Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jun 17 16:49:06 volprox volumio[951]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jun 17 16:49:06 volprox volumio[951]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jun 17 16:49:06 volprox volumio[951]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.29 UA: Mozilla/5.0 (Linux; Android 12; SM-N976N Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 17 16:49:06 volprox ntpd[877]: IO: Listen normally on 3 eth0 172.30.1.70:123 Jun 17 16:49:06 volprox ntpd[877]: IO: new interface(s) found: waking up resolver Jun 17 16:49:06 volprox ntpd[877]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:06 volprox ntpd[877]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 17 16:49:06 volprox ntpd[877]: DNS: Pool taking: 194.0.5.123 Jun 17 16:49:06 volprox ntpd[877]: DNS: Pool taking: 106.247.248.106 Jun 17 16:49:06 volprox ntpd[877]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 17 16:49:06 volprox volumio[951]: info: Discovery: this is already registered, 83e5f107-19f4-428e-afde-43003911ea29 Jun 17 16:49:06 volprox volumio[951]: info: Discovery: Found device VolProx Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.29 UA: Mozilla/5.0 (Linux; Android 12; SM-N976N Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jun 17 16:49:06 volprox volumio[951]: info: Starting Shairport Sync Jun 17 16:49:06 volprox volumio[951]: info: Starting Shairport Sync Jun 17 16:49:06 volprox volumio[951]: info: Starting Shairport Sync Jun 17 16:49:06 volprox sudo[1335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 16:49:06 volprox sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:06 volprox sudo[1336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 16:49:06 volprox sudo[1336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:06 volprox sudo[1332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 16:49:06 volprox sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:06 volprox systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 17 16:49:06 volprox systemd[1]: shairport-sync.service: Deactivated successfully. Jun 17 16:49:06 volprox systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 17 16:49:06 volprox systemd[1]: shairport-sync.service: Consumed 1.397s CPU time. Jun 17 16:49:06 volprox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 17 16:49:06 volprox sudo[1335]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:06 volprox sudo[1336]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:06 volprox systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 17 16:49:06 volprox systemd[1]: shairport-sync.service: Deactivated successfully. Jun 17 16:49:06 volprox systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 17 16:49:06 volprox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 17 16:49:06 volprox sudo[1332]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:06 volprox volumio[951]: info: Discovery: Connected to remote: 172.30.1.79 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: Shairport-Sync Started Jun 17 16:49:06 volprox volumio[951]: Error adding Membership: Error: addMembership EINVAL Jun 17 16:49:06 volprox volumio[951]: info: Shairport-Sync Started Jun 17 16:49:06 volprox volumio[951]: info: Shairport-Sync Started Jun 17 16:49:06 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70:3000 from 172.30.1.29 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Jun 17 16:49:06 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:06 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:06 volprox volumio[951]: info: Volumio called home Jun 17 16:49:07 volprox mpd[1287]: 2025-06-17T16:49:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 16:49:07 volprox volumio[951]: info: [yt-cast-receiver] DIAL server listening on port 38098 Jun 17 16:49:07 volprox ntpd[877]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:07 volprox ntpd[877]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 17 16:49:07 volprox ntpd[877]: DNS: Pool taking: 203.32.26.46 Jun 17 16:49:07 volprox ntpd[877]: DNS: Pool taking: 221.151.118.78 Jun 17 16:49:07 volprox ntpd[877]: DNS: Pool taking: 2401:c080:1c00:24a1:5400:5ff:fe04:720 Jun 17 16:49:07 volprox ntpd[877]: DNS: Pool taking: 240b:400d:3:3300:aeda:71da:9779:d4f1 Jun 17 16:49:07 volprox ntpd[877]: DNS: Pool taking: 2001:678:8::123 Jun 17 16:49:07 volprox ntpd[877]: DNS: Pool taking: 2401:c080:1c00:232a:5400:5ff:fe5d:4cee Jun 17 16:49:07 volprox ntpd[877]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 17 16:49:07 volprox systemd[1]: Started mpd.service - Music Player Daemon. Jun 17 16:49:07 volprox sudo[1272]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:07 volprox sudo[1262]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:07 volprox volumio[951]: error: MPD error: The expression evaluated to a falsy value: Jun 17 16:49:07 volprox volumio[951]: assert.ok(self.idling) Jun 17 16:49:07 volprox volumio[951]: error: The expression evaluated to a falsy value: Jun 17 16:49:07 volprox volumio[951]: assert.ok(self.idling) Jun 17 16:49:07 volprox volumio[951]: error: updateQueue error: null Jun 17 16:49:07 volprox volumio[951]: info: MPD running with PID1287 Jun 17 16:49:07 volprox volumio[951]: ,establishing connection Jun 17 16:49:07 volprox volumio[951]: error: updateQueue error: null Jun 17 16:49:07 volprox volumio[951]: info: Completed starting Core Plugins Jun 17 16:49:07 volprox volumio[951]: info: ------------------------------------------- Jun 17 16:49:07 volprox volumio[951]: info: ----- MyVolumio plugins startup ---- Jun 17 16:49:07 volprox volumio[951]: info: ------------------------------------------- Jun 17 16:49:07 volprox volumio[951]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 17 16:49:08 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70:3000 from 172.30.1.29 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Jun 17 16:49:08 volprox ntpd[877]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:08 volprox ntpd[877]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 17 16:49:08 volprox ntpd[877]: DNS: Pool taking: 175.195.167.194 Jun 17 16:49:08 volprox ntpd[877]: DNS: Pool taking: 121.174.142.82 Jun 17 16:49:08 volprox ntpd[877]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 17 16:49:08 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70:3000 from 172.30.1.29 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Jun 17 16:49:09 volprox ntpd[877]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 17 16:49:09 volprox ntpd[877]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 17 16:49:09 volprox ntpd[877]: DNS: Pool taking: 158.247.202.103 Jun 17 16:49:09 volprox ntpd[877]: DNS: Pool taking: 121.174.142.81 Jun 17 16:49:09 volprox ntpd[877]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 17 16:49:10 volprox volumio[951]: verbose: New Socket.io Connection to 172.30.1.70 from 172.30.1.29 UA: Mozilla/5.0 (Linux; Android 12; SM-N976N Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::volumioGetVisibleSources Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:10 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 16:49:10 volprox volumio[951]: info: Received Get System Info Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 16:49:10 volprox volumio[951]: info: Discovery: Getting this device information Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:10 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::volumioGetState Jun 17 16:49:10 volprox volumio[951]: info: CorePlayQueue::getTrack 0 Jun 17 16:49:10 volprox volumio[951]: info: Listing playlists Jun 17 16:49:10 volprox volumio[951]: info: CoreCommandRouter::volumioGetQueue Jun 17 16:49:10 volprox volumio[951]: info: CoreStateMachine::getQueue Jun 17 16:49:10 volprox volumio[951]: info: CorePlayQueue::getQueue Jun 17 16:49:10 volprox sudo[1369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kasiri33,password=Tw22tyim!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //172.30.1.60/music /mnt/NAS/VolProx Jun 17 16:49:10 volprox sudo[1369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:10 volprox kernel: CIFS: Attempting to mount //172.30.1.60/music Jun 17 16:49:10 volprox sudo[1369]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:11 volprox volumio[951]: info: Volumio Network Manager: Network status updated: 1 Jun 17 16:49:11 volprox sudo[1410]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 16:49:11 volprox sudo[1410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:11 volprox sudo[1410]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:11 volprox sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 16:49:11 volprox sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:11 volprox sudo[1417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 17 16:49:11 volprox sudo[1417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:11 volprox sudo[1412]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:11 volprox systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 17 16:49:11 volprox sudo[1417]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:11 volprox volumio[951]: info: Upmpdcli Daemon Started Jun 17 16:49:12 volprox volumio[951]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 17 16:49:12 volprox ntpd[877]: CLOCK: time stepped by -0.411180 Jun 17 16:49:12 volprox ntpd[877]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jun 17 16:49:13 volprox upmpdcli[1439]: writing RSA key Jun 17 16:49:16 volprox volumio[951]: Cannot compose Albumart path Jun 17 16:49:16 volprox volumio[951]: Cannot compose Albumart path Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 17 16:49:17 volprox volumio[951]: info: Adding plugin bluetooth to MyMusic Plugins Jun 17 16:49:17 volprox volumio[951]: info: Adding plugin multiroom to MyMusic Plugins Jun 17 16:49:17 volprox volumio[951]: info: Adding plugin metavolumio to MyMusic Plugins Jun 17 16:49:17 volprox volumio[951]: info: Adding plugin cd_controller to MyMusic Plugins Jun 17 16:49:17 volprox volumio[951]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 17 16:49:17 volprox volumio[951]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 17 16:49:17 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:17 volprox volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 16:49:17 volprox volumio[951]: info: Starting MyVolumio Remote Streaming Endpoints Jun 17 16:49:17 volprox volumio[951]: info: MyVolumio login type: Token Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 17 16:49:17 volprox volumio[951]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 17 16:49:17 volprox volumio[951]: info: Streaming services startup Jun 17 16:49:17 volprox volumio[951]: info: Starting Streaming Daemon Jun 17 16:49:18 volprox volumio[951]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 17 16:49:18 volprox sudo[1450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 17 16:49:18 volprox sudo[1450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:18 volprox sudo[1450]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:18 volprox volumio[951]: error: Cannot start Volumio Streaming Daemon Jun 17 16:49:18 volprox volumio[951]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 17 16:49:18 volprox volumio[951]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 17 16:49:18 volprox volumio[951]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 17 16:49:19 volprox volumio[951]: info: MyVolumio token set successfully Jun 17 16:49:19 volprox volumio[951]: info: MYVOLUMIO: Adding device Jun 17 16:49:19 volprox volumio[951]: info: MYVOLUMIO: Evaluating Server Jun 17 16:49:19 volprox volumio[951]: info: MyVolumio status changed Jun 17 16:49:19 volprox volumio[951]: info: Streaming services startup Jun 17 16:49:19 volprox volumio[951]: info: Starting Streaming Daemon Jun 17 16:49:19 volprox volumio[951]: info: Removing browser output: myVolumio user plan is not superstar Jun 17 16:49:19 volprox volumio[951]: info: Removing audio output: Jun 17 16:49:19 volprox volumio[951]: info: Stoppping Tunnel 1 Jun 17 16:49:19 volprox sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 17 16:49:19 volprox sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:19 volprox sudo[1475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 17 16:49:19 volprox sudo[1475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 16:49:19 volprox systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox sudo[1475]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:19 volprox volumio[951]: error: Cannot start Volumio Streaming Daemon Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox volumio[951]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 17 16:49:19 volprox volumio[951]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox setdatetime-helper.sh[1480]: Time is not synchronized. Attempting to sync... Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 17 16:49:19 volprox sudo[1477]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:19 volprox volumio[951]: info: Remote SSH Stopped Jun 17 16:49:19 volprox sudo[1488]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 17 Jun 2025 07:49:19 GMT#015' Jun 17 16:49:19 volprox sudo[1488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 17 16:49:19 volprox setdatetime-helper.sh[1489]: Tue Jun 17 16:49:19 KST 2025 Jun 17 16:49:19 volprox systemd-journald[484]: Time jumped backwards, rotating. Jun 17 16:49:19 volprox setdatetime-helper.sh[1480]: Time synchronized successfully. Jun 17 16:49:19 volprox sudo[1488]: pam_unix(sudo:session): session closed for user root Jun 17 16:49:19 volprox systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 17 16:49:19 volprox systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 17 16:49:19 volprox volumio[951]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Jun 17 16:49:19 volprox volumio[951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 16:49:19 volprox volumio[951]: Error: Unable to resolve or reject the same promise twice Jun 17 16:49:19 volprox volumio[951]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 17 16:49:19 volprox volumio[951]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 17 16:49:19 volprox volumio[951]: at Socket.emit (node:events:514:28) Jun 17 16:49:19 volprox volumio[951]: at addChunk (node:internal/streams/readable:343:12) Jun 17 16:49:19 volprox volumio[951]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 17 16:49:19 volprox volumio[951]: at Readable.push (node:internal/streams/readable:253:10) Jun 17 16:49:19 volprox volumio[951]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 17 16:49:19 volprox volumio[951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 16:49:19 volprox sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-17 16:48' Jun 17 16:49:19 volprox sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:47:19 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f0f6a90610a0a39b2758a5b3958219e6"