-- Logs begin at Mon 2025-03-17 18:06:24 UTC, end at Mon 2025-03-17 18:06:42 UTC. -- Mar 17 18:06:24 volumio kernel: Linux version 5.10.176-volumio (x86@busteros) (gcc (Debian 8.3.0-6) 8.3.0, GNU ld (GNU Binutils for Debian) 2.31.1) #1 SMP Thu Mar 30 17:57:37 CEST 2023 Mar 17 18:06:24 volumio kernel: Command line: BOOT_IMAGE=vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=995b3bef-0123-488a-8237-3568c5f00abb bootpart=UUID=5A07-DE33 datapart=UUID=401d59b5-fe69-47e1-b9dd-4c8e38d89f93 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 initrd=volumio.initrd Mar 17 18:06:24 volumio kernel: KERNEL supported cpus: Mar 17 18:06:24 volumio kernel: Intel GenuineIntel Mar 17 18:06:24 volumio kernel: AMD AuthenticAMD Mar 17 18:06:24 volumio kernel: Hygon HygonGenuine Mar 17 18:06:24 volumio kernel: Centaur CentaurHauls Mar 17 18:06:24 volumio kernel: zhaoxin Shanghai Mar 17 18:06:24 volumio kernel: x86/fpu: x87 FPU will use FXSAVE Mar 17 18:06:24 volumio kernel: BIOS-provided physical RAM map: Mar 17 18:06:24 volumio kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Mar 17 18:06:24 volumio kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Mar 17 18:06:24 volumio kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Mar 17 18:06:24 volumio kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffddfff] usable Mar 17 18:06:24 volumio kernel: BIOS-e820: [mem 0x000000007ffde000-0x000000007fffffff] reserved Mar 17 18:06:24 volumio kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 17 18:06:24 volumio kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Mar 17 18:06:24 volumio kernel: NX (Execute Disable) protection: active Mar 17 18:06:24 volumio kernel: SMBIOS 2.8 present. Mar 17 18:06:24 volumio kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 Mar 17 18:06:24 volumio kernel: Hypervisor detected: KVM Mar 17 18:06:24 volumio kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Mar 17 18:06:24 volumio kernel: kvm-clock: cpu 0, msr 25b9001, primary cpu clock Mar 17 18:06:24 volumio kernel: kvm-clock: using sched offset of 692505996259513 cycles Mar 17 18:06:24 volumio kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 17 18:06:24 volumio kernel: tsc: Detected 2496.000 MHz processor Mar 17 18:06:24 volumio kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 17 18:06:24 volumio kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 17 18:06:24 volumio kernel: last_pfn = 0x7ffde max_arch_pfn = 0x400000000 Mar 17 18:06:24 volumio kernel: MTRR default type: write-back Mar 17 18:06:24 volumio kernel: MTRR fixed ranges enabled: Mar 17 18:06:24 volumio kernel: 00000-9FFFF write-back Mar 17 18:06:24 volumio kernel: A0000-BFFFF uncachable Mar 17 18:06:24 volumio kernel: C0000-FFFFF write-protect Mar 17 18:06:24 volumio kernel: MTRR variable ranges enabled: Mar 17 18:06:24 volumio kernel: 0 base 0080000000 mask FF80000000 uncachable Mar 17 18:06:24 volumio kernel: 1 disabled Mar 17 18:06:24 volumio kernel: 2 disabled Mar 17 18:06:24 volumio kernel: 3 disabled Mar 17 18:06:24 volumio kernel: 4 disabled Mar 17 18:06:24 volumio kernel: 5 disabled Mar 17 18:06:24 volumio kernel: 6 disabled Mar 17 18:06:24 volumio kernel: 7 disabled Mar 17 18:06:24 volumio kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 17 18:06:24 volumio kernel: found SMP MP-table at [mem 0x000f5440-0x000f544f] Mar 17 18:06:24 volumio kernel: RAMDISK: [mem 0x7ccba000-0x7ffddfff] Mar 17 18:06:24 volumio kernel: ACPI: Early table checksum verification disabled Mar 17 18:06:24 volumio kernel: ACPI: RSDP 0x00000000000F5260 000014 (v00 BOCHS ) Mar 17 18:06:24 volumio kernel: ACPI: RSDT 0x000000007FFE2F10 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:06:24 volumio kernel: ACPI: FACP 0x000000007FFE2CFA 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:06:24 volumio kernel: ACPI: DSDT 0x000000007FFDF040 003CBA (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:06:24 volumio kernel: ACPI: FACS 0x000000007FFDF000 000040 Mar 17 18:06:24 volumio kernel: ACPI: APIC 0x000000007FFE2D6E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:06:24 volumio kernel: ACPI: SSDT 0x000000007FFE2DE6 0000CA (v01 BOCHS VMGENID 00000001 BXPC 00000001) Mar 17 18:06:24 volumio kernel: ACPI: HPET 0x000000007FFE2EB0 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:06:24 volumio kernel: ACPI: WAET 0x000000007FFE2EE8 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:06:24 volumio kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe2cfa-0x7ffe2d6d] Mar 17 18:06:24 volumio kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffdf040-0x7ffe2cf9] Mar 17 18:06:24 volumio kernel: ACPI: Reserving FACS table memory at [mem 0x7ffdf000-0x7ffdf03f] Mar 17 18:06:24 volumio kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe2d6e-0x7ffe2de5] Mar 17 18:06:24 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0x7ffe2de6-0x7ffe2eaf] Mar 17 18:06:24 volumio kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe2eb0-0x7ffe2ee7] Mar 17 18:06:24 volumio kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe2ee8-0x7ffe2f0f] Mar 17 18:06:24 volumio kernel: ACPI: Local APIC address 0xfee00000 Mar 17 18:06:24 volumio kernel: Zone ranges: Mar 17 18:06:24 volumio kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 17 18:06:24 volumio kernel: DMA32 [mem 0x0000000001000000-0x000000007ffddfff] Mar 17 18:06:24 volumio kernel: Normal empty Mar 17 18:06:24 volumio kernel: Movable zone start for each node Mar 17 18:06:24 volumio kernel: Early memory node ranges Mar 17 18:06:24 volumio kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Mar 17 18:06:24 volumio kernel: node 0: [mem 0x0000000000100000-0x000000007ffddfff] Mar 17 18:06:24 volumio kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffddfff] Mar 17 18:06:24 volumio kernel: On node 0 totalpages: 524156 Mar 17 18:06:24 volumio kernel: DMA zone: 64 pages used for memmap Mar 17 18:06:24 volumio kernel: DMA zone: 21 pages reserved Mar 17 18:06:24 volumio kernel: DMA zone: 3998 pages, LIFO batch:0 Mar 17 18:06:24 volumio kernel: DMA32 zone: 8128 pages used for memmap Mar 17 18:06:24 volumio kernel: DMA32 zone: 520158 pages, LIFO batch:63 Mar 17 18:06:24 volumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 17 18:06:24 volumio kernel: On node 0, zone DMA: 97 pages in unavailable ranges Mar 17 18:06:24 volumio kernel: On node 0, zone DMA32: 34 pages in unavailable ranges Mar 17 18:06:24 volumio kernel: ACPI: PM-Timer IO Port: 0x608 Mar 17 18:06:24 volumio kernel: ACPI: Local APIC address 0xfee00000 Mar 17 18:06:24 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Mar 17 18:06:24 volumio kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Mar 17 18:06:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Mar 17 18:06:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Mar 17 18:06:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 17 18:06:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Mar 17 18:06:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Mar 17 18:06:24 volumio kernel: ACPI: IRQ0 used by override. Mar 17 18:06:24 volumio kernel: ACPI: IRQ5 used by override. Mar 17 18:06:24 volumio kernel: ACPI: IRQ9 used by override. Mar 17 18:06:24 volumio kernel: ACPI: IRQ10 used by override. Mar 17 18:06:24 volumio kernel: ACPI: IRQ11 used by override. Mar 17 18:06:24 volumio kernel: Using ACPI (MADT) for SMP configuration information Mar 17 18:06:24 volumio kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Mar 17 18:06:24 volumio kernel: smpboot: Allowing 1 CPUs, 0 hotplug CPUs Mar 17 18:06:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Mar 17 18:06:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] Mar 17 18:06:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] Mar 17 18:06:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] Mar 17 18:06:24 volumio kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Mar 17 18:06:24 volumio kernel: Booting paravirtualized kernel on KVM Mar 17 18:06:24 volumio kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Mar 17 18:06:24 volumio kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1 Mar 17 18:06:24 volumio kernel: percpu: Embedded 57 pages/cpu s194328 r8192 d30952 u2097152 Mar 17 18:06:24 volumio kernel: pcpu-alloc: s194328 r8192 d30952 u2097152 alloc=1*2097152 Mar 17 18:06:24 volumio kernel: pcpu-alloc: [0] 0 Mar 17 18:06:24 volumio kernel: kvm-guest: stealtime: cpu 0, msr 7aa1b600 Mar 17 18:06:24 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 515943 Mar 17 18:06:24 volumio kernel: Kernel command line: BOOT_IMAGE=vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=995b3bef-0123-488a-8237-3568c5f00abb bootpart=UUID=5A07-DE33 datapart=UUID=401d59b5-fe69-47e1-b9dd-4c8e38d89f93 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 initrd=volumio.initrd Mar 17 18:06:24 volumio kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 18:06:24 volumio kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 17 18:06:24 volumio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 18:06:24 volumio kernel: Memory: 1983192K/2096624K available (12296K kernel code, 1777K rwdata, 3096K rodata, 1820K init, 2436K bss, 113176K reserved, 0K cma-reserved) Mar 17 18:06:24 volumio kernel: Kernel/User page tables isolation: enabled Mar 17 18:06:24 volumio kernel: ftrace: allocating 37338 entries in 146 pages Mar 17 18:06:24 volumio kernel: ftrace: allocated 146 pages with 3 groups Mar 17 18:06:24 volumio kernel: rcu: Hierarchical RCU implementation. Mar 17 18:06:24 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1. Mar 17 18:06:24 volumio kernel: Rude variant of Tasks RCU enabled. Mar 17 18:06:24 volumio kernel: Tracing variant of Tasks RCU enabled. Mar 17 18:06:24 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Mar 17 18:06:24 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 Mar 17 18:06:24 volumio kernel: NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16 Mar 17 18:06:24 volumio kernel: Console: colour VGA+ 80x25 Mar 17 18:06:24 volumio kernel: printk: console [tty0] enabled Mar 17 18:06:24 volumio kernel: ACPI: Core revision 20200925 Mar 17 18:06:24 volumio kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Mar 17 18:06:24 volumio kernel: APIC: Switch to symmetric I/O mode setup Mar 17 18:06:24 volumio kernel: x2apic enabled Mar 17 18:06:24 volumio kernel: Switched APIC routing to physical x2apic. Mar 17 18:06:24 volumio kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Mar 17 18:06:24 volumio kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x23fa772cf26, max_idle_ns: 440795269835 ns Mar 17 18:06:24 volumio kernel: Calibrating delay loop (skipped) preset value.. 4992.00 BogoMIPS (lpj=9984000) Mar 17 18:06:24 volumio kernel: pid_max: default: 32768 minimum: 301 Mar 17 18:06:24 volumio kernel: LSM: Security Framework initializing Mar 17 18:06:24 volumio kernel: Yama: becoming mindful. Mar 17 18:06:24 volumio kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Mar 17 18:06:24 volumio kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Mar 17 18:06:24 volumio kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Mar 17 18:06:24 volumio kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Mar 17 18:06:24 volumio kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 17 18:06:24 volumio kernel: Spectre V2 : Mitigation: Retpolines Mar 17 18:06:24 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 17 18:06:24 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 17 18:06:24 volumio kernel: Speculative Store Bypass: Vulnerable Mar 17 18:06:24 volumio kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Mar 17 18:06:24 volumio kernel: MMIO Stale Data: Unknown: No mitigations Mar 17 18:06:24 volumio kernel: Freeing SMP alternatives memory: 32K Mar 17 18:06:24 volumio kernel: smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0xf, model: 0x6b, stepping: 0x1) Mar 17 18:06:24 volumio kernel: Performance Events: unsupported Netburst CPU model 107 no PMU driver, software events only. Mar 17 18:06:24 volumio kernel: rcu: Hierarchical SRCU implementation. Mar 17 18:06:24 volumio kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 17 18:06:24 volumio kernel: smp: Bringing up secondary CPUs ... Mar 17 18:06:24 volumio kernel: smp: Brought up 1 node, 1 CPU Mar 17 18:06:24 volumio kernel: smpboot: Max logical packages: 1 Mar 17 18:06:24 volumio kernel: smpboot: Total of 1 processors activated (4992.00 BogoMIPS) Mar 17 18:06:24 volumio kernel: devtmpfs: initialized Mar 17 18:06:24 volumio kernel: x86/mm: Memory block size: 128MB Mar 17 18:06:24 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Mar 17 18:06:24 volumio kernel: futex hash table entries: 256 (order: 2, 16384 bytes, linear) Mar 17 18:06:24 volumio kernel: pinctrl core: initialized pinctrl subsystem Mar 17 18:06:24 volumio kernel: NET: Registered protocol family 16 Mar 17 18:06:24 volumio kernel: audit: initializing netlink subsys (disabled) Mar 17 18:06:24 volumio kernel: thermal_sys: Registered thermal governor 'fair_share' Mar 17 18:06:24 volumio kernel: thermal_sys: Registered thermal governor 'bang_bang' Mar 17 18:06:24 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 18:06:24 volumio kernel: thermal_sys: Registered thermal governor 'user_space' Mar 17 18:06:24 volumio kernel: EISA bus registered Mar 17 18:06:24 volumio kernel: cpuidle: using governor ladder Mar 17 18:06:24 volumio kernel: cpuidle: using governor menu Mar 17 18:06:24 volumio kernel: ACPI: bus type PCI registered Mar 17 18:06:24 volumio kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 18:06:24 volumio kernel: PCI: Using configuration type 1 for base access Mar 17 18:06:24 volumio kernel: Kprobes globally optimized Mar 17 18:06:24 volumio kernel: audit: type=2000 audit(1742234764.828:1): state=initialized audit_enabled=0 res=1 Mar 17 18:06:24 volumio kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 18:06:24 volumio kernel: ACPI: Added _OSI(Module Device) Mar 17 18:06:24 volumio kernel: ACPI: Added _OSI(Processor Device) Mar 17 18:06:24 volumio kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 18:06:24 volumio kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 18:06:24 volumio kernel: ACPI: Added _OSI(Linux-Dell-Video) Mar 17 18:06:24 volumio kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Mar 17 18:06:24 volumio kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Mar 17 18:06:24 volumio kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded Mar 17 18:06:24 volumio kernel: ACPI: Interpreter enabled Mar 17 18:06:24 volumio kernel: ACPI: (supports S0 S3 S4 S5) Mar 17 18:06:24 volumio kernel: ACPI: Using IOAPIC for interrupt routing Mar 17 18:06:24 volumio kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 17 18:06:24 volumio kernel: ACPI: Enabled 3 GPEs in block 00 to 0F Mar 17 18:06:24 volumio kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 17 18:06:24 volumio kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Mar 17 18:06:24 volumio kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. Mar 17 18:06:24 volumio kernel: acpiphp: Slot [3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [9] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [18] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [6] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [7] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [8] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [10] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [11] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [12] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [13] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [14] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [15] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [16] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [17] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [19] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [20] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [21] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [22] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [23] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [24] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [25] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [26] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [27] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [28] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [29] registered Mar 17 18:06:24 volumio kernel: PCI host bridge to bus 0000:00 Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 17 18:06:24 volumio kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Mar 17 18:06:24 volumio kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Mar 17 18:06:24 volumio kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Mar 17 18:06:24 volumio kernel: pci 0000:00:01.1: reg 0x20: [io 0xf0e0-0xf0ef] Mar 17 18:06:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Mar 17 18:06:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Mar 17 18:06:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Mar 17 18:06:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Mar 17 18:06:24 volumio kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Mar 17 18:06:24 volumio kernel: pci 0000:00:01.2: reg 0x20: [io 0xf080-0xf09f] Mar 17 18:06:24 volumio kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Mar 17 18:06:24 volumio kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Mar 17 18:06:24 volumio kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000 Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: reg 0x10: [mem 0xc0000000-0xdfffffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: reg 0x14: [mem 0xe0000000-0xefffffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: reg 0x18: [mem 0xf0650000-0xf0651fff] Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: reg 0x1c: [io 0xf0a0-0xf0bf] Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: reg 0x30: [mem 0xf0640000-0xf064ffff pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:03.0: [1af4:1002] type 00 class 0x00ff00 Mar 17 18:06:24 volumio kernel: pci 0000:00:03.0: reg 0x10: [io 0xf000-0xf03f] Mar 17 18:06:24 volumio kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfea00000-0xfea03fff 64bit pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: [1b36:0001] type 01 class 0x060400 Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: reg 0x10: [mem 0xf0652000-0xf06520ff 64bit] Mar 17 18:06:24 volumio kernel: pci 0000:00:09.0: [1af4:1003] type 00 class 0x078000 Mar 17 18:06:24 volumio kernel: pci 0000:00:09.0: reg 0x10: [io 0xf040-0xf07f] Mar 17 18:06:24 volumio kernel: pci 0000:00:09.0: reg 0x14: [mem 0xf0653000-0xf0653fff] Mar 17 18:06:24 volumio kernel: pci 0000:00:09.0: reg 0x20: [mem 0xfea04000-0xfea07fff 64bit pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:12.0: [1af4:1000] type 00 class 0x020000 Mar 17 18:06:24 volumio kernel: pci 0000:00:12.0: reg 0x10: [io 0xf0c0-0xf0df] Mar 17 18:06:24 volumio kernel: pci 0000:00:12.0: reg 0x14: [mem 0xf0654000-0xf0654fff] Mar 17 18:06:24 volumio kernel: pci 0000:00:12.0: reg 0x20: [mem 0xfea08000-0xfea0bfff 64bit pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:12.0: reg 0x30: [mem 0xf0600000-0xf063ffff pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: [1b36:0001] type 01 class 0x060400 Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: reg 0x10: [mem 0xf0655000-0xf06550ff 64bit] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: [1b36:0001] type 01 class 0x060400 Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: reg 0x10: [mem 0xf0656000-0xf06560ff 64bit] Mar 17 18:06:24 volumio kernel: pci_bus 0000:01: extended config space not accessible Mar 17 18:06:24 volumio kernel: acpiphp: Slot [0] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [1-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [3-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [4-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [5] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [6-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [7-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [8-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [9-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [10-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [11-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [12-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [13-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [14-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [15-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [16-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [17-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [18-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [19-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [20-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [21-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [22-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [23-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [24-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [25-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [26-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [27-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [28-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [29-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [30] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [31] registered Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xf0400000-0xf05fffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci_bus 0000:02: extended config space not accessible Mar 17 18:06:24 volumio kernel: acpiphp: Slot [0-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [1-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [2-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [3-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [4-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [5-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [6-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [7-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [8-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [9-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [10-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [11-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [12-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [13-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [14-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [15-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [16-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [17-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [18-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [19-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [20-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [21-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [22-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [23-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [24-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [25-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [26-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [27-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [28-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [29-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [30-2] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [31-2] registered Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xf0200000-0xf03fffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci_bus 0000:03: extended config space not accessible Mar 17 18:06:24 volumio kernel: acpiphp: Slot [12-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [0-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [1-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [2-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [3-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [4-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [5-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [6-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [7-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [8-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [9-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [10-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [11-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [13-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [14-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [15-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [16-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [17-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [18-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [19-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [20-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [21-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [22-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [23-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [24-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [25-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [26-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [27-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [28-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [29-4] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [30-3] registered Mar 17 18:06:24 volumio kernel: acpiphp: Slot [31-3] registered Mar 17 18:06:24 volumio kernel: pci 0000:03:0c.0: [8086:293e] type 00 class 0x040300 Mar 17 18:06:24 volumio kernel: pci 0000:03:0c.0: reg 0x10: [mem 0xf0000000-0xf0003fff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xf0000000-0xf01fffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: on NUMA node 0 Mar 17 18:06:24 volumio kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) Mar 17 18:06:24 volumio kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) Mar 17 18:06:24 volumio kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) Mar 17 18:06:24 volumio kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) Mar 17 18:06:24 volumio kernel: ACPI: PCI Interrupt Link [LNKS] (IRQs *9) Mar 17 18:06:24 volumio kernel: iommu: Default domain type: Translated Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: vgaarb: bridge control possible Mar 17 18:06:24 volumio kernel: vgaarb: loaded Mar 17 18:06:24 volumio kernel: SCSI subsystem initialized Mar 17 18:06:24 volumio kernel: libata version 3.00 loaded. Mar 17 18:06:24 volumio kernel: ACPI: bus type USB registered Mar 17 18:06:24 volumio kernel: usbcore: registered new interface driver usbfs Mar 17 18:06:24 volumio kernel: usbcore: registered new interface driver hub Mar 17 18:06:24 volumio kernel: usbcore: registered new device driver usb Mar 17 18:06:24 volumio kernel: PCI: Using ACPI for IRQ routing Mar 17 18:06:24 volumio kernel: PCI: pci_cache_line_size set to 64 bytes Mar 17 18:06:24 volumio kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Mar 17 18:06:24 volumio kernel: e820: reserve RAM buffer [mem 0x7ffde000-0x7fffffff] Mar 17 18:06:24 volumio kernel: hpet: 3 channels of 0 reserved for per-cpu timers Mar 17 18:06:24 volumio kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Mar 17 18:06:24 volumio kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Mar 17 18:06:24 volumio kernel: clocksource: Switched to clocksource kvm-clock Mar 17 18:06:24 volumio kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 18:06:24 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 18:06:24 volumio kernel: pnp: PnP ACPI init Mar 17 18:06:24 volumio kernel: pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active) Mar 17 18:06:24 volumio kernel: pnp 00:01: Plug and Play ACPI device, IDs PNP0f13 (active) Mar 17 18:06:24 volumio kernel: pnp 00:02: [dma 2] Mar 17 18:06:24 volumio kernel: pnp 00:02: Plug and Play ACPI device, IDs PNP0700 (active) Mar 17 18:06:24 volumio kernel: pnp 00:03: Plug and Play ACPI device, IDs PNP0b00 (active) Mar 17 18:06:24 volumio kernel: pnp: PnP ACPI: found 4 devices Mar 17 18:06:24 volumio kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 17 18:06:24 volumio kernel: NET: Registered protocol family 2 Mar 17 18:06:24 volumio kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 17 18:06:24 volumio kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Mar 17 18:06:24 volumio kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Mar 17 18:06:24 volumio kernel: TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear) Mar 17 18:06:24 volumio kernel: TCP: Hash tables configured (established 16384 bind 16384) Mar 17 18:06:24 volumio kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Mar 17 18:06:24 volumio kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Mar 17 18:06:24 volumio kernel: NET: Registered protocol family 1 Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xf0400000-0xf05fffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xf0200000-0xf03fffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xf0000000-0xf01fffff] Mar 17 18:06:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Mar 17 18:06:24 volumio kernel: pci_bus 0000:01: resource 0 [io 0xe000-0xefff] Mar 17 18:06:24 volumio kernel: pci_bus 0000:01: resource 1 [mem 0xf0400000-0xf05fffff] Mar 17 18:06:24 volumio kernel: pci_bus 0000:01: resource 2 [mem 0xfe800000-0xfe9fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci_bus 0000:02: resource 0 [io 0xd000-0xdfff] Mar 17 18:06:24 volumio kernel: pci_bus 0000:02: resource 1 [mem 0xf0200000-0xf03fffff] Mar 17 18:06:24 volumio kernel: pci_bus 0000:02: resource 2 [mem 0xfe600000-0xfe7fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci_bus 0000:03: resource 0 [io 0xc000-0xcfff] Mar 17 18:06:24 volumio kernel: pci_bus 0000:03: resource 1 [mem 0xf0000000-0xf01fffff] Mar 17 18:06:24 volumio kernel: pci_bus 0000:03: resource 2 [mem 0xfe400000-0xfe5fffff 64bit pref] Mar 17 18:06:24 volumio kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Mar 17 18:06:24 volumio kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Mar 17 18:06:24 volumio kernel: pci 0000:00:01.0: Activating ISA DMA hang workarounds Mar 17 18:06:24 volumio kernel: PCI Interrupt Link [LNKD] enabled at IRQ 11 Mar 17 18:06:24 volumio kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x630 took 19142 usecs Mar 17 18:06:24 volumio kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Mar 17 18:06:24 volumio kernel: PCI: CLS 0 bytes, default 64 Mar 17 18:06:24 volumio kernel: Trying to unpack rootfs image as initramfs... Mar 17 18:06:24 volumio kernel: Freeing initrd memory: 52368K Mar 17 18:06:24 volumio kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x23fa772cf26, max_idle_ns: 440795269835 ns Mar 17 18:06:24 volumio kernel: Initialise system trusted keyrings Mar 17 18:06:24 volumio kernel: workingset: timestamp_bits=46 max_order=19 bucket_order=0 Mar 17 18:06:24 volumio kernel: zbud: loaded Mar 17 18:06:24 volumio kernel: Key type asymmetric registered Mar 17 18:06:24 volumio kernel: Asymmetric key parser 'x509' registered Mar 17 18:06:24 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Mar 17 18:06:24 volumio kernel: io scheduler mq-deadline registered Mar 17 18:06:24 volumio kernel: io scheduler kyber registered Mar 17 18:06:24 volumio kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.S28_) Mar 17 18:06:24 volumio kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Mar 17 18:06:24 volumio kernel: shpchp 0000:00:05.0: Cannot get control of SHPC hotplug Mar 17 18:06:24 volumio kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF0_) Mar 17 18:06:24 volumio kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Mar 17 18:06:24 volumio kernel: shpchp 0000:00:1e.0: Cannot get control of SHPC hotplug Mar 17 18:06:24 volumio kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF8_) Mar 17 18:06:24 volumio kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Mar 17 18:06:24 volumio kernel: shpchp 0000:00:1f.0: Cannot get control of SHPC hotplug Mar 17 18:06:24 volumio kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Mar 17 18:06:24 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Mar 17 18:06:24 volumio kernel: ACPI: Power Button [PWRF] Mar 17 18:06:24 volumio kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Mar 17 18:06:24 volumio kernel: Linux agpgart interface v0.103 Mar 17 18:06:24 volumio kernel: Uniform Multi-Platform E-IDE driver Mar 17 18:06:24 volumio kernel: ide-gd driver 1.18 Mar 17 18:06:24 volumio kernel: ata_piix 0000:00:01.1: version 2.13 Mar 17 18:06:24 volumio kernel: scsi host0: ata_piix Mar 17 18:06:24 volumio kernel: scsi host1: ata_piix Mar 17 18:06:24 volumio kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xf0e0 irq 14 Mar 17 18:06:24 volumio kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xf0e8 irq 15 Mar 17 18:06:24 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Mar 17 18:06:24 volumio kernel: ehci-pci: EHCI PCI platform driver Mar 17 18:06:24 volumio kernel: ehci-platform: EHCI generic platform driver Mar 17 18:06:24 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Mar 17 18:06:24 volumio kernel: ohci-pci: OHCI PCI platform driver Mar 17 18:06:24 volumio kernel: ohci-platform: OHCI generic platform driver Mar 17 18:06:24 volumio kernel: uhci_hcd: USB Universal Host Controller Interface driver Mar 17 18:06:24 volumio kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Mar 17 18:06:24 volumio kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Mar 17 18:06:24 volumio kernel: uhci_hcd 0000:00:01.2: detected 2 ports Mar 17 18:06:24 volumio kernel: uhci_hcd 0000:00:01.2: irq 11, io base 0x0000f080 Mar 17 18:06:24 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.10 Mar 17 18:06:24 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 17 18:06:24 volumio kernel: usb usb1: Product: UHCI Host Controller Mar 17 18:06:24 volumio kernel: usb usb1: Manufacturer: Linux 5.10.176-volumio uhci_hcd Mar 17 18:06:24 volumio kernel: usb usb1: SerialNumber: 0000:00:01.2 Mar 17 18:06:24 volumio kernel: hub 1-0:1.0: USB hub found Mar 17 18:06:24 volumio kernel: hub 1-0:1.0: 2 ports detected Mar 17 18:06:24 volumio kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Mar 17 18:06:24 volumio kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Mar 17 18:06:24 volumio kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Mar 17 18:06:24 volumio kernel: mousedev: PS/2 mouse device common for all mice Mar 17 18:06:24 volumio kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 Mar 17 18:06:24 volumio kernel: rtc_cmos 00:03: RTC can wake from S4 Mar 17 18:06:24 volumio kernel: rtc_cmos 00:03: registered as rtc0 Mar 17 18:06:24 volumio kernel: rtc_cmos 00:03: setting system clock to 2025-03-17T18:06:06 UTC (1742234766) Mar 17 18:06:24 volumio kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Mar 17 18:06:24 volumio kernel: i2c /dev entries driver Mar 17 18:06:24 volumio kernel: platform eisa.0: Probing EISA bus 0 Mar 17 18:06:24 volumio kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Mar 17 18:06:24 volumio kernel: intel_pstate: CPU model not supported Mar 17 18:06:24 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 17 18:06:24 volumio kernel: NET: Registered protocol family 10 Mar 17 18:06:24 volumio kernel: Segment Routing with IPv6 Mar 17 18:06:24 volumio kernel: mip6: Mobile IPv6 Mar 17 18:06:24 volumio kernel: NET: Registered protocol family 17 Mar 17 18:06:24 volumio kernel: mpls_gso: MPLS GSO support Mar 17 18:06:24 volumio kernel: IPI shorthand broadcast: enabled Mar 17 18:06:24 volumio kernel: sched_clock: Marking stable (754741085, 2845841)->(766961278, -9374352) Mar 17 18:06:24 volumio kernel: registered taskstats version 1 Mar 17 18:06:24 volumio kernel: Loading compiled-in X.509 certificates Mar 17 18:06:24 volumio kernel: zswap: loaded using pool lzo/zbud Mar 17 18:06:24 volumio kernel: ata1.01: NODEV after polling detection Mar 17 18:06:24 volumio kernel: ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 Mar 17 18:06:24 volumio kernel: ata1.00: 33554432 sectors, multi 16: LBA48 Mar 17 18:06:24 volumio kernel: scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Mar 17 18:06:24 volumio kernel: sd 0:0:0:0: [sda] 33554432 512-byte logical blocks: (17.2 GB/16.0 GiB) Mar 17 18:06:24 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 17 18:06:24 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 Mar 17 18:06:24 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Mar 17 18:06:24 volumio kernel: sda: sda1 sda2 sda3 Mar 17 18:06:24 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 17 18:06:24 volumio kernel: Freeing unused kernel image (initmem) memory: 1820K Mar 17 18:06:24 volumio kernel: Write protecting the kernel read-only data: 18432k Mar 17 18:06:24 volumio kernel: Freeing unused kernel image (text/rodata gap) memory: 2036K Mar 17 18:06:24 volumio kernel: Freeing unused kernel image (rodata/data gap) memory: 1000K Mar 17 18:06:24 volumio kernel: Run /init as init process Mar 17 18:06:24 volumio kernel: with arguments: Mar 17 18:06:24 volumio kernel: /init Mar 17 18:06:24 volumio kernel: splash Mar 17 18:06:24 volumio kernel: with environment: Mar 17 18:06:24 volumio kernel: HOME=/ Mar 17 18:06:24 volumio kernel: TERM=linux Mar 17 18:06:24 volumio kernel: BOOT_IMAGE=vmlinuz Mar 17 18:06:24 volumio kernel: biosdevname=0 Mar 17 18:06:24 volumio kernel: imgpart=UUID=995b3bef-0123-488a-8237-3568c5f00abb Mar 17 18:06:24 volumio kernel: bootpart=UUID=5A07-DE33 Mar 17 18:06:24 volumio kernel: datapart=UUID=401d59b5-fe69-47e1-b9dd-4c8e38d89f93 Mar 17 18:06:24 volumio kernel: imgfile=/volumio_current.sqsh Mar 17 18:06:24 volumio kernel: use_kmsg=yes Mar 17 18:06:24 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 17 18:06:24 volumio kernel: fuse: init (API version 7.32) Mar 17 18:06:24 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Mar 17 18:06:24 volumio kernel: sdhci: Copyright(c) Pierre Ossman Mar 17 18:06:24 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 18:06:24 volumio kernel: usbcore: registered new interface driver usbhid Mar 17 18:06:24 volumio kernel: usbhid: USB HID core driver Mar 17 18:06:24 volumio kernel: loop: module loaded Mar 17 18:06:24 volumio kernel: usbcore: registered new interface driver usb-storage Mar 17 18:06:24 volumio unknown: [i] initramfs: IMGPART=/dev/sda2 Mar 17 18:06:24 volumio unknown: [i] initramfs: IMGFILE=/volumio_current.sqsh Mar 17 18:06:24 volumio unknown: [i] initramfs: BOOTPART=/dev/sda1 Mar 17 18:06:24 volumio unknown: [i] initramfs: DATAPART=/dev/sda3 Mar 17 18:06:24 volumio kernel: EXT4-fs (sda2): recovery complete Mar 17 18:06:24 volumio kernel: EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null) Mar 17 18:06:24 volumio unknown: [i] initramfs: GPTSIZEMISMATCH: Mar 17 18:06:24 volumio unknown: [i] initramfs: Doing a 5 second delay here to give kernel load a headstart Mar 17 18:06:24 volumio unknown: [i] initramfs: Checking for a volumio rootfs update on a USB device Mar 17 18:06:24 volumio unknown: [i] initramfs: Checking for factory reset on a USB device Mar 17 18:06:24 volumio unknown: [i] initramfs: Checking for factory or user-data reset on boot device Mar 17 18:06:24 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 17 18:06:24 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 17 18:06:24 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 17 18:06:24 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 17 18:06:24 volumio unknown: [i] initramfs: Volumio version 3.512 Mar 17 18:06:24 volumio kernel: EXT4-fs (sda3): 2 orphan inodes deleted Mar 17 18:06:24 volumio kernel: EXT4-fs (sda3): recovery complete Mar 17 18:06:24 volumio kernel: EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null) Mar 17 18:06:24 volumio unknown: [i] initramfs: Checking if fstab needs to be updated.. Mar 17 18:06:24 volumio unknown: [i] initramfs: Current /dev/sda1 - UUID=5A07-DE33 Mar 17 18:06:24 volumio unknown: [i] initramfs: Current fstb \boot Mar 17 18:06:24 volumio unknown: [i] initramfs: Volumio: 3.512 Mar 17 18:06:24 volumio unknown: [i] initramfs: Finishing initramfs, switching rootfs and starting the boot process... Mar 17 18:06:24 volumio systemd[1]: Inserted module 'autofs4' Mar 17 18:06:24 volumio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Mar 17 18:06:24 volumio systemd[1]: Detected virtualization kvm. Mar 17 18:06:24 volumio systemd[1]: Detected architecture x86-64. Mar 17 18:06:24 volumio systemd[1]: Set hostname to . Mar 17 18:06:24 volumio systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Mar 17 18:06:24 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Mar 17 18:06:24 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 17 18:06:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 17 18:06:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 17 18:06:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 17 18:06:24 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Mar 17 18:06:24 volumio systemd[1]: Reached target System Time Synchronized. Mar 17 18:06:24 volumio systemd[1]: Listening on Journal Socket (/dev/log). Mar 17 18:06:24 volumio kernel: RPC: Registered named UNIX socket transport module. Mar 17 18:06:24 volumio kernel: RPC: Registered udp transport module. Mar 17 18:06:24 volumio kernel: RPC: Registered tcp transport module. Mar 17 18:06:24 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 17 18:06:24 volumio systemd-journald[197]: Journal started Mar 17 18:06:24 volumio systemd-journald[197]: Runtime journal (/run/log/journal/c11fdee23ced21dd746a26f464986581) is 3.7M, max 30.0M, 26.2M free. Mar 17 18:06:24 volumio systemd-journald[197]: Runtime journal (/run/log/journal/c11fdee23ced21dd746a26f464986581) is 3.7M, max 30.0M, 26.2M free. Mar 17 18:06:24 volumio systemd-udevd[230]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 17 18:06:24 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Mar 17 18:06:24 volumio systemd[1]: Started Show Plymouth Boot Screen. Mar 17 18:06:24 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 17 18:06:24 volumio systemd[1]: Reached target Local Encrypted Volumes. Mar 17 18:06:24 volumio systemd[1]: Reached target Paths. Mar 17 18:06:24 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 17 18:06:24 volumio fake-hwclock[198]: Current system time: 2025-03-17 18:06:24 Mar 17 18:06:24 volumio fake-hwclock[198]: fake-hwclock saved clock information is in the past: 2025-03-09 17:43:02 Mar 17 18:06:24 volumio fake-hwclock[198]: To set system time to this saved clock anyway, use "force" Mar 17 18:06:24 volumio systemd[1]: Started Flush Journal to Persistent Storage. Mar 17 18:06:24 volumio kernel: FDC 0 is a S82078B Mar 17 18:06:24 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Mar 17 18:06:24 volumio kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 Mar 17 18:06:24 volumio kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 Mar 17 18:06:24 volumio kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 18:06:24 volumio kernel: SSE version of gcm_enc/dec engaged. Mar 17 18:06:24 volumio kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Mar 17 18:06:24 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 17 18:06:24 volumio systemd-udevd[242]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 17 18:06:24 volumio kernel: PCI Interrupt Link [LNKC] enabled at IRQ 10 Mar 17 18:06:24 volumio systemd[1]: Found device QEMU_HARDDISK boot. Mar 17 18:06:24 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/5A07-DE33... Mar 17 18:06:24 volumio systemd[1]: Started File System Check Daemon to report status. Mar 17 18:06:24 volumio systemd-fsck[305]: fsck.fat 4.1 (2017-01-24) Mar 17 18:06:24 volumio systemd-fsck[305]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mar 17 18:06:24 volumio systemd-fsck[305]: Automatically removing dirty bit. Mar 17 18:06:24 volumio systemd-fsck[305]: Performing changes. Mar 17 18:06:24 volumio systemd-fsck[305]: /dev/sda1: 22 files, 15676/43727 clusters Mar 17 18:06:24 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/5A07-DE33. Mar 17 18:06:24 volumio systemd[1]: Mounting /boot... Mar 17 18:06:24 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 17 18:06:24 volumio systemd[1]: Mounted /boot. Mar 17 18:06:24 volumio systemd[1]: Reached target Local File Systems. Mar 17 18:06:24 volumio systemd[1]: Starting Preprocess NFS configuration... Mar 17 18:06:24 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 17 18:06:24 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 17 18:06:24 volumio systemd[1]: Starting Create Volatile Files and Directories... Mar 17 18:06:24 volumio systemd[1]: nfs-config.service: Succeeded. Mar 17 18:06:24 volumio systemd[1]: Started Preprocess NFS configuration. Mar 17 18:06:24 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 17 18:06:24 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 17 18:06:24 volumio systemd[1]: Reached target NFS client services. Mar 17 18:06:24 volumio systemd[1]: plymouth-read-write.service: Succeeded. Mar 17 18:06:24 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 17 18:06:24 volumio systemd[1]: Received SIGRTMIN+20 from PID 237 (plymouthd). Mar 17 18:06:24 volumio systemd[1]: Started Create Volatile Files and Directories. Mar 17 18:06:24 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 17 18:06:24 volumio systemd[1]: Starting RPC bind portmap service... Mar 17 18:06:24 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 17 18:06:24 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 17 18:06:24 volumio systemd[1]: Reached target System Initialization. Mar 17 18:06:24 volumio systemd[1]: Listening on mpd.socket. Mar 17 18:06:24 volumio systemd[1]: Listening on triggerhappy.socket. Mar 17 18:06:24 volumio systemd[1]: Started Daily man-db regeneration. Mar 17 18:06:24 volumio systemd[1]: Started Daily apt download activities. Mar 17 18:06:24 volumio systemd[1]: Started Daily apt upgrade and clean activities. Mar 17 18:06:24 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 17 18:06:24 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 17 18:06:24 volumio systemd[1]: Reached target Sockets. Mar 17 18:06:24 volumio systemd[1]: Reached target Basic System. Mar 17 18:06:24 volumio systemd[1]: Started volumio-remote-updater.service. Mar 17 18:06:24 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 17 18:06:24 volumio systemd[1]: Starting DHCP Client Daemon... Mar 17 18:06:24 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Mar 17 18:06:24 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 17 18:06:24 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 17 18:06:24 volumio systemd[1]: Started Volumio Iptables Module. Mar 17 18:06:24 volumio thd[325]: Unable to parse trigger line: Mar 17 18:06:24 volumio thd[325]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 17 18:06:24 volumio thd[325]: Unable to parse trigger line: Mar 17 18:06:24 volumio thd[325]: Found socket passed from systemd Mar 17 18:06:24 volumio systemd[1]: Starting Wireless Services... Mar 17 18:06:24 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 17 18:06:24 volumio systemd[1]: Started D-Bus System Message Bus. Mar 17 18:06:24 volumio systemd-udevd[247]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 17 18:06:24 volumio systemd[1]: Starting WPA supplicant... Mar 17 18:06:24 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 17 18:06:24 volumio systemd[1]: Reached target Timers. Mar 17 18:06:24 volumio avahi-daemon[326]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Mar 17 18:06:24 volumio systemd[1]: Starting Login Service... Mar 17 18:06:24 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 17 18:06:24 volumio avahi-daemon[326]: Successfully dropped root privileges. Mar 17 18:06:24 volumio avahi-daemon[326]: avahi-daemon 0.7 starting up. Mar 17 18:06:24 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 17 18:06:24 volumio systemd[1]: Started triggerhappy global hotkey daemon. Mar 17 18:06:24 volumio systemd[1]: Started RPC bind portmap service. Mar 17 18:06:24 volumio systemd[1]: Reached target Remote File Systems (Pre). Mar 17 18:06:24 volumio systemd[1]: Reached target Remote File Systems. Mar 17 18:06:24 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 17 18:06:24 volumio systemd[1]: Reached target RPC Port Mapper. Mar 17 18:06:24 volumio avahi-daemon[326]: Successfully called chroot(). Mar 17 18:06:24 volumio avahi-daemon[326]: Successfully dropped remaining capabilities. Mar 17 18:06:24 volumio avahi-daemon[326]: No service file found in /etc/avahi/services. Mar 17 18:06:24 volumio avahi-daemon[326]: Network interface enumeration completed. Mar 17 18:06:24 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 17 18:06:24 volumio avahi-daemon[326]: Server startup complete. Host name is volumio.local. Local service cookie is 2984202144. Mar 17 18:06:25 volumio systemd-logind[333]: Watching system buttons on /dev/input/event0 (Power Button) Mar 17 18:06:25 volumio systemd-logind[333]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Mar 17 18:06:25 volumio systemd-logind[333]: New seat seat0. Mar 17 18:06:25 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 17 18:06:25 volumio systemd[1]: Started Login Service. Mar 17 18:06:25 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 17 18:06:25 volumio systemd-udevd[239]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 17 18:06:25 volumio systemd-logind[333]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Mar 17 18:06:25 volumio systemd[1]: Started WPA supplicant. Mar 17 18:06:25 volumio wpa_supplicant[332]: Successfully initialized wpa_supplicant Mar 17 18:06:25 volumio volumio-remote-updater[323]: Error: No active session Mar 17 18:06:25 volumio volumio-remote-updater[323]: [2025-03-17 18:06:25] [info] asio async_connect error: system:111 (Connection refused) Mar 17 18:06:25 volumio volumio-remote-updater[323]: [2025-03-17 18:06:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 17 18:06:25 volumio volumio-remote-updater[323]: [2025-03-17 18:06:25] [error] handle_connect error: Underlying Transport Error Mar 17 18:06:25 volumio systemd[1]: iptables.service: Succeeded. Mar 17 18:06:25 volumio kernel: random: systemd-journal: uninitialized urandom read (16 bytes read) Mar 17 18:06:25 volumio volumio[334]: Could not open config: /tmp/upmpdcli.conf Mar 17 18:06:25 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 17 18:06:25 volumio dhcpcd[324]: no valid interfaces found Mar 17 18:06:25 volumio dhcpcd[324]: no valid interfaces found Mar 17 18:06:25 volumio systemd[1]: Started DHCP Client Daemon. Mar 17 18:06:25 volumio dhcpcd[324]: no interfaces have a carrier Mar 17 18:06:25 volumio dhcpcd[324]: forked to background, child pid 394 Mar 17 18:06:25 volumio dhcpcd[324]: no interfaces have a carrier Mar 17 18:06:25 volumio dhcpcd[324]: forked to background, child pid 394 Mar 17 18:06:25 volumio systemd-udevd[243]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 17 18:06:25 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:06:25 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 17 18:06:25 volumio loadcpufreq[338]: Loading cpufreq kernel modules...failed. Mar 17 18:06:25 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 17 18:06:25 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 17 18:06:25 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 17 18:06:25 volumio systemd-udevd[246]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 17 18:06:25 volumio systemd-logind[333]: Watching system buttons on /dev/input/event0 (Power Button) Mar 17 18:06:25 volumio systemd-udevd[241]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 17 18:06:25 volumio cpufrequtils[398]: CPUFreq Utilities: Setting performance CPUFreq governor...disabled, governor not available...done. Mar 17 18:06:25 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 17 18:06:25 volumio systemd-udevd[245]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 17 18:06:25 volumio kernel: PCI Interrupt Link [LNKB] enabled at IRQ 10 Mar 17 18:06:25 volumio kernel: qxl 0000:00:02.0: vgaarb: deactivate vga console Mar 17 18:06:25 volumio kernel: Console: switching to colour dummy device 80x25 Mar 17 18:06:25 volumio kernel: PCI Interrupt Link [LNKA] enabled at IRQ 11 Mar 17 18:06:25 volumio kernel: [drm] Device Version 0.0 Mar 17 18:06:25 volumio kernel: [drm] Compression level 0 log level 0 Mar 17 18:06:25 volumio kernel: [drm] 98302 io pages at offset 0x8000000 Mar 17 18:06:25 volumio kernel: [drm] 134217728 byte draw area at offset 0x0 Mar 17 18:06:25 volumio kernel: [drm] RAM header offset: 0x1fffe000 Mar 17 18:06:25 volumio kernel: [TTM] Zone kernel: Available graphics memory: 1020352 KiB Mar 17 18:06:25 volumio kernel: [TTM] Initializing pool allocator Mar 17 18:06:25 volumio kernel: [TTM] Initializing DMA pool allocator Mar 17 18:06:25 volumio kernel: [drm] qxl: 128M of VRAM memory size Mar 17 18:06:25 volumio kernel: [drm] qxl: 511M of IO pages memory ready (VRAM domain) Mar 17 18:06:25 volumio kernel: [drm] qxl: 256M of Surface memory size Mar 17 18:06:25 volumio kernel: [drm] slot 0 (main): base 0xc0000000, size 0x1fffe000 Mar 17 18:06:25 volumio kernel: [drm] slot 1 (surfaces): base 0xe0000000, size 0x10000000 Mar 17 18:06:25 volumio kernel: [drm] Initialized qxl 0.1.0 20120117 for 0000:00:02.0 on minor 0 Mar 17 18:06:25 volumio kernel: fbcon: qxldrmfb (fb0) is primary device Mar 17 18:06:25 volumio kernel: Console: switching to colour frame buffer device 128x48 Mar 17 18:06:25 volumio kernel: qxl 0000:00:02.0: [drm] fb0: qxldrmfb frame buffer device Mar 17 18:06:25 volumio systemd-udevd[246]: Using default interface naming scheme 'v240'. Mar 17 18:06:25 volumio systemd-udevd[246]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D0: autoconfig for Generic: line_outs=1 (0x3/0x0/0x0/0x0/0x0) type:speaker Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D0: mono: mono_out=0x0 Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D0: inputs: Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D0: Mic=0x5 Mar 17 18:06:25 volumio dhcpcd[394]: eth0: waiting for carrier Mar 17 18:06:25 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D1: autoconfig for Generic: line_outs=1 (0x3/0x0/0x0/0x0/0x0) type:line Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D1: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D1: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D1: mono: mono_out=0x0 Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D1: inputs: Mar 17 18:06:25 volumio kernel: snd_hda_codec_generic hdaudioC0D1: Line=0x5 Mar 17 18:06:25 volumio kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Master Playback Volume:0 is already present Mar 17 18:06:25 volumio kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Master Playback Switch:0 is already present Mar 17 18:06:25 volumio kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Capture Volume:0 is already present Mar 17 18:06:25 volumio kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Capture Switch:0 is already present Mar 17 18:06:25 volumio systemd[1]: Started Manage Sound Card State (restore and store). Mar 17 18:06:25 volumio systemd[1]: Starting Save/Restore Sound Card State... Mar 17 18:06:25 volumio alsactl[436]: alsactl 1.1.8 daemon started Mar 17 18:06:25 volumio haveged[322]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Mar 17 18:06:25 volumio haveged[322]: haveged: cpu: (L4 VC); data: 32K (L2 L4 V); inst: 32K (L2 L4 V); idx: 24/40; sz: 31410/52825 Mar 17 18:06:25 volumio haveged[322]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9977 Mar 17 18:06:25 volumio haveged[322]: haveged: fills: 0, generated: 0 Mar 17 18:06:25 volumio kernel: random: crng init done Mar 17 18:06:25 volumio kernel: random: 238 urandom warning(s) missed due to ratelimiting Mar 17 18:06:25 volumio systemd[1]: Started Save/Restore Sound Card State. Mar 17 18:06:25 volumio systemd[1]: Reached target Sound Card. Mar 17 18:06:25 volumio systemd[1]: Found device Virtio network device. Mar 17 18:06:25 volumio systemd[1]: Started ifup for eth0. Mar 17 18:06:25 volumio sh[440]: command failed: No such device (-19) Mar 17 18:06:25 volumio sh[440]: sending commands to master dhcpcd process Mar 17 18:06:25 volumio dhcpcd[459]: sending commands to master dhcpcd process Mar 17 18:06:25 volumio dhcpcd[394]: control command: /sbin/dhcpcd eth0 Mar 17 18:06:25 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 17 18:06:25 volumio systemd[1]: Starting Raise network interfaces... Mar 17 18:06:25 volumio ifup[462]: command failed: No such device (-19) Mar 17 18:06:25 volumio sh[440]: eth0=eth0 Mar 17 18:06:25 volumio ifup[462]: command failed: No such device (-19) Mar 17 18:06:25 volumio ifup[462]: command failed: No such device (-19) Mar 17 18:06:26 volumio systemd[1]: Started Raise network interfaces. Mar 17 18:06:26 volumio systemd[1]: Reached target Network. Mar 17 18:06:26 volumio systemd[1]: Starting OpenBSD Secure Shell server... Mar 17 18:06:26 volumio systemd[1]: Starting Music Player Daemon... Mar 17 18:06:26 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 17 18:06:26 volumio systemd[1]: Starting Permit User Sessions... Mar 17 18:06:26 volumio systemd[1]: Starting Network Time Service... Mar 17 18:06:26 volumio systemd[1]: Reached target Network is Online. Mar 17 18:06:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 18:06:26 volumio systemd[1]: Starting /etc/rc.local Compatibility... Mar 17 18:06:26 volumio wireless.js[329]: WIRELESS: No wireless interface, exiting Mar 17 18:06:26 volumio systemd[1]: Starting Samba NMB Daemon... Mar 17 18:06:26 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Mar 17 18:06:26 volumio systemd[1]: Started Permit User Sessions. Mar 17 18:06:26 volumio systemd[1]: Started /etc/rc.local Compatibility. Mar 17 18:06:26 volumio ntpd[593]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 17 18:06:26 volumio ntpd[593]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 17 18:06:26 volumio ntpd[611]: proto: precision = 0.036 usec (-25) Mar 17 18:06:26 volumio systemd[1]: Started Network Time Service. Mar 17 18:06:26 volumio systemd[1]: Starting Hold until boot process finishes up... Mar 17 18:06:26 volumio ntpd[611]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 17 18:06:26 volumio ntpd[611]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 17 18:06:26 volumio ntpd[611]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 811 days ago Mar 17 18:06:26 volumio ntpd[611]: Listen and drop on 0 v6wildcard [::]:123 Mar 17 18:06:26 volumio ntpd[611]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 17 18:06:26 volumio ntpd[611]: Listen normally on 2 lo 127.0.0.1:123 Mar 17 18:06:26 volumio ntpd[611]: Listening on routing socket on fd #19 for interface updates Mar 17 18:06:26 volumio ntpd[611]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 17 18:06:26 volumio ntpd[611]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 17 18:06:26 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 17 18:06:26 volumio ifplugd(eth0)[627]: ifplugd 0.28 initializing. Mar 17 18:06:26 volumio ifplugd(eth0)[627]: Using interface eth0/4E:7A:27:E1:7D:D4 with driver (version: 1.0.0) Mar 17 18:06:26 volumio ifplugd(eth0)[627]: Using detection mode: SIOCETHTOOL Mar 17 18:06:26 volumio ifplugd(eth0)[627]: Initialization complete, link beat detected. Mar 17 18:06:26 volumio systemd[1]: Received SIGRTMIN+21 from PID 237 (plymouthd). Mar 17 18:06:26 volumio iw[609]: command failed: No such device (-19) Mar 17 18:06:26 volumio sshd[631]: Server listening on 0.0.0.0 port 22. Mar 17 18:06:26 volumio systemd[1]: Received SIGRTMIN+21 from PID 237 (plymouthd). Mar 17 18:06:26 volumio systemd[1]: plymouth-quit.service: Succeeded. Mar 17 18:06:26 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Mar 17 18:06:26 volumio sshd[631]: Server listening on :: port 22. Mar 17 18:06:26 volumio systemd[1]: Started OpenBSD Secure Shell server. Mar 17 18:06:26 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 17 18:06:26 volumio systemd[1]: Started Hold until boot process finishes up. Mar 17 18:06:26 volumio systemd[1]: plymouth-start.service: Succeeded. Mar 17 18:06:26 volumio systemd[1]: Started Getty on tty1. Mar 17 18:06:26 volumio systemd[1]: Reached target Login Prompts. Mar 17 18:06:26 volumio iwconfig[629]: Error for wireless request "Set Power Management" (8B2C) : Mar 17 18:06:26 volumio iwconfig[629]: SET failed on device wlan0 ; No such device. Mar 17 18:06:26 volumio systemd[1]: wireless.service: Succeeded. Mar 17 18:06:26 volumio systemd[1]: Started Wireless Services. Mar 17 18:06:26 volumio systemd[1]: Started Volumio Backend Module. Mar 17 18:06:26 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Mar 17 18:06:26 volumio systemd[1]: Started Start Volumio Kiosk. Mar 17 18:06:26 volumio ifplugd(eth0)[627]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 17 18:06:26 volumio ifplugd(eth0)[627]: client: ifup: interface eth0 already configured Mar 17 18:06:26 volumio ifplugd(eth0)[627]: Program executed successfully. Mar 17 18:06:26 volumio ifplugd[602]: Network Interface Plugging Daemon...start eth0...done. Mar 17 18:06:26 volumio systemd[1]: Started LSB: Brings up/down network automatically. Mar 17 18:06:26 volumio startx[636]: X.Org X Server 1.20.4 Mar 17 18:06:26 volumio startx[636]: X Protocol Version 11, Revision 0 Mar 17 18:06:26 volumio startx[636]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Mar 17 18:06:26 volumio startx[636]: Current Operating System: Linux volumio 5.10.176-volumio #1 SMP Thu Mar 30 17:57:37 CEST 2023 x86_64 Mar 17 18:06:26 volumio startx[636]: Kernel command line: BOOT_IMAGE=vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=995b3bef-0123-488a-8237-3568c5f00abb bootpart=UUID=5A07-DE33 datapart=UUID=401d59b5-fe69-47e1-b9dd-4c8e38d89f93 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 initrd=volumio.initrd Mar 17 18:06:26 volumio startx[636]: Build Date: 18 December 2021 09:05:36AM Mar 17 18:06:26 volumio startx[636]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Mar 17 18:06:26 volumio startx[636]: Current version of pixman: 0.36.0 Mar 17 18:06:26 volumio startx[636]: Before reporting problems, check http://wiki.x.org Mar 17 18:06:26 volumio startx[636]: to make sure that you have the latest version. Mar 17 18:06:26 volumio startx[636]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 17 18:06:26 volumio startx[636]: (++) from command line, (!!) notice, (II) informational, Mar 17 18:06:26 volumio startx[636]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 17 18:06:26 volumio startx[636]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Mar 17 18:06:26 2025 Mar 17 18:06:26 volumio startx[636]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 17 18:06:26 volumio startx[636]: pci id for fd 14: 1b36:0100, driver (null) Mar 17 18:06:26 volumio nmbd[607]: [2025/03/17 18:06:26.537606, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 17 18:06:26 volumio nmbd[607]: started asyncdns process 708 Mar 17 18:06:26 volumio nmbd[607]: [2025/03/17 18:06:26.539722, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 17 18:06:26 volumio nmbd[607]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 17 18:06:26 volumio nmbd[607]: [2025/03/17 18:06:26.539828, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 17 18:06:26 volumio nmbd[607]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 17 18:06:26 volumio dhcpcd[394]: eth0: carrier acquired Mar 17 18:06:26 volumio dhcpcd[394]: DUID 00:04:7f:2e:84:09:c0:c3:49:43:be:2f:4a:60:09:9a:21:28 Mar 17 18:06:26 volumio dhcpcd[394]: eth0: IAID 27:e1:7d:d4 Mar 17 18:06:26 volumio dhcpcd[394]: eth0: adding address fe80::4c7a:27ff:fee1:7dd4 Mar 17 18:06:26 volumio dhcpcd[394]: ipv6_addaddr1: Permission denied Mar 17 18:06:26 volumio dhcpcd[394]: eth0: rebinding lease of 192.168.1.180 Mar 17 18:06:26 volumio dhcpcd[394]: eth0: probing address 192.168.1.180/24 Mar 17 18:06:26 volumio soundcard-init.sh[635]: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'Master Playback Switch',0,1,0) appears twice or more Mar 17 18:06:26 volumio soundcard-init.sh[635]: amixer: Mixer load hw:0 error: Invalid argument Mar 17 18:06:26 volumio systemd[1]: soundcard-init.service: Succeeded. Mar 17 18:06:27 volumio ntpd[611]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 17 18:06:27 volumio mpd[628]: Mar 17 18:06 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 18:06:27 volumio kernel: broken atomic modeset userspace detected, disabling atomic Mar 17 18:06:27 volumio startx[636]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Mar 17 18:06:27 volumio volumio[634]: info: ------------------------------------------- Mar 17 18:06:27 volumio volumio[634]: info: ----- Volumio3 ---- Mar 17 18:06:27 volumio volumio[634]: info: ------------------------------------------- Mar 17 18:06:27 volumio volumio[634]: info: ----- System startup ---- Mar 17 18:06:27 volumio volumio[634]: info: ------------------------------------------- Mar 17 18:06:27 volumio mpd[628]: Mar 17 18:06 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 17 18:06:27 volumio mpd[628]: Mar 17 18:06 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 17 18:06:27 volumio systemd[1]: Started Music Player Daemon. Mar 17 18:06:27 volumio dhcpcd[394]: eth0: soliciting an IPv6 router Mar 17 18:06:27 volumio volumio[634]: info: MYVOLUMIO Environment detected Mar 17 18:06:27 volumio volumio[634]: info: Plugin folders cleanup Mar 17 18:06:27 volumio volumio[634]: info: Scanning into folder /volumio/app/plugins/ Mar 17 18:06:27 volumio volumio[634]: info: Scanning category audio_interface Mar 17 18:06:27 volumio volumio[634]: info: Scanning category miscellanea Mar 17 18:06:27 volumio volumio[634]: info: Scanning category music_service Mar 17 18:06:27 volumio volumio[634]: info: Scanning category plugins.json Mar 17 18:06:27 volumio volumio[634]: info: Scanning category system_controller Mar 17 18:06:27 volumio volumio[634]: info: Scanning category user_interface Mar 17 18:06:27 volumio volumio[634]: info: Scanning into folder /data/plugins/ Mar 17 18:06:27 volumio volumio[634]: info: Scanning category audio_interface Mar 17 18:06:27 volumio volumio[634]: info: Scanning category system_controller Mar 17 18:06:27 volumio volumio[634]: info: Plugin folders cleanup completed Mar 17 18:06:27 volumio volumio[634]: info: ------------------------------------------- Mar 17 18:06:27 volumio volumio[634]: info: ----- Core plugins startup ---- Mar 17 18:06:27 volumio volumio[634]: info: ------------------------------------------- Mar 17 18:06:27 volumio volumio[634]: info: Loading plugins from folder /volumio/app/plugins/ Mar 17 18:06:27 volumio volumio[634]: info: Adding plugin upnp to MyMusic Plugins Mar 17 18:06:27 volumio volumio[634]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 17 18:06:27 volumio volumio[634]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 17 18:06:27 volumio volumio[634]: info: Loading plugins from folder /data/plugins/ Mar 17 18:06:27 volumio volumio[634]: info: Loading plugin "system"... Mar 17 18:06:27 volumio volumio[634]: info: Loading plugin "appearance"... Mar 17 18:06:28 volumio ntpd[611]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "network"... Mar 17 18:06:28 volumio volumio[634]: info: Refreshing Cached IP Addresses Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "services"... Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "alsa_controller"... Mar 17 18:06:28 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "wizard"... Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "volumio_command_line_client"... Mar 17 18:06:28 volumio volumio[634]: info: Plugin upnp is not enabled Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "my_music"... Mar 17 18:06:28 volumio sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 17 18:06:28 volumio sudo[836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 17 18:06:28 volumio sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 17 18:06:28 volumio sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "mpd"... Mar 17 18:06:28 volumio sudo[836]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:28 volumio sudo[838]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:28 volumio sudo[839]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:28 volumio volumio[634]: info: Creating MPD Configuration file Mar 17 18:06:28 volumio volumio[634]: info: Plugin upnp_browser is not enabled Mar 17 18:06:28 volumio volumio[634]: info: Loading plugin "networkfs"... Mar 17 18:06:28 volumio sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 18:06:28 volumio sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 18:06:28 volumio sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[851]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:28 volumio systemd[1]: Stopping Music Player Daemon... Mar 17 18:06:28 volumio systemd[1]: mpd.service: Succeeded. Mar 17 18:06:28 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 18:06:28 volumio systemd[1]: Starting Music Player Daemon... Mar 17 18:06:28 volumio sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/Artistes /mnt/NAS/Artistes Mar 17 18:06:28 volumio sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/NRJ /mnt/NAS/NRJ Mar 17 18:06:28 volumio volumio[634]: info: Starting Udev Watcher for removable devices Mar 17 18:06:28 volumio sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/ost /mnt/NAS/OST Mar 17 18:06:28 volumio sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/Fun Radio /mnt/NAS/Fun_Radio Mar 17 18:06:28 volumio sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=!mafleur56,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/Spotify /mnt/NAS/Spotify Mar 17 18:06:28 volumio sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:28 volumio kernel: FS-Cache: Loaded Mar 17 18:06:28 volumio kernel: Key type dns_resolver registered Mar 17 18:06:29 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Mar 17 18:06:29 volumio kernel: Key type cifs.spnego registered Mar 17 18:06:29 volumio kernel: Key type cifs.idmap registered Mar 17 18:06:29 volumio sudo[861]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:29 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/NRJ Mar 17 18:06:29 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 17 18:06:29 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 17 18:06:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 17 18:06:29 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/Artistes Mar 17 18:06:29 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 17 18:06:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 17 18:06:29 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/ost Mar 17 18:06:29 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 17 18:06:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 17 18:06:29 volumio sudo[865]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:29 volumio sudo[868]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:29 volumio sudo[863]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:29 volumio sudo[870]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:29 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/Fun Radio Mar 17 18:06:29 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 17 18:06:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 17 18:06:29 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/Spotify Mar 17 18:06:29 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 17 18:06:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 17 18:06:29 volumio mpd[857]: Mar 17 18:06 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 18:06:29 volumio ntpd[611]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 17 18:06:29 volumio volumio[634]: info: Ignoring mount for partition: boot Mar 17 18:06:29 volumio volumio[634]: info: Ignoring mount for partition: volumio Mar 17 18:06:29 volumio volumio[634]: info: Ignoring mount for partition: volumio_data Mar 17 18:06:29 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "alarm-clock"... Mar 17 18:06:29 volumio volumio[634]: info: Plugin airplay_emulation is not enabled Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "last_100"... Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "webradio"... Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "i2s_dacs"... Mar 17 18:06:29 volumio volumio[634]: info: I2S DAC not set, start Auto-detection Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "volumiodiscovery"... Mar 17 18:06:29 volumio volumio[634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 17 18:06:29 volumio node[634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 17 18:06:29 volumio volumio[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:06:29 volumio node[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:06:29 volumio volumio[634]: *** WARNING *** For more information see Mar 17 18:06:29 volumio node[634]: *** WARNING *** For more information see Mar 17 18:06:29 volumio volumio[634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 17 18:06:29 volumio node[634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 17 18:06:29 volumio volumio[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:06:29 volumio node[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:06:29 volumio volumio[634]: *** WARNING *** For more information see Mar 17 18:06:29 volumio node[634]: *** WARNING *** For more information see Mar 17 18:06:29 volumio volumio[634]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 17 18:06:29 volumio volumio[634]: info: Discovery: Started advertising with name: Volumio Mar 17 18:06:29 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "autostart"... Mar 17 18:06:29 volumio mpd[857]: Mar 17 18:06 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 17 18:06:29 volumio mpd[857]: Mar 17 18:06 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 17 18:06:29 volumio systemd[1]: Started Music Player Daemon. Mar 17 18:06:29 volumio sudo[852]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:29 volumio volumio[634]: info: Applying required configuration parameters for plugin autostart Mar 17 18:06:29 volumio volumio[634]: info: AutoStart - onVolumioStart - read config.json Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "outputs"... Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "albumart"... Mar 17 18:06:29 volumio volumio[634]: info: Plugin example_plugin is not enabled Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "inputs"... Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "updater_comm"... Mar 17 18:06:29 volumio volumio[634]: Forking 1 albumart workers Mar 17 18:06:29 volumio volumio[634]: info: Plugin mpdemulation is not enabled Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "rest_api"... Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "websocket"... Mar 17 18:06:29 volumio volumio[634]: info: Loading plugin "snapserver"... Mar 17 18:06:29 volumio volumio[634]: info: Applying required configuration parameters for plugin snapserver Mar 17 18:06:29 volumio volumio[634]: info: Loading i18n strings for locale fr Mar 17 18:06:29 volumio volumio[634]: Updating browse sources language Mar 17 18:06:29 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:06:29 volumio dbus-daemon[330]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=0 pid=811 comm="/usr/lib/chromium/chromium --show-component-extens") Mar 17 18:06:29 volumio systemd[1]: Starting Bluetooth service... Mar 17 18:06:29 volumio volumio[634]: Starting albumart workers Mar 17 18:06:30 volumio kernel: Bluetooth: Core ver 2.22 Mar 17 18:06:30 volumio kernel: NET: Registered protocol family 31 Mar 17 18:06:30 volumio kernel: Bluetooth: HCI device and connection manager initialized Mar 17 18:06:30 volumio bluetoothd[925]: Bluetooth daemon 5.50 Mar 17 18:06:30 volumio bluetoothd[925]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 17 18:06:30 volumio dbus-daemon[330]: [system] Successfully activated service 'org.bluez' Mar 17 18:06:30 volumio systemd[1]: Started Bluetooth service. Mar 17 18:06:30 volumio bluetoothd[925]: Starting SDP server Mar 17 18:06:30 volumio bluetoothd[925]: Excluding (cli) sap Mar 17 18:06:30 volumio kernel: Bluetooth: HCI socket layer initialized Mar 17 18:06:30 volumio kernel: Bluetooth: L2CAP socket layer initialized Mar 17 18:06:30 volumio kernel: Bluetooth: SCO socket layer initialized Mar 17 18:06:30 volumio bluetoothd[925]: Bluetooth management interface 1.18 initialized Mar 17 18:06:30 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 17 18:06:30 volumio kernel: Bluetooth: BNEP filters: protocol multicast Mar 17 18:06:30 volumio kernel: Bluetooth: BNEP socket layer initialized Mar 17 18:06:30 volumio dbus-daemon[330]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.9' (uid=0 pid=925 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 17 18:06:30 volumio systemd[1]: Starting Hostname Service... Mar 17 18:06:30 volumio ntpd[611]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 17 18:06:30 volumio dbus-daemon[330]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 17 18:06:30 volumio systemd[1]: Started Hostname Service. Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::initPlayerControls Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 18:06:30 volumio volumio[634]: Express server listening on port 3000 Mar 17 18:06:30 volumio volumio[634]: [Metrics] WebUI: 3s 61.40ms Mar 17 18:06:30 volumio volumio[634]: info: Setting Device type: x86 Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::resetVolumioState Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::getcurrentVolume Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioRetrievevolume Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:06:30 volumio volumio[634]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 17 18:06:30 volumio volumio[634]: info: Completed loading Core Plugins Mar 17 18:06:30 volumio volumio[634]: info: Preparing to generate the ALSA configuration file Mar 17 18:06:30 volumio volumio[634]: info: Cannot mount NAS Spotify at system boot, trial number 1 ,retrying in 5 seconds Mar 17 18:06:30 volumio volumio[634]: info: Cannot mount NAS Fun_Radio at system boot, trial number 1 ,retrying in 5 seconds Mar 17 18:06:30 volumio volumio[634]: info: Cannot mount NAS OST at system boot, trial number 1 ,retrying in 5 seconds Mar 17 18:06:30 volumio volumio[634]: info: Cannot mount NAS NRJ at system boot, trial number 1 ,retrying in 5 seconds Mar 17 18:06:30 volumio volumio[634]: info: Cannot mount NAS Artistes at system boot, trial number 1 ,retrying in 5 seconds Mar 17 18:06:30 volumio volumio[634]: info: MPD Permissions set Mar 17 18:06:30 volumio volumio[634]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 17 18:06:30 volumio volumio[634]: wlan0 Interface doesn't support scanning. Mar 17 18:06:30 volumio volumio[634]: info: Cannot use regular scanning, forcing with ap-force Mar 17 18:06:30 volumio sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 17 18:06:30 volumio sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:30 volumio volumio-remote-updater[323]: [2025-03-17 18:06:30] [connect] Successful connection Mar 17 18:06:30 volumio sudo[981]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:30 volumio volumio[634]: command failed: No such device (-19) Mar 17 18:06:30 volumio volumio[634]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 17 18:06:30 volumio volumio[634]: command failed: No such device (-19) Mar 17 18:06:30 volumio volumio[634]: info: The plugin snapserver has an ALSA contribution file multiRoomPlayback.ignore.1.conf Mar 17 18:06:30 volumio volumio[634]: info: Reading ALSA contributions from plugins. Mar 17 18:06:30 volumio volumio[634]: info: MPD running with PID857 Mar 17 18:06:30 volumio volumio[634]: ,establishing connection Mar 17 18:06:30 volumio volumio[634]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'Master Playback Switch',0,1,0) appears twice or more Mar 17 18:06:30 volumio volumio[634]: amixer: Mixer hw:0 load error: Invalid argument Mar 17 18:06:30 volumio volumio[634]: error: MPD error: The expression evaluated to a falsy value: Mar 17 18:06:30 volumio volumio[634]: assert.ok(self.idling) Mar 17 18:06:30 volumio volumio[634]: error: The expression evaluated to a falsy value: Mar 17 18:06:30 volumio volumio[634]: assert.ok(self.idling) Mar 17 18:06:30 volumio volumio-remote-updater[323]: [2025-03-17 18:06:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742234790 101 Mar 17 18:06:30 volumio volumio[634]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: Reloading queue from file Mar 17 18:06:30 volumio volumio[634]: info: VolumeController:: Volume=undefined Mute =false Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::pushState Mar 17 18:06:30 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::updateTrackBlock Mar 17 18:06:30 volumio volumio[634]: info: CorePlayQueue::getTrackBlock Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioRetrievevolume Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::setRepeat true single undefined Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::pushState Mar 17 18:06:30 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::setRandom true Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::pushState Mar 17 18:06:30 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Mar 17 18:06:30 volumio volumio[634]: error: updateQueue error: null Mar 17 18:06:30 volumio volumio[634]: info: Asound.conf file unchanged, so no further update is needed Mar 17 18:06:30 volumio volumio[634]: info: Output device has changed, restarting MPD Mar 17 18:06:30 volumio volumio[634]: info: ___________ START PLUGINS ___________ Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 17 18:06:30 volumio volumio[634]: info: [1742234790400] CoreMusicLibrary::Adding element Last_100 Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 17 18:06:30 volumio volumio[634]: info: [1742234790404] CoreMusicLibrary::Adding element Webradio Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 18:06:30 volumio volumio[634]: info: AutoStart - onStart Mar 17 18:06:30 volumio sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 18:06:30 volumio sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:30 volumio sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 18:06:30 volumio sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:30 volumio volumio[634]: info: Volumio Calling Home Mar 17 18:06:30 volumio sudo[1013]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:30 volumio systemd[1]: Stopping Music Player Daemon... Mar 17 18:06:30 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapserver Mar 17 18:06:30 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:30 volumio systemd[1]: mpd.service: Succeeded. Mar 17 18:06:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 18:06:30 volumio systemd[1]: Starting Music Player Daemon... Mar 17 18:06:30 volumio systemd[1]: Started Snapcast server. Mar 17 18:06:30 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:30 volumio snapserver[1029]: Version 0.27.0, revision 54a3d862 Mar 17 18:06:30 volumio snapserver[1029]: Adding source: pipe:///tmp/snapfifo?name=Volumio Mar 17 18:06:30 volumio snapserver[1029]: Settings file: "/var/lib/snapserver/server.json" Mar 17 18:06:30 volumio snapserver[1029]: Using HTTP host name: volumio Mar 17 18:06:30 volumio snapserver[1029]: Adding service 'Snapcast' Mar 17 18:06:30 volumio snapserver[1029]: PcmStream: Volumio, sampleFormat: 44100:16:2 Mar 17 18:06:30 volumio snapserver[1029]: PipeStream mode: create Mar 17 18:06:30 volumio snapserver[1029]: Stream: {"fragment":"","host":"","path":"/tmp/snapfifo","query":{"chunk_ms":"20","codec":"flac","name":"Volumio","sampleformat":"44100:16:2"},"raw":"pipe:////tmp/snapfifo?chunk_ms=20&codec=flac&name=Volumio&sampleformat=44100:16:2","scheme":"pipe"} Mar 17 18:06:30 volumio snapserver[1029]: Init - compression level: 2 Mar 17 18:06:30 volumio snapserver[1029]: Exception: end of file Mar 17 18:06:30 volumio snapserver[1029]: Creating TCP acceptor for address: 0.0.0.0, port: 1705 Mar 17 18:06:30 volumio snapserver[1029]: Creating HTTP acceptor for address: 0.0.0.0, port: 1780 Mar 17 18:06:30 volumio snapserver[1029]: Creating stream acceptor for address: 0.0.0.0, port: 1704 Mar 17 18:06:30 volumio snapserver[1029]: Number of threads: 2, hw threads: 1 Mar 17 18:06:30 volumio volumio[634]: info: Completed starting Core Plugins Mar 17 18:06:30 volumio volumio[634]: info: ------------------------------------------- Mar 17 18:06:30 volumio volumio[634]: info: ----- MyVolumio plugins startup ---- Mar 17 18:06:30 volumio volumio[634]: info: ------------------------------------------- Mar 17 18:06:30 volumio volumio[634]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 17 18:06:30 volumio volumio[634]: info: SnapServer started Mar 17 18:06:30 volumio volumio[634]: info: MPD Permissions set Mar 17 18:06:30 volumio volumio[634]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'Master Playback Switch',0,1,0) appears twice or more Mar 17 18:06:30 volumio volumio[634]: amixer: Mixer hw:0 load error: Invalid argument Mar 17 18:06:30 volumio volumio[634]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:06:30 volumio volumio[634]: info: VolumeController:: Volume=undefined Mute =false Mar 17 18:06:30 volumio volumio[634]: info: CoreStateMachine::pushState Mar 17 18:06:30 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Mar 17 18:06:30 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:30 volumio snapserver[1029]: Service 'Snapcast' successfully established. Mar 17 18:06:30 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:30 volumio mpd[1034]: Mar 17 18:06 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 18:06:30 volumio mpd[1034]: Mar 17 18:06 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 17 18:06:30 volumio mpd[1034]: Mar 17 18:06 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 17 18:06:30 volumio systemd[1]: Started Music Player Daemon. Mar 17 18:06:30 volumio sudo[1015]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:30 volumio volumio[634]: error: updateQueue error: null Mar 17 18:06:31 volumio volumio[634]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3 Mar 17 18:06:31 volumio volumio[634]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:31 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 18:06:31 volumio volumio[634]: info: Received Get System Info Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 18:06:31 volumio volumio[634]: info: Discovery: Getting this device information Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:31 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:31 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:31 volumio volumio[634]: info: Listing playlists Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 17 18:06:31 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 17 18:06:32 volumio dhcpcd[394]: eth0: leased 192.168.1.180 for 43200 seconds Mar 17 18:06:32 volumio dhcpcd[394]: eth0: adding route to 192.168.1.0/24 Mar 17 18:06:32 volumio dhcpcd[394]: eth0: adding default route via 192.168.1.1 Mar 17 18:06:32 volumio avahi-daemon[326]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.180. Mar 17 18:06:32 volumio avahi-daemon[326]: New relevant interface eth0.IPv4 for mDNS. Mar 17 18:06:32 volumio avahi-daemon[326]: Registering new address record for 192.168.1.180 on eth0.IPv4. Mar 17 18:06:32 volumio ntpd[611]: ntpd exiting on signal 15 (Terminated) Mar 17 18:06:32 volumio systemd[1]: Stopping Network Time Service... Mar 17 18:06:32 volumio systemd[1]: ntp.service: Succeeded. Mar 17 18:06:32 volumio systemd[1]: Stopped Network Time Service. Mar 17 18:06:32 volumio systemd[1]: Starting Network Time Service... Mar 17 18:06:32 volumio ntpd[1105]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 17 18:06:32 volumio ntpd[1105]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 17 18:06:32 volumio systemd[1]: Started Network Time Service. Mar 17 18:06:32 volumio ntpd[1111]: proto: precision = 0.037 usec (-25) Mar 17 18:06:32 volumio ntpd[1111]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 17 18:06:32 volumio ntpd[1111]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 17 18:06:32 volumio ntpd[1111]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 811 days ago Mar 17 18:06:32 volumio ntpd[1111]: Listen and drop on 0 v6wildcard [::]:123 Mar 17 18:06:32 volumio ntpd[1111]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 17 18:06:32 volumio ntpd[1111]: Listen normally on 2 lo 127.0.0.1:123 Mar 17 18:06:32 volumio ntpd[1111]: Listen normally on 3 eth0 192.168.1.180:123 Mar 17 18:06:32 volumio ntpd[1111]: Listening on routing socket on fd #20 for interface updates Mar 17 18:06:32 volumio ntpd[1111]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 17 18:06:32 volumio ntpd[1111]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 17 18:06:32 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:32 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:33 volumio volumio[634]: info: Discovery: adding 2e020012-8fc6-427b-9a9d-527e7334ee2a Mar 17 18:06:33 volumio volumio[634]: info: Discovery: Found device Volumio Mar 17 18:06:33 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:33 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:33 volumio ntpd[1111]: Soliciting pool server 162.159.200.1 Mar 17 18:06:33 volumio snapserver[1029]: StreamServer::NewConnection: 192.168.1.181 Mar 17 18:06:33 volumio snapserver[1029]: Hello from b8:27:eb:c1:35:cc, host: snapclient-salon, v0.20.0, ClientName: Snapclient, OS: Buildroot 2021.02.1, Arch: armv6l, Protocol version: 2 Mar 17 18:06:33 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 17 18:06:33 volumio volumio[634]: info: Received Get System Info Mar 17 18:06:33 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 18:06:33 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 18:06:33 volumio volumio[634]: info: Discovery: Getting this device information Mar 17 18:06:33 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:33 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:33 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 18:06:34 volumio ntpd[1111]: Soliciting pool server 194.57.169.1 Mar 17 18:06:34 volumio ntpd[1111]: Soliciting pool server 51.255.95.80 Mar 17 18:06:35 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=!mafleur56,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/Spotify /mnt/NAS/Spotify Mar 17 18:06:35 volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/Fun Radio /mnt/NAS/Fun_Radio Mar 17 18:06:35 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:35 volumio sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/ost /mnt/NAS/OST Mar 17 18:06:35 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:35 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/NRJ /mnt/NAS/NRJ Mar 17 18:06:35 volumio sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:35 volumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=2GHxDEFqyO9qTqd10VDC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.3/Musiques/Artistes /mnt/NAS/Artistes Mar 17 18:06:35 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:35 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/Spotify Mar 17 18:06:35 volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:35 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/Fun Radio Mar 17 18:06:35 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/ost Mar 17 18:06:35 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/NRJ Mar 17 18:06:35 volumio kernel: FS-Cache: Duplicate cookie detected Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie c=0000000050f0d2c6 [p=000000003649f01a fl=222 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie d=000000006a680764 n=00000000b4d5af51 Mar 17 18:06:35 volumio kernel: FS-Cache: O-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie c=00000000c165ca0f [p=000000003649f01a fl=2 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie d=000000006a680764 n=000000000a5a7246 Mar 17 18:06:35 volumio kernel: FS-Cache: N-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio kernel: FS-Cache: Duplicate cookie detected Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie c=0000000050f0d2c6 [p=000000003649f01a fl=222 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie d=000000006a680764 n=00000000b4d5af51 Mar 17 18:06:35 volumio kernel: FS-Cache: O-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie c=00000000c165ca0f [p=000000003649f01a fl=2 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie d=000000006a680764 n=00000000e8e8e13f Mar 17 18:06:35 volumio kernel: FS-Cache: N-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio kernel: CIFS: Attempting to mount //192.168.1.3/Musiques/Artistes Mar 17 18:06:35 volumio kernel: FS-Cache: Duplicate cookie detected Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie c=0000000050f0d2c6 [p=000000003649f01a fl=222 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie d=000000006a680764 n=00000000b4d5af51 Mar 17 18:06:35 volumio kernel: FS-Cache: O-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie c=00000000c165ca0f [p=000000003649f01a fl=2 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie d=000000006a680764 n=00000000d8438a53 Mar 17 18:06:35 volumio kernel: FS-Cache: N-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio kernel: FS-Cache: Duplicate cookie detected Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie c=0000000050f0d2c6 [p=000000003649f01a fl=222 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: O-cookie d=000000006a680764 n=00000000b4d5af51 Mar 17 18:06:35 volumio kernel: FS-Cache: O-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie c=00000000c165ca0f [p=000000003649f01a fl=2 nc=0 na=1] Mar 17 18:06:35 volumio kernel: FS-Cache: N-cookie d=000000006a680764 n=000000006c034631 Mar 17 18:06:35 volumio kernel: FS-Cache: N-key=[8] '020001bdc0a80103' Mar 17 18:06:35 volumio ntpd[1111]: Soliciting pool server 45.132.96.81 Mar 17 18:06:35 volumio ntpd[1111]: Soliciting pool server 82.67.126.242 Mar 17 18:06:35 volumio ntpd[1111]: Soliciting pool server 94.23.45.103 Mar 17 18:06:35 volumio volumio[634]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 17 18:06:36 volumio sudo[1145]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:36 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:36 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:36 volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:36 volumio ntpd[1111]: Soliciting pool server 37.59.63.125 Mar 17 18:06:36 volumio ntpd[1111]: Soliciting pool server 188.213.26.33 Mar 17 18:06:36 volumio ntpd[1111]: Soliciting pool server 129.250.35.250 Mar 17 18:06:36 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:36 volumio ntpd[1111]: Soliciting pool server 82.64.42.185 Mar 17 18:06:36 volumio nmbd[607]: [2025/03/17 18:06:36.541305, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 17 18:06:36 volumio systemd[1]: Started Samba NMB Daemon. Mar 17 18:06:36 volumio nmbd[607]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 17 18:06:36 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 17 18:06:36 volumio winbindd[1191]: [2025/03/17 18:06:36.617375, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 17 18:06:36 volumio winbindd[1191]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 17 18:06:36 volumio systemd[1]: Started Samba Winbind Daemon. Mar 17 18:06:36 volumio systemd[1]: Starting Samba SMB Daemon... Mar 17 18:06:36 volumio winbindd[1191]: [2025/03/17 18:06:36.622504, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 17 18:06:36 volumio winbindd[1191]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 17 18:06:36 volumio systemd[1]: Started Samba SMB Daemon. Mar 17 18:06:36 volumio systemd[1]: Reached target Multi-User System. Mar 17 18:06:36 volumio systemd[1]: Reached target Graphical Interface. Mar 17 18:06:36 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 17 18:06:36 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 17 18:06:36 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 17 18:06:36 volumio systemd[1]: Startup finished in 18.254s (kernel) + 12.737s (userspace) = 30.992s. Mar 17 18:06:36 volumio smbd[1195]: [2025/03/17 18:06:36.734012, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 17 18:06:36 volumio smbd[1195]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 17 18:06:36 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 17 18:06:36 volumio volumio[634]: info: Received Get System Info Mar 17 18:06:36 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 18:06:36 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 18:06:36 volumio volumio[634]: info: Discovery: Getting this device information Mar 17 18:06:36 volumio volumio[634]: info: CoreCommandRouter::volumioGetState Mar 17 18:06:36 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Mar 17 18:06:36 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 18:06:37 volumio ntpd[1111]: Soliciting pool server 54.38.242.85 Mar 17 18:06:37 volumio ntpd[1111]: Soliciting pool server 162.159.200.123 Mar 17 18:06:37 volumio ntpd[1111]: Soliciting pool server 195.154.226.102 Mar 17 18:06:37 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 17 18:06:38 volumio ntpd[1111]: Soliciting pool server 82.64.84.116 Mar 17 18:06:38 volumio ntpd[1111]: Soliciting pool server 5.196.160.139 Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 17 18:06:39 volumio volumio[634]: info: Adding plugin bluetooth to MyMusic Plugins Mar 17 18:06:39 volumio volumio[634]: info: Adding plugin multiroom to MyMusic Plugins Mar 17 18:06:39 volumio volumio[634]: info: Adding plugin metavolumio to MyMusic Plugins Mar 17 18:06:39 volumio volumio[634]: info: Adding plugin cd_controller to MyMusic Plugins Mar 17 18:06:39 volumio volumio[634]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 17 18:06:39 volumio volumio[634]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 17 18:06:39 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:06:39 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:06:39 volumio volumio[634]: info: Starting MyVolumio Remote Streaming Endpoints Mar 17 18:06:39 volumio volumio[634]: info: MyVolumio login type: Token Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 17 18:06:39 volumio ntpd[1111]: Soliciting pool server 51.68.44.27 Mar 17 18:06:39 volumio ntpd[1111]: Soliciting pool server 2001:bc8:255e:100::1 Mar 17 18:06:39 volumio volumio[634]: info: Starting Streaming Service Transparent Proxy Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 17 18:06:39 volumio volumio[634]: info: Streaming services startup Mar 17 18:06:39 volumio volumio[634]: info: Starting Streaming Daemon Mar 17 18:06:39 volumio volumio[634]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 17 18:06:39 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 17 18:06:39 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:39 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:39 volumio volumio[634]: error: Cannot start Volumio Streaming Daemon Mar 17 18:06:39 volumio volumio[634]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 17 18:06:39 volumio volumio[634]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 17 18:06:39 volumio volumio[634]: STREAMING PROXY: Starting server on port 3245 Mar 17 18:06:39 volumio volumio[634]: Node JS runtime: 14 Mar 17 18:06:39 volumio volumio[634]: error: MyVolumio Custom Token format not valid, refreshing it Mar 17 18:06:40 volumio volumio[634]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.512&uuid=675266c1b72b8c0435ccfd4293238653" http://updates.volumio.org/downloader-v1/track-device Mar 17 18:06:40 volumio volumio[634]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 17 18:06:40 volumio volumio[634]: Dload Upload Total Spent Left Speed Mar 17 18:06:40 volumio volumio[634]: [132B blob data] Mar 17 18:06:40 volumio volumio[634]: retrying in 5 seconds, trial 0 Mar 17 18:06:40 volumio volumio[634]: info: Volumio Calling Home Mar 17 18:06:40 volumio volumio[634]: info: MyVolumio login type: Token Mar 17 18:06:40 volumio volumio[634]: info: Volumio called home Mar 17 18:06:41 volumio volumio[634]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 17 18:06:41 volumio volumio[634]: info: MYVOLUMIO: Adding device Mar 17 18:06:41 volumio volumio[634]: info: MYVOLUMIO: Evaluating Server Mar 17 18:06:42 volumio volumio[634]: info: MyVolumio status changed Mar 17 18:06:42 volumio volumio[634]: info: Streaming services startup Mar 17 18:06:42 volumio volumio[634]: info: Starting Streaming Daemon Mar 17 18:06:42 volumio volumio[634]: info: Removing browser output: myVolumio user plan is not superstar Mar 17 18:06:42 volumio volumio[634]: info: Removing audio output: Mar 17 18:06:42 volumio volumio[634]: info: Stoppping Tunnel 1 Mar 17 18:06:42 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 17 18:06:42 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:42 volumio sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 17 18:06:42 volumio sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:06:42 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:42 volumio volumio[634]: error: Cannot start Volumio Streaming Daemon Mar 17 18:06:42 volumio volumio[634]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 17 18:06:42 volumio volumio[634]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 17 18:06:42 volumio sudo[1417]: pam_unix(sudo:session): session closed for user root Mar 17 18:06:42 volumio volumio[634]: info: Remote SSH Stopped Mar 17 18:06:41 volumio volumio[634]: error: Failed to ping endpoint eu5.myvolumio.org : unknown error Mar 17 18:06:41 volumio volumio[634]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 18:06:41 volumio volumio[634]: Error: Unable to resolve or reject the same promise twice Mar 17 18:06:41 volumio volumio[634]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 17 18:06:41 volumio volumio[634]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:31902) Mar 17 18:06:41 volumio volumio[634]: at Socket.emit (events.js:412:35) Mar 17 18:06:41 volumio volumio[634]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 17 18:06:41 volumio volumio[634]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 17 18:06:41 volumio volumio[634]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 18:06:41 volumio sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 18:05 Mar 17 18:06:41 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 06:04:32 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dd58c1c2d5a97e584de8089531ce548f"