-- Logs begin at Sun 2025-08-31 21:58:30 CST, end at Mon 2025-09-01 05:58:31 CST. -- Sep 01 05:52:51 volumio-x86 kernel: Linux version 6.6.32-volumio (gkkpch@UbuntuVM24.04) (gcc (Ubuntu 13.2.0-23ubuntu4) 13.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 Sep 01 05:52:51 volumio-x86 kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Sep 01 05:52:51 volumio-x86 kernel: KERNEL supported cpus: Sep 01 05:52:51 volumio-x86 kernel: Intel GenuineIntel Sep 01 05:52:51 volumio-x86 kernel: AMD AuthenticAMD Sep 01 05:52:51 volumio-x86 kernel: Hygon HygonGenuine Sep 01 05:52:51 volumio-x86 kernel: Centaur CentaurHauls Sep 01 05:52:51 volumio-x86 kernel: zhaoxin Shanghai Sep 01 05:52:51 volumio-x86 kernel: BIOS-provided physical RAM map: Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009d3ff] usable Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000000009d400-0x000000000009ffff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007c28ffff] usable Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000007c290000-0x000000007d68ffff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000007d690000-0x000000009cabefff] usable Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000009cabf000-0x000000009cebefff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000009cebf000-0x000000009cfbefff] ACPI NVS Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000009cfbf000-0x000000009cffefff] ACPI data Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000009cfff000-0x000000009cffffff] usable Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x000000009d000000-0x000000009f9fffff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x00000000feb00000-0x00000000feb0ffff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fed00000-0x00000000fee00fff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x00000000ffb80000-0x00000000ffffffff] reserved Sep 01 05:52:51 volumio-x86 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000035f5fffff] usable Sep 01 05:52:51 volumio-x86 kernel: NX (Execute Disable) protection: active Sep 01 05:52:51 volumio-x86 kernel: APIC: Static calls initialized Sep 01 05:52:51 volumio-x86 kernel: SMBIOS 2.7 present. Sep 01 05:52:51 volumio-x86 kernel: DMI: LENOVO 20312/VIUU4, BIOS 8DCN39WW 04/04/2014 Sep 01 05:52:51 volumio-x86 kernel: tsc: Fast TSC calibration using PIT Sep 01 05:52:51 volumio-x86 kernel: tsc: Detected 2493.992 MHz processor Sep 01 05:52:51 volumio-x86 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 01 05:52:51 volumio-x86 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 01 05:52:51 volumio-x86 kernel: last_pfn = 0x35f600 max_arch_pfn = 0x400000000 Sep 01 05:52:51 volumio-x86 kernel: MTRR map: 8 entries (5 fixed + 3 variable; max 25), built from 10 variable MTRRs Sep 01 05:52:51 volumio-x86 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 01 05:52:51 volumio-x86 kernel: e820: update [mem 0x9d000000-0xffffffff] usable ==> reserved Sep 01 05:52:51 volumio-x86 kernel: last_pfn = 0x9d000 max_arch_pfn = 0x400000000 Sep 01 05:52:51 volumio-x86 kernel: found SMP MP-table at [mem 0x000fe1b0-0x000fe1bf] Sep 01 05:52:51 volumio-x86 kernel: Using GB pages for direct mapping Sep 01 05:52:51 volumio-x86 kernel: RAMDISK: [mem 0x7800c000-0x7c28ffff] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Early table checksum verification disabled Sep 01 05:52:51 volumio-x86 kernel: ACPI: RSDP 0x00000000000FE020 000024 (v02 LENOVO) Sep 01 05:52:51 volumio-x86 kernel: ACPI: XSDT 0x000000009CFFE210 0000B4 (v01 LENOVO CB-01 00000001 01000013) Sep 01 05:52:51 volumio-x86 kernel: ACPI: FACP 0x000000009CFF7000 00010C (v05 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: DSDT 0x000000009CFE7000 00CB8B (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: FACS 0x000000009CFBA000 000040 Sep 01 05:52:51 volumio-x86 kernel: ACPI: SLIC 0x000000009CFFD000 000176 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: UEFI 0x000000009CFFC000 000236 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: FPDT 0x000000009CFFA000 000044 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: MSDM 0x000000009CFF9000 000055 (v03 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: ASF! 0x000000009CFF8000 0000A5 (v32 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: HPET 0x000000009CFF6000 000038 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: APIC 0x000000009CFF5000 00008C (v03 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: MCFG 0x000000009CFF4000 00003C (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0x000000009CFE6000 0007F9 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: BOOT 0x000000009CFE4000 000028 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: LPIT 0x000000009CFE3000 00005C (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: ASPT 0x000000009CFE1000 000034 (v07 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: DBGP 0x000000009CFE0000 000034 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0x000000009CFD8000 000539 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0x000000009CFD7000 000AD8 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0x000000009CFD3000 0034C6 (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0x000000009CFCE000 00399A (v01 LENOVO CB-01 00000001 ACPI 00040000) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving FACP table memory at [mem 0x9cff7000-0x9cff710b] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe7000-0x9cff3b8a] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfba000-0x9cfba03f] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving SLIC table memory at [mem 0x9cffd000-0x9cffd175] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving UEFI table memory at [mem 0x9cffc000-0x9cffc235] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving FPDT table memory at [mem 0x9cffa000-0x9cffa043] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving MSDM table memory at [mem 0x9cff9000-0x9cff9054] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving ASF! table memory at [mem 0x9cff8000-0x9cff80a4] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving HPET table memory at [mem 0x9cff6000-0x9cff6037] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving APIC table memory at [mem 0x9cff5000-0x9cff508b] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cff4000-0x9cff403b] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving SSDT table memory at [mem 0x9cfe6000-0x9cfe67f8] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving BOOT table memory at [mem 0x9cfe4000-0x9cfe4027] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving LPIT table memory at [mem 0x9cfe3000-0x9cfe305b] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving ASPT table memory at [mem 0x9cfe1000-0x9cfe1033] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving DBGP table memory at [mem 0x9cfe0000-0x9cfe0033] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving SSDT table memory at [mem 0x9cfd8000-0x9cfd8538] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving SSDT table memory at [mem 0x9cfd7000-0x9cfd7ad7] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving SSDT table memory at [mem 0x9cfd3000-0x9cfd64c5] Sep 01 05:52:51 volumio-x86 kernel: ACPI: Reserving SSDT table memory at [mem 0x9cfce000-0x9cfd1999] Sep 01 05:52:51 volumio-x86 kernel: Zone ranges: Sep 01 05:52:51 volumio-x86 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 01 05:52:51 volumio-x86 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 01 05:52:51 volumio-x86 kernel: Normal [mem 0x0000000100000000-0x000000035f5fffff] Sep 01 05:52:51 volumio-x86 kernel: Movable zone start for each node Sep 01 05:52:51 volumio-x86 kernel: Early memory node ranges Sep 01 05:52:51 volumio-x86 kernel: node 0: [mem 0x0000000000001000-0x000000000009cfff] Sep 01 05:52:51 volumio-x86 kernel: node 0: [mem 0x0000000000100000-0x000000007c28ffff] Sep 01 05:52:51 volumio-x86 kernel: node 0: [mem 0x000000007d690000-0x000000009cabefff] Sep 01 05:52:51 volumio-x86 kernel: node 0: [mem 0x000000009cfff000-0x000000009cffffff] Sep 01 05:52:51 volumio-x86 kernel: node 0: [mem 0x0000000100000000-0x000000035f5fffff] Sep 01 05:52:51 volumio-x86 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000035f5fffff] Sep 01 05:52:51 volumio-x86 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 01 05:52:51 volumio-x86 kernel: On node 0, zone DMA: 99 pages in unavailable ranges Sep 01 05:52:51 volumio-x86 kernel: On node 0, zone DMA32: 5120 pages in unavailable ranges Sep 01 05:52:51 volumio-x86 kernel: On node 0, zone DMA32: 1344 pages in unavailable ranges Sep 01 05:52:51 volumio-x86 kernel: On node 0, zone Normal: 12288 pages in unavailable ranges Sep 01 05:52:51 volumio-x86 kernel: On node 0, zone Normal: 2560 pages in unavailable ranges Sep 01 05:52:51 volumio-x86 kernel: Reserving Intel graphics memory at [mem 0x9da00000-0x9f9fffff] Sep 01 05:52:51 volumio-x86 kernel: ACPI: PM-Timer IO Port: 0x1808 Sep 01 05:52:51 volumio-x86 kernel: IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23 Sep 01 05:52:51 volumio-x86 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 01 05:52:51 volumio-x86 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 01 05:52:51 volumio-x86 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 01 05:52:51 volumio-x86 kernel: [Firmware Bug]: TSC_DEADLINE disabled due to Errata; please update microcode to version: 0x22 (or later) Sep 01 05:52:51 volumio-x86 kernel: smpboot: Allowing 8 CPUs, 4 hotplug CPUs Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x0009d000-0x0009dfff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x0009e000-0x0009ffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000dffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x000e0000-0x000fffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x7c290000-0x7d68ffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x9cabf000-0x9cebefff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x9cebf000-0x9cfbefff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x9cfbf000-0x9cffefff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x9d000000-0x9f9fffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x9fa00000-0xdfffffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xe0000000-0xefffffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xf0000000-0xfeafffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfeb00000-0xfeb0ffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfeb10000-0xfebfffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfec00000-0xfec00fff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfec01000-0xfecfffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfed00000-0xfee00fff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfee01000-0xffb7ffff] Sep 01 05:52:51 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xffb80000-0xffffffff] Sep 01 05:52:51 volumio-x86 kernel: [mem 0x9fa00000-0xdfffffff] available for PCI devices Sep 01 05:52:51 volumio-x86 kernel: Booting paravirtualized kernel on bare hardware Sep 01 05:52:51 volumio-x86 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Sep 01 05:52:51 volumio-x86 kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:8 nr_node_ids:1 Sep 01 05:52:51 volumio-x86 kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u262144 Sep 01 05:52:51 volumio-x86 kernel: pcpu-alloc: s191400 r8192 d29784 u262144 alloc=1*2097152 Sep 01 05:52:51 volumio-x86 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 6 7 Sep 01 05:52:51 volumio-x86 kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Sep 01 05:52:51 volumio-x86 kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Sep 01 05:52:51 volumio-x86 kernel: random: crng init done Sep 01 05:52:51 volumio-x86 kernel: Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: Built 1 zonelists, mobility grouping on. Total pages: 3075341 Sep 01 05:52:51 volumio-x86 kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off Sep 01 05:52:51 volumio-x86 kernel: software IO TLB: area num 8. Sep 01 05:52:51 volumio-x86 kernel: Memory: 12112264K/12497264K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 384744K reserved, 0K cma-reserved) Sep 01 05:52:51 volumio-x86 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 Sep 01 05:52:51 volumio-x86 kernel: Kernel/User page tables isolation: enabled Sep 01 05:52:51 volumio-x86 kernel: ftrace: allocating 40433 entries in 158 pages Sep 01 05:52:51 volumio-x86 kernel: ftrace: allocated 158 pages with 5 groups Sep 01 05:52:51 volumio-x86 kernel: Dynamic Preempt: voluntary Sep 01 05:52:51 volumio-x86 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 01 05:52:51 volumio-x86 kernel: Trampoline variant of Tasks RCU enabled. Sep 01 05:52:51 volumio-x86 kernel: Rude variant of Tasks RCU enabled. Sep 01 05:52:51 volumio-x86 kernel: Tracing variant of Tasks RCU enabled. Sep 01 05:52:51 volumio-x86 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Sep 01 05:52:51 volumio-x86 kernel: NR_IRQS: 4352, nr_irqs: 488, preallocated irqs: 16 Sep 01 05:52:51 volumio-x86 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 01 05:52:51 volumio-x86 kernel: Console: colour VGA+ 80x25 Sep 01 05:52:51 volumio-x86 kernel: printk: console [tty0] enabled Sep 01 05:52:51 volumio-x86 kernel: ACPI: Core revision 20230628 Sep 01 05:52:51 volumio-x86 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns Sep 01 05:52:51 volumio-x86 kernel: APIC: Switch to symmetric I/O mode setup Sep 01 05:52:51 volumio-x86 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 01 05:52:51 volumio-x86 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x23f30e551fe, max_idle_ns: 440795312231 ns Sep 01 05:52:51 volumio-x86 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 4987.98 BogoMIPS (lpj=9975968) Sep 01 05:52:51 volumio-x86 kernel: x86/cpu: VMX (outside TXT) disabled by BIOS Sep 01 05:52:51 volumio-x86 kernel: CPU0: Thermal monitoring enabled (TM1) Sep 01 05:52:51 volumio-x86 kernel: process: using mwait in idle threads Sep 01 05:52:51 volumio-x86 kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 Sep 01 05:52:51 volumio-x86 kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 Sep 01 05:52:51 volumio-x86 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 01 05:52:51 volumio-x86 kernel: Spectre V2 : Mitigation: Retpolines Sep 01 05:52:51 volumio-x86 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Sep 01 05:52:51 volumio-x86 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Sep 01 05:52:51 volumio-x86 kernel: Speculative Store Bypass: Vulnerable Sep 01 05:52:51 volumio-x86 kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Sep 01 05:52:51 volumio-x86 kernel: MMIO Stale Data: Unknown: No mitigations Sep 01 05:52:51 volumio-x86 kernel: SRBDS: Vulnerable: No microcode Sep 01 05:52:51 volumio-x86 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 01 05:52:51 volumio-x86 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 01 05:52:51 volumio-x86 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 01 05:52:51 volumio-x86 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 01 05:52:51 volumio-x86 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 01 05:52:51 volumio-x86 kernel: Freeing SMP alternatives memory: 32K Sep 01 05:52:51 volumio-x86 kernel: pid_max: default: 32768 minimum: 301 Sep 01 05:52:51 volumio-x86 kernel: LSM: initializing lsm=capability,yama,integrity Sep 01 05:52:51 volumio-x86 kernel: Yama: becoming mindful. Sep 01 05:52:51 volumio-x86 kernel: Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: smpboot: CPU0: Intel(R) Core(TM) i5-4200M CPU @ 2.50GHz (family: 0x6, model: 0x3c, stepping: 0x3) Sep 01 05:52:51 volumio-x86 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1. Sep 01 05:52:51 volumio-x86 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1. Sep 01 05:52:51 volumio-x86 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1. Sep 01 05:52:51 volumio-x86 kernel: Performance Events: PEBS fmt2+, Haswell events, 16-deep LBR, full-width counters, Intel PMU driver. Sep 01 05:52:51 volumio-x86 kernel: ... version: 3 Sep 01 05:52:51 volumio-x86 kernel: ... bit width: 48 Sep 01 05:52:51 volumio-x86 kernel: ... generic registers: 4 Sep 01 05:52:51 volumio-x86 kernel: ... value mask: 0000ffffffffffff Sep 01 05:52:51 volumio-x86 kernel: ... max period: 00007fffffffffff Sep 01 05:52:51 volumio-x86 kernel: ... fixed-purpose events: 3 Sep 01 05:52:51 volumio-x86 kernel: ... event mask: 000000070000000f Sep 01 05:52:51 volumio-x86 kernel: signal: max sigframe size: 1776 Sep 01 05:52:51 volumio-x86 kernel: Estimated ratio of average max frequency by base frequency (times 1024): 1228 Sep 01 05:52:51 volumio-x86 kernel: rcu: Hierarchical SRCU implementation. Sep 01 05:52:51 volumio-x86 kernel: rcu: Max phase no-delay instances is 1000. Sep 01 05:52:51 volumio-x86 kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. Sep 01 05:52:51 volumio-x86 kernel: smp: Bringing up secondary CPUs ... Sep 01 05:52:51 volumio-x86 kernel: smpboot: x86: Booting SMP configuration: Sep 01 05:52:51 volumio-x86 kernel: .... node #0, CPUs: #2 #1 #3 Sep 01 05:52:51 volumio-x86 kernel: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. Sep 01 05:52:51 volumio-x86 kernel: smp: Brought up 1 node, 4 CPUs Sep 01 05:52:51 volumio-x86 kernel: smpboot: Max logical packages: 2 Sep 01 05:52:51 volumio-x86 kernel: smpboot: Total of 4 processors activated (19951.93 BogoMIPS) Sep 01 05:52:51 volumio-x86 kernel: devtmpfs: initialized Sep 01 05:52:51 volumio-x86 kernel: x86/mm: Memory block size: 128MB Sep 01 05:52:51 volumio-x86 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cebf000-0x9cfbefff] (1048576 bytes) Sep 01 05:52:51 volumio-x86 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Sep 01 05:52:51 volumio-x86 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: pinctrl core: initialized pinctrl subsystem Sep 01 05:52:51 volumio-x86 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 01 05:52:51 volumio-x86 kernel: audit: initializing netlink subsys (disabled) Sep 01 05:52:51 volumio-x86 kernel: audit: type=2000 audit(1756677160.152:1): state=initialized audit_enabled=0 res=1 Sep 01 05:52:51 volumio-x86 kernel: thermal_sys: Registered thermal governor 'fair_share' Sep 01 05:52:51 volumio-x86 kernel: thermal_sys: Registered thermal governor 'bang_bang' Sep 01 05:52:51 volumio-x86 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 01 05:52:51 volumio-x86 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 01 05:52:51 volumio-x86 kernel: EISA bus registered Sep 01 05:52:51 volumio-x86 kernel: cpuidle: using governor ladder Sep 01 05:52:51 volumio-x86 kernel: cpuidle: using governor menu Sep 01 05:52:51 volumio-x86 kernel: Simple Boot Flag at 0x44 set to 0x1 Sep 01 05:52:51 volumio-x86 kernel: ACPI FADT declares the system doesn't support PCIe ASPM, so disable it Sep 01 05:52:51 volumio-x86 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 01 05:52:51 volumio-x86 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Sep 01 05:52:51 volumio-x86 kernel: PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved as E820 entry Sep 01 05:52:51 volumio-x86 kernel: PCI: Using configuration type 1 for base access Sep 01 05:52:51 volumio-x86 kernel: core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on Sep 01 05:52:51 volumio-x86 kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance' Sep 01 05:52:51 volumio-x86 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 01 05:52:51 volumio-x86 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 01 05:52:51 volumio-x86 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 01 05:52:51 volumio-x86 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 01 05:52:51 volumio-x86 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 01 05:52:51 volumio-x86 kernel: ACPI: Added _OSI(Module Device) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Added _OSI(Processor Device) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 01 05:52:51 volumio-x86 kernel: ACPI: 6 ACPI AML tables successfully acquired and loaded Sep 01 05:52:51 volumio-x86 kernel: ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored Sep 01 05:52:51 volumio-x86 kernel: ACPI: Dynamic OEM Table Load: Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0xFFFF8881002F9000 0003D3 (v01 PmRef Cpu0Cst 00003001 INTL 20121220) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Dynamic OEM Table Load: Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0xFFFF888100A03000 0005AA (v01 PmRef ApIst 00003000 INTL 20121220) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Dynamic OEM Table Load: Sep 01 05:52:51 volumio-x86 kernel: ACPI: SSDT 0xFFFF88810031A200 000119 (v01 PmRef ApCst 00003000 INTL 20121220) Sep 01 05:52:51 volumio-x86 kernel: ACPI: _OSC evaluated successfully for all CPUs Sep 01 05:52:51 volumio-x86 kernel: ACPI: EC: EC started Sep 01 05:52:51 volumio-x86 kernel: ACPI: EC: interrupt blocked Sep 01 05:52:51 volumio-x86 kernel: ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 Sep 01 05:52:51 volumio-x86 kernel: ACPI: \_SB_.PCI0.LPCB.EC0_: Boot DSDT EC used to handle transactions Sep 01 05:52:51 volumio-x86 kernel: ACPI: Interpreter enabled Sep 01 05:52:51 volumio-x86 kernel: ACPI: PM: (supports S0 S3 S4 S5) Sep 01 05:52:51 volumio-x86 kernel: ACPI: Using IOAPIC for interrupt routing Sep 01 05:52:51 volumio-x86 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 01 05:52:51 volumio-x86 kernel: PCI: Using E820 reservations for host bridge windows Sep 01 05:52:51 volumio-x86 kernel: ACPI: Enabled 10 GPEs in block 00 to 3F Sep 01 05:52:51 volumio-x86 kernel: ACPI: \NVP3: New power resource Sep 01 05:52:51 volumio-x86 kernel: ACPI: \NVP2: New power resource Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-fe]) Sep 01 05:52:51 volumio-x86 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 01 05:52:51 volumio-x86 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR] Sep 01 05:52:51 volumio-x86 kernel: acpi PNP0A08:00: FADT indicates ASPM is unsupported, using BIOS configuration Sep 01 05:52:51 volumio-x86 kernel: PCI host bridge to bus 0000:00 Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: root bus resource [mem 0x9fa00000-0xfeafffff window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: root bus resource [bus 00-fe] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:00.0: [8086:0c04] type 00 class 0x060000 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: [8086:0c01] type 01 class 0x060400 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: [8086:0c05] type 01 class 0x060400 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: [8086:0416] type 00 class 0x030000 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xb5000000-0xb53fffff 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xc0000000-0xcfffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: reg 0x20: [io 0x6000-0x603f] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:03.0: [8086:0c0c] type 00 class 0x040300 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:03.0: reg 0x10: [mem 0xb5710000-0xb5713fff 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:14.0: [8086:8c31] type 00 class 0x0c0330 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:14.0: reg 0x10: [mem 0xb5700000-0xb570ffff 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:14.0: PME# supported from D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:16.0: [8086:8c3a] type 00 class 0x078000 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:16.0: reg 0x10: [mem 0xb5718000-0xb571800f 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:16.0: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1a.0: [8086:8c2d] type 00 class 0x0c0320 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1a.0: reg 0x10: [mem 0xb571d000-0xb571d3ff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1b.0: [8086:8c20] type 00 class 0x040300 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1b.0: reg 0x10: [mem 0xb5714000-0xb5717fff 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: [8086:8c16] type 01 class 0x060400 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.4: [8086:8c18] type 01 class 0x060400 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.4: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1d.0: [8086:8c26] type 00 class 0x0c0320 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1d.0: reg 0x10: [mem 0xb571c000-0xb571c3ff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.0: [8086:8c49] type 00 class 0x060100 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: [8086:8c03] type 00 class 0x010601 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: reg 0x10: [io 0x6088-0x608f] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: reg 0x14: [io 0x6094-0x6097] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: reg 0x18: [io 0x6080-0x6087] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: reg 0x1c: [io 0x6090-0x6093] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x6060-0x607f] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xb571b000-0xb571b7ff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.2: PME# supported from D3hot Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.3: [8086:8c22] type 00 class 0x0c0500 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.3: reg 0x10: [mem 0xb5719000-0xb57190ff 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6040-0x605f] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: PCI bridge to [bus 01-06] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: bridge window [io 0x5000-0x5fff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: bridge window [mem 0xb4000000-0xb4ffffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: bridge window [mem 0xb2000000-0xb2ffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: [10de:1292] type 00 class 0x030200 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: reg 0x10: [mem 0xb3000000-0xb3ffffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: reg 0x14: [mem 0xa0000000-0xafffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: reg 0x1c: [mem 0xb0000000-0xb1ffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: reg 0x24: [io 0x4000-0x407f] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: reg 0x30: [mem 0xfff80000-0xffffffff pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: Enabling HDA controller Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: PCI bridge to [bus 07] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: bridge window [io 0x4000-0x4fff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: bridge window [mem 0xb3000000-0xb3ffffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: bridge window [mem 0xa0000000-0xb1ffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:08:00.0: [10ec:8136] type 00 class 0x020000 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:08:00.0: reg 0x10: [io 0x3000-0x30ff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:08:00.0: reg 0x18: [mem 0xb5600000-0xb5600fff 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:08:00.0: reg 0x20: [mem 0xb5400000-0xb5403fff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:08:00.0: supports D1 D2 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:08:00.0: PME# supported from D0 D1 D2 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: PCI bridge to [bus 08] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: bridge window [io 0x3000-0x3fff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: bridge window [mem 0xb5600000-0xb56fffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: bridge window [mem 0xb5400000-0xb54fffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:09:00.0: [14e4:4365] type 00 class 0x028000 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:09:00.0: reg 0x10: [mem 0xb5500000-0xb5507fff 64bit] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:09:00.0: enabling Extended Tags Sep 01 05:52:51 volumio-x86 kernel: pci 0000:09:00.0: supports D1 D2 Sep 01 05:52:51 volumio-x86 kernel: pci 0000:09:00.0: PME# supported from D0 D3hot D3cold Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.4: PCI bridge to [bus 09] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.4: bridge window [mem 0xb5500000-0xb55fffff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: on NUMA node 0 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 7 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 7 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 10 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 0 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKE disabled Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 0 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKF disabled Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 01 05:52:51 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 01 05:52:51 volumio-x86 kernel: ACPI: EC: interrupt unblocked Sep 01 05:52:51 volumio-x86 kernel: ACPI: EC: event unblocked Sep 01 05:52:51 volumio-x86 kernel: ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 Sep 01 05:52:51 volumio-x86 kernel: ACPI: EC: GPE=0x17 Sep 01 05:52:51 volumio-x86 kernel: ACPI: \_SB_.PCI0.LPCB.EC0_: Boot DSDT EC initialization complete Sep 01 05:52:51 volumio-x86 kernel: ACPI: \_SB_.PCI0.LPCB.EC0_: EC: Used to handle transactions and events Sep 01 05:52:51 volumio-x86 kernel: iommu: Default domain type: Translated Sep 01 05:52:51 volumio-x86 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 01 05:52:51 volumio-x86 kernel: SCSI subsystem initialized Sep 01 05:52:51 volumio-x86 kernel: libata version 3.00 loaded. Sep 01 05:52:51 volumio-x86 kernel: ACPI: bus type USB registered Sep 01 05:52:51 volumio-x86 kernel: usbcore: registered new interface driver usbfs Sep 01 05:52:51 volumio-x86 kernel: usbcore: registered new interface driver hub Sep 01 05:52:51 volumio-x86 kernel: usbcore: registered new device driver usb Sep 01 05:52:51 volumio-x86 kernel: PCI: Using ACPI for IRQ routing Sep 01 05:52:51 volumio-x86 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 01 05:52:51 volumio-x86 kernel: e820: reserve RAM buffer [mem 0x0009d400-0x0009ffff] Sep 01 05:52:51 volumio-x86 kernel: e820: reserve RAM buffer [mem 0x7c290000-0x7fffffff] Sep 01 05:52:51 volumio-x86 kernel: e820: reserve RAM buffer [mem 0x9cabf000-0x9fffffff] Sep 01 05:52:51 volumio-x86 kernel: e820: reserve RAM buffer [mem 0x9d000000-0x9fffffff] Sep 01 05:52:51 volumio-x86 kernel: e820: reserve RAM buffer [mem 0x35f600000-0x35fffffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 01 05:52:51 volumio-x86 kernel: vgaarb: loaded Sep 01 05:52:51 volumio-x86 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0 Sep 01 05:52:51 volumio-x86 kernel: hpet0: 8 comparators, 64-bit 14.318180 MHz counter Sep 01 05:52:51 volumio-x86 kernel: clocksource: Switched to clocksource tsc-early Sep 01 05:52:51 volumio-x86 kernel: VFS: Disk quotas dquot_6.6.0 Sep 01 05:52:51 volumio-x86 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 01 05:52:51 volumio-x86 kernel: pnp: PnP ACPI init Sep 01 05:52:51 volumio-x86 kernel: system 00:00: [io 0x0680-0x069f] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:00: [io 0xffff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:00: [io 0xffff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:00: [io 0xffff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:00: [io 0x1800-0x18fe] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:00: [io 0x164e-0x164f] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:01: [io 0x0800-0x087f] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:03: [io 0x1854-0x1857] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xfed1c000-0xfed1ffff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xfed10000-0xfed17fff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xfed18000-0xfed18fff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xfed19000-0xfed19fff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xe0000000-0xefffffff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xfed20000-0xfed3ffff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xfed90000-0xfed93fff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xff000000-0xffffffff] could not be reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0xfee00000-0xfeefffff] could not be reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0x9fa20000-0x9fa20fff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: system 00:06: [mem 0x9fa10000-0x9fa1ffff] has been reserved Sep 01 05:52:51 volumio-x86 kernel: pnp: PnP ACPI: found 7 devices Sep 01 05:52:51 volumio-x86 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 01 05:52:51 volumio-x86 kernel: NET: Registered PF_INET protocol family Sep 01 05:52:51 volumio-x86 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: tcp_listen_portaddr_hash hash table entries: 8192 (order: 5, 131072 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: TCP established hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: TCP: Hash tables configured (established 131072 bind 65536) Sep 01 05:52:51 volumio-x86 kernel: UDP hash table entries: 8192 (order: 6, 262144 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: UDP-Lite hash table entries: 8192 (order: 6, 262144 bytes, linear) Sep 01 05:52:51 volumio-x86 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: can't claim BAR 6 [mem 0xfff80000-0xffffffff pref]: no compatible bridge window Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: PCI bridge to [bus 01-06] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: bridge window [io 0x5000-0x5fff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: bridge window [mem 0xb4000000-0xb4ffffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.0: bridge window [mem 0xb2000000-0xb2ffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: BAR 6: no space for [mem size 0x00080000 pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: BAR 6: failed to assign [mem size 0x00080000 pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: PCI bridge to [bus 07] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: bridge window [io 0x4000-0x4fff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: bridge window [mem 0xb3000000-0xb3ffffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:01.1: bridge window [mem 0xa0000000-0xb1ffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: PCI bridge to [bus 08] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: bridge window [io 0x3000-0x3fff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: bridge window [mem 0xb5600000-0xb56fffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.0: bridge window [mem 0xb5400000-0xb54fffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.4: PCI bridge to [bus 09] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1c.4: bridge window [mem 0xb5500000-0xb55fffff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:00: resource 7 [mem 0x9fa00000-0xfeafffff window] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:01: resource 0 [io 0x5000-0x5fff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:01: resource 1 [mem 0xb4000000-0xb4ffffff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:01: resource 2 [mem 0xb2000000-0xb2ffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:07: resource 0 [io 0x4000-0x4fff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:07: resource 1 [mem 0xb3000000-0xb3ffffff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:07: resource 2 [mem 0xa0000000-0xb1ffffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:08: resource 0 [io 0x3000-0x3fff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:08: resource 1 [mem 0xb5600000-0xb56fffff] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:08: resource 2 [mem 0xb5400000-0xb54fffff 64bit pref] Sep 01 05:52:51 volumio-x86 kernel: pci_bus 0000:09: resource 1 [mem 0xb5500000-0xb55fffff] Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1a.0: quirk_usb_early_handoff+0x0/0x770 took 18948 usecs Sep 01 05:52:51 volumio-x86 kernel: pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x770 took 19503 usecs Sep 01 05:52:51 volumio-x86 kernel: PCI: CLS 64 bytes, default 64 Sep 01 05:52:51 volumio-x86 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 01 05:52:51 volumio-x86 kernel: software IO TLB: mapped [mem 0x0000000098abf000-0x000000009cabf000] (64MB) Sep 01 05:52:51 volumio-x86 kernel: Trying to unpack rootfs image as initramfs... Sep 01 05:52:51 volumio-x86 kernel: RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer Sep 01 05:52:51 volumio-x86 kernel: RAPL PMU: hw unit of domain pp0-core 2^-14 Joules Sep 01 05:52:51 volumio-x86 kernel: RAPL PMU: hw unit of domain package 2^-14 Joules Sep 01 05:52:51 volumio-x86 kernel: RAPL PMU: hw unit of domain dram 2^-14 Joules Sep 01 05:52:51 volumio-x86 kernel: RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules Sep 01 05:52:51 volumio-x86 kernel: Initialise system trusted keyrings Sep 01 05:52:51 volumio-x86 kernel: workingset: timestamp_bits=46 max_order=22 bucket_order=0 Sep 01 05:52:51 volumio-x86 kernel: zbud: loaded Sep 01 05:52:51 volumio-x86 kernel: Key type asymmetric registered Sep 01 05:52:51 volumio-x86 kernel: Asymmetric key parser 'x509' registered Sep 01 05:52:51 volumio-x86 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Sep 01 05:52:51 volumio-x86 kernel: io scheduler mq-deadline registered Sep 01 05:52:51 volumio-x86 kernel: io scheduler kyber registered Sep 01 05:52:51 volumio-x86 kernel: pcieport 0000:00:01.0: PME: Signaling with IRQ 24 Sep 01 05:52:51 volumio-x86 kernel: pcieport 0000:00:01.1: PME: Signaling with IRQ 25 Sep 01 05:52:51 volumio-x86 kernel: pcieport 0000:00:1c.0: PME: Signaling with IRQ 26 Sep 01 05:52:51 volumio-x86 kernel: pcieport 0000:00:1c.4: PME: Signaling with IRQ 27 Sep 01 05:52:51 volumio-x86 kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Sep 01 05:52:51 volumio-x86 kernel: ACPI: AC: AC Adapter [ADP0] (on-line) Sep 01 05:52:51 volumio-x86 kernel: input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0 Sep 01 05:52:51 volumio-x86 kernel: ACPI: button: Lid Switch [LID0] Sep 01 05:52:51 volumio-x86 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 Sep 01 05:52:51 volumio-x86 kernel: ACPI: button: Power Button [PWRB] Sep 01 05:52:51 volumio-x86 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Sep 01 05:52:51 volumio-x86 kernel: ACPI: button: Power Button [PWRF] Sep 01 05:52:51 volumio-x86 kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Sep 01 05:52:51 volumio-x86 kernel: Linux agpgart interface v0.103 Sep 01 05:52:51 volumio-x86 kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 Sep 01 05:52:51 volumio-x86 kernel: xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x0000000000009810 Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1d.0: EHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 2 Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1d.0: debug port 2 Sep 01 05:52:51 volumio-x86 kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 3 Sep 01 05:52:51 volumio-x86 kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.0 SuperSpeed Sep 01 05:52:51 volumio-x86 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Sep 01 05:52:51 volumio-x86 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 01 05:52:51 volumio-x86 kernel: usb usb1: Product: xHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: usb usb1: Manufacturer: Linux 6.6.32-volumio xhci-hcd Sep 01 05:52:51 volumio-x86 kernel: usb usb1: SerialNumber: 0000:00:14.0 Sep 01 05:52:51 volumio-x86 kernel: hub 1-0:1.0: USB hub found Sep 01 05:52:51 volumio-x86 kernel: hub 1-0:1.0: 14 ports detected Sep 01 05:52:51 volumio-x86 kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Sep 01 05:52:51 volumio-x86 kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 01 05:52:51 volumio-x86 kernel: usb usb3: Product: xHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: usb usb3: Manufacturer: Linux 6.6.32-volumio xhci-hcd Sep 01 05:52:51 volumio-x86 kernel: usb usb3: SerialNumber: 0000:00:14.0 Sep 01 05:52:51 volumio-x86 kernel: hub 3-0:1.0: USB hub found Sep 01 05:52:51 volumio-x86 kernel: hub 3-0:1.0: 4 ports detected Sep 01 05:52:51 volumio-x86 kernel: usb: port power management may be unreliable Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1d.0: irq 23, io mem 0xb571c000 Sep 01 05:52:51 volumio-x86 kernel: i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12 Sep 01 05:52:51 volumio-x86 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 01 05:52:51 volumio-x86 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 01 05:52:51 volumio-x86 kernel: mousedev: PS/2 mouse device common for all mice Sep 01 05:52:51 volumio-x86 kernel: rtc_cmos 00:02: RTC can wake from S4 Sep 01 05:52:51 volumio-x86 kernel: rtc_cmos 00:02: registered as rtc0 Sep 01 05:52:51 volumio-x86 kernel: rtc_cmos 00:02: setting system clock to 2025-08-31T21:52:40 UTC (1756677160) Sep 01 05:52:51 volumio-x86 kernel: rtc_cmos 00:02: alarms up to one month, 242 bytes nvram, hpet irqs Sep 01 05:52:51 volumio-x86 kernel: i2c_dev: i2c /dev entries driver Sep 01 05:52:51 volumio-x86 kernel: platform eisa.0: Probing EISA bus 0 Sep 01 05:52:51 volumio-x86 kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Sep 01 05:52:51 volumio-x86 kernel: intel_pstate: Intel P-state driver initializing Sep 01 05:52:51 volumio-x86 kernel: ledtrig-cpu: registered to indicate activity on CPUs Sep 01 05:52:51 volumio-x86 kernel: NET: Registered PF_INET6 protocol family Sep 01 05:52:51 volumio-x86 kernel: Segment Routing with IPv6 Sep 01 05:52:51 volumio-x86 kernel: In-situ OAM (IOAM) with IPv6 Sep 01 05:52:51 volumio-x86 kernel: mip6: Mobile IPv6 Sep 01 05:52:51 volumio-x86 kernel: NET: Registered PF_PACKET protocol family Sep 01 05:52:51 volumio-x86 kernel: mpls_gso: MPLS GSO support Sep 01 05:52:51 volumio-x86 kernel: microcode: Microcode Update Driver: v2.2. Sep 01 05:52:51 volumio-x86 kernel: IPI shorthand broadcast: enabled Sep 01 05:52:51 volumio-x86 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3 Sep 01 05:52:51 volumio-x86 kernel: sched_clock: Marking stable (362639542, 1902510)->(371553631, -7011579) Sep 01 05:52:51 volumio-x86 kernel: Loading compiled-in X.509 certificates Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00 Sep 01 05:52:51 volumio-x86 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Sep 01 05:52:51 volumio-x86 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 01 05:52:51 volumio-x86 kernel: usb usb2: Product: EHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: usb usb2: Manufacturer: Linux 6.6.32-volumio ehci_hcd Sep 01 05:52:51 volumio-x86 kernel: usb usb2: SerialNumber: 0000:00:1d.0 Sep 01 05:52:51 volumio-x86 kernel: hub 2-0:1.0: USB hub found Sep 01 05:52:51 volumio-x86 kernel: hub 2-0:1.0: 2 ports detected Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1a.0: EHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 4 Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1a.0: debug port 2 Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1a.0: irq 16, io mem 0xb571d000 Sep 01 05:52:51 volumio-x86 kernel: ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00 Sep 01 05:52:51 volumio-x86 kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Sep 01 05:52:51 volumio-x86 kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 01 05:52:51 volumio-x86 kernel: usb usb4: Product: EHCI Host Controller Sep 01 05:52:51 volumio-x86 kernel: usb usb4: Manufacturer: Linux 6.6.32-volumio ehci_hcd Sep 01 05:52:51 volumio-x86 kernel: usb usb4: SerialNumber: 0000:00:1a.0 Sep 01 05:52:51 volumio-x86 kernel: hub 4-0:1.0: USB hub found Sep 01 05:52:51 volumio-x86 kernel: hub 4-0:1.0: 2 ports detected Sep 01 05:52:51 volumio-x86 kernel: clk: Disabling unused clocks Sep 01 05:52:51 volumio-x86 kernel: ACPI: battery: Slot [BAT0] (battery present) Sep 01 05:52:51 volumio-x86 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Sep 01 05:52:51 volumio-x86 kernel: usb 2-1: new high-speed USB device number 2 using ehci-pci Sep 01 05:52:51 volumio-x86 kernel: usb 4-1: new high-speed USB device number 2 using ehci-pci Sep 01 05:52:51 volumio-x86 kernel: usb 1-1: New USB device found, idVendor=5986, idProduct=029d, bcdDevice=14.09 Sep 01 05:52:51 volumio-x86 kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 01 05:52:51 volumio-x86 kernel: usb 1-1: Product: Lenovo EasyCamera Sep 01 05:52:51 volumio-x86 kernel: usb 1-1: Manufacturer: Vimicro Corp. Sep 01 05:52:51 volumio-x86 kernel: usb 2-1: New USB device found, idVendor=8087, idProduct=8000, bcdDevice= 0.05 Sep 01 05:52:51 volumio-x86 kernel: usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 01 05:52:51 volumio-x86 kernel: hub 2-1:1.0: USB hub found Sep 01 05:52:51 volumio-x86 kernel: hub 2-1:1.0: 8 ports detected Sep 01 05:52:51 volumio-x86 kernel: usb 4-1: New USB device found, idVendor=8087, idProduct=8008, bcdDevice= 0.05 Sep 01 05:52:51 volumio-x86 kernel: usb 4-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 01 05:52:51 volumio-x86 kernel: hub 4-1:1.0: USB hub found Sep 01 05:52:51 volumio-x86 kernel: hub 4-1:1.0: 6 ports detected Sep 01 05:52:51 volumio-x86 kernel: usb 1-7: new full-speed USB device number 3 using xhci_hcd Sep 01 05:52:51 volumio-x86 kernel: usb 1-7: New USB device found, idVendor=105b, idProduct=e065, bcdDevice= 1.12 Sep 01 05:52:51 volumio-x86 kernel: usb 1-7: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 01 05:52:51 volumio-x86 kernel: usb 1-7: Product: BCM43142A0 Sep 01 05:52:51 volumio-x86 kernel: usb 1-7: Manufacturer: Broadcom Corp Sep 01 05:52:51 volumio-x86 kernel: usb 1-7: SerialNumber: 0071CC99A6AA Sep 01 05:52:51 volumio-x86 kernel: Freeing initrd memory: 68112K Sep 01 05:52:51 volumio-x86 kernel: Freeing unused kernel image (initmem) memory: 2988K Sep 01 05:52:51 volumio-x86 kernel: Write protecting the kernel read-only data: 20480k Sep 01 05:52:51 volumio-x86 kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K Sep 01 05:52:51 volumio-x86 kernel: Run /init as init process Sep 01 05:52:51 volumio-x86 kernel: with arguments: Sep 01 05:52:51 volumio-x86 kernel: /init Sep 01 05:52:51 volumio-x86 kernel: splash Sep 01 05:52:51 volumio-x86 kernel: with environment: Sep 01 05:52:51 volumio-x86 kernel: HOME=/ Sep 01 05:52:51 volumio-x86 kernel: TERM=linux Sep 01 05:52:51 volumio-x86 kernel: BOOT_IMAGE=vmlinuz Sep 01 05:52:51 volumio-x86 kernel: biosdevname=0 Sep 01 05:52:51 volumio-x86 kernel: imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 Sep 01 05:52:51 volumio-x86 kernel: bootpart=UUID=79FB-2BA3 Sep 01 05:52:51 volumio-x86 kernel: datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd Sep 01 05:52:51 volumio-x86 kernel: hwdevice=x86 Sep 01 05:52:51 volumio-x86 kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Sep 01 05:52:51 volumio-x86 kernel: imgfile=/volumio_current.sqsh Sep 01 05:52:51 volumio-x86 kernel: use_kmsg=no Sep 01 05:52:51 volumio-x86 kernel: usb 1-10: new high-speed USB device number 4 using xhci_hcd Sep 01 05:52:51 volumio-x86 kernel: wmi_bus wmi_bus-PNP0C14:00: [Firmware Info]: 00000000-0000-0000-0000-000000000000 has zero instances Sep 01 05:52:51 volumio-x86 kernel: wmi_bus wmi_bus-PNP0C14:00: [Firmware Info]: 00000000-0000-0000-0000-000000000000 has zero instances Sep 01 05:52:51 volumio-x86 kernel: wmi_bus wmi_bus-PNP0C14:00: [Firmware Info]: 00000000-0000-0000-0000-000000000000 has zero instances Sep 01 05:52:51 volumio-x86 kernel: wmi_bus wmi_bus-PNP0C14:00: [Firmware Info]: 00000000-0000-0000-0000-000000000000 has zero instances Sep 01 05:52:51 volumio-x86 kernel: ACPI: bus type drm_connector registered Sep 01 05:52:51 volumio-x86 kernel: ahci 0000:00:1f.2: version 3.0 Sep 01 05:52:51 volumio-x86 kernel: ahci 0000:00:1f.2: SSS flag set, parallel bus scan disabled Sep 01 05:52:51 volumio-x86 kernel: ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 4 ports 6 Gbps 0x14 impl SATA mode Sep 01 05:52:51 volumio-x86 kernel: ahci 0000:00:1f.2: flags: 64bit ncq stag pm led clo pio slum part ems apst Sep 01 05:52:51 volumio-x86 kernel: i801_smbus 0000:00:1f.3: SPD Write Disable is set Sep 01 05:52:51 volumio-x86 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 01 05:52:51 volumio-x86 kernel: i2c i2c-0: 2/4 memory slots populated (from DMI) Sep 01 05:52:51 volumio-x86 kernel: i2c i2c-0: Successfully instantiated SPD at 0x50 Sep 01 05:52:51 volumio-x86 kernel: i2c i2c-0: Successfully instantiated SPD at 0x52 Sep 01 05:52:51 volumio-x86 kernel: scsi host0: ahci Sep 01 05:52:51 volumio-x86 kernel: scsi host1: ahci Sep 01 05:52:51 volumio-x86 kernel: scsi host2: ahci Sep 01 05:52:51 volumio-x86 kernel: scsi host3: ahci Sep 01 05:52:51 volumio-x86 kernel: scsi host4: ahci Sep 01 05:52:51 volumio-x86 kernel: ata1: DUMMY Sep 01 05:52:51 volumio-x86 kernel: ata2: DUMMY Sep 01 05:52:51 volumio-x86 kernel: ata3: SATA max UDMA/133 abar m2048@0xb571b000 port 0xb571b200 irq 29 Sep 01 05:52:51 volumio-x86 kernel: ata4: DUMMY Sep 01 05:52:51 volumio-x86 kernel: ata5: SATA max UDMA/133 abar m2048@0xb571b000 port 0xb571b300 irq 29 Sep 01 05:52:51 volumio-x86 kernel: r8169 0000:08:00.0: can't disable ASPM; OS doesn't have ASPM control Sep 01 05:52:51 volumio-x86 kernel: r8169 0000:08:00.0 eth0: RTL8106e, 68:f7:28:99:c4:4d, XID 449, IRQ 30 Sep 01 05:52:51 volumio-x86 kernel: usb 1-10: New USB device found, idVendor=24a9, idProduct=205a, bcdDevice= 2.00 Sep 01 05:52:51 volumio-x86 kernel: usb 1-10: New USB device strings: Mfr=2, Product=3, SerialNumber=4 Sep 01 05:52:51 volumio-x86 kernel: usb 1-10: Product: USB_0114 Sep 01 05:52:51 volumio-x86 kernel: usb 1-10: Manufacturer: ASolid Sep 01 05:52:51 volumio-x86 kernel: usb 1-10: SerialNumber: 37392825 Sep 01 05:52:51 volumio-x86 kernel: usb-storage 1-10:1.0: USB Mass Storage device detected Sep 01 05:52:51 volumio-x86 kernel: scsi host5: usb-storage 1-10:1.0 Sep 01 05:52:51 volumio-x86 kernel: usbcore: registered new interface driver usb-storage Sep 01 05:52:51 volumio-x86 kernel: tsc: Refined TSC clocksource calibration: 2494.228 MHz Sep 01 05:52:51 volumio-x86 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x23f3ed6cc7b, max_idle_ns: 440795302954 ns Sep 01 05:52:51 volumio-x86 kernel: clocksource: Switched to clocksource tsc Sep 01 05:52:51 volumio-x86 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 01 05:52:51 volumio-x86 kernel: ata3.00: ATAPI: PLDS DVD-RW DU8A5SH, BL61, max UDMA/100 Sep 01 05:52:51 volumio-x86 kernel: ata3.00: configured for UDMA/100 Sep 01 05:52:51 volumio-x86 kernel: scsi 2:0:0:0: CD-ROM PLDS DVD-RW DU8A5SH BL61 PQ: 0 ANSI: 5 Sep 01 05:52:51 volumio-x86 kernel: psmouse serio1: synaptics: queried max coordinates: x [..5664], y [..4682] Sep 01 05:52:51 volumio-x86 kernel: psmouse serio1: synaptics: queried min coordinates: x [1278..], y [1172..] Sep 01 05:52:51 volumio-x86 kernel: psmouse serio1: synaptics: The touchpad can support a better bus than the too old PS/2 protocol. Make sure MOUSE_PS2_SYNAPTICS_SMBUS and RMI4_SMB are enabled to get a better touchpad experience. Sep 01 05:52:51 volumio-x86 kernel: ata5: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Sep 01 05:52:51 volumio-x86 kernel: psmouse serio1: synaptics: Touchpad model: 1, fw: 8.1, id: 0x1e2b1, caps: 0xd00123/0x840300/0x127c00/0x0, board id: 2334, fw id: 1508589 Sep 01 05:52:51 volumio-x86 kernel: ata5.00: LPM support broken, forcing max_power Sep 01 05:52:51 volumio-x86 kernel: ata5.00: ATA-8: ST1000LM024 HN-M101MBB, 2BA30001, max UDMA/100 Sep 01 05:52:51 volumio-x86 kernel: ata5.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 32) Sep 01 05:52:51 volumio-x86 kernel: ata5.00: LPM support broken, forcing max_power Sep 01 05:52:51 volumio-x86 kernel: ata5.00: configured for UDMA/100 Sep 01 05:52:51 volumio-x86 kernel: scsi 4:0:0:0: Direct-Access ATA ST1000LM024 HN-M 0001 PQ: 0 ANSI: 5 Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: [sda] 4096-byte physical blocks Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: [sda] Write Protect is off Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: [sda] Mode Sense: 00 3a 00 00 Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 01 05:52:51 volumio-x86 kernel: input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio1/input/input5 Sep 01 05:52:51 volumio-x86 kernel: scsi 5:0:0:0: Direct-Access ASolid USB PQ: 0 ANSI: 4 Sep 01 05:52:51 volumio-x86 kernel: sd 5:0:0:0: [sdb] 61440001 512-byte logical blocks: (31.5 GB/29.3 GiB) Sep 01 05:52:51 volumio-x86 kernel: sd 5:0:0:0: [sdb] Write Protect is off Sep 01 05:52:51 volumio-x86 kernel: sd 5:0:0:0: [sdb] Mode Sense: 23 00 00 00 Sep 01 05:52:51 volumio-x86 kernel: sd 5:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Sep 01 05:52:51 volumio-x86 kernel: sdb: sdb1 sdb2 sdb3 sdb4 Sep 01 05:52:51 volumio-x86 kernel: sd 5:0:0:0: [sdb] Attached SCSI removable disk Sep 01 05:52:51 volumio-x86 kernel: sda: sda1 sda2 < sda5 sda6 > sda3 sda4 Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: [sda] Attached SCSI disk Sep 01 05:52:51 volumio-x86 kernel: usbcore: registered new interface driver uas Sep 01 05:52:51 volumio-x86 kernel: ACPI Warning: \_SB.PCI0.PEG1.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20230628/nsarguments-61) Sep 01 05:52:51 volumio-x86 kernel: pci 0000:07:00.0: optimus capabilities: enabled, status dynamic power, hda bios codec supported Sep 01 05:52:51 volumio-x86 kernel: VGA switcheroo: detected Optimus DSM method \_SB_.PCI0.PEG1.PEGP handle Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: enabling device (0006 -> 0007) Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: NVIDIA GK208 (108120a1) Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: bios: version 80.28.2c.00.08 Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: fb: 2048 MiB DDR3 Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: DRM: VRAM: 2048 MiB Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: DRM: GART: 1048576 MiB Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: DRM: Pointer to TMDS table not found Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: DRM: DCB version 4.0 Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: DRM: MM: using COPY for buffer copies Sep 01 05:52:51 volumio-x86 kernel: [drm] Initialized nouveau 1.4.0 20120801 for 0000:07:00.0 on minor 0 Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: [drm] No compatible format found Sep 01 05:52:51 volumio-x86 kernel: nouveau 0000:07:00.0: [drm] Cannot find any crtc or sizes Sep 01 05:52:51 volumio-x86 kernel: i915 0000:00:02.0: vgaarb: deactivate vga console Sep 01 05:52:51 volumio-x86 kernel: Console: switching to colour dummy device 80x25 Sep 01 05:52:51 volumio-x86 kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem Sep 01 05:52:51 volumio-x86 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray Sep 01 05:52:51 volumio-x86 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 01 05:52:51 volumio-x86 kernel: i915 0000:00:02.0: [drm] Skipping intel_backlight registration Sep 01 05:52:51 volumio-x86 kernel: [drm] Initialized i915 1.6.0 20201103 for 0000:00:02.0 on minor 1 Sep 01 05:52:51 volumio-x86 kernel: ACPI: video: [Firmware Bug]: ACPI(PEGP) defines _DOD but not _DOS Sep 01 05:52:51 volumio-x86 kernel: ACPI: video: Video Device [PEGP] (multi-head: yes rom: yes post: no) Sep 01 05:52:51 volumio-x86 kernel: ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PCI0.GFX0.DD02._BCL], AE_NOT_FOUND (20230628/psargs-330) Sep 01 05:52:51 volumio-x86 kernel: ACPI Error: Aborting method \_SB.PCI0.PEG1.PEGP.DD02._BCL due to previous error (AE_NOT_FOUND) (20230628/psparse-529) Sep 01 05:52:51 volumio-x86 kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:4b/LNXVIDEO:00/input/input6 Sep 01 05:52:51 volumio-x86 kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Sep 01 05:52:51 volumio-x86 kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:01/input/input7 Sep 01 05:52:51 volumio-x86 kernel: vga_switcheroo: enabled Sep 01 05:52:51 volumio-x86 kernel: fbcon: i915drmfb (fb0) is primary device Sep 01 05:52:51 volumio-x86 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 01 05:52:51 volumio-x86 kernel: Console: switching to colour frame buffer device 170x48 Sep 01 05:52:51 volumio-x86 kernel: i915 0000:00:02.0: [drm] fb0: i915drmfb frame buffer device Sep 01 05:52:51 volumio-x86 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 01 05:52:51 volumio-x86 kernel: sdhci: Secure Digital Host Controller Interface driver Sep 01 05:52:51 volumio-x86 kernel: sdhci: Copyright(c) Pierre Ossman Sep 01 05:52:51 volumio-x86 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 01 05:52:51 volumio-x86 kernel: usbcore: registered new interface driver usbhid Sep 01 05:52:51 volumio-x86 kernel: usbhid: USB HID core driver Sep 01 05:52:51 volumio-x86 kernel: fuse: init (API version 7.39) Sep 01 05:52:51 volumio-x86 kernel: [drm] radeon kernel modesetting enabled. Sep 01 05:52:51 volumio-x86 kernel: scsi host6: pata_legacy Sep 01 05:52:51 volumio-x86 kernel: ata6: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 Sep 01 05:52:51 volumio-x86 kernel: scsi host6: pata_legacy Sep 01 05:52:51 volumio-x86 kernel: ata7: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 Sep 01 05:52:51 volumio-x86 kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 01 05:52:51 volumio-x86 kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 01 05:52:51 volumio-x86 kernel: EXT4-fs (sdb2): recovery complete Sep 01 05:52:51 volumio-x86 kernel: EXT4-fs (sdb2): mounted filesystem d35eb915-f24a-437a-a43b-4afe991304f7 r/w with ordered data mode. Quota mode: none. Sep 01 05:52:51 volumio-x86 kernel: loop: module loaded Sep 01 05:52:51 volumio-x86 kernel: block device autoloading is deprecated and will be removed. Sep 01 05:52:51 volumio-x86 kernel: loop0: detected capacity change from 0 to 1725144 Sep 01 05:52:51 volumio-x86 kernel: EXT4-fs (sdb3): mounted filesystem 2fc5abe1-9579-4377-a3f7-b00c1ebaeecd r/w with ordered data mode. Quota mode: none. Sep 01 05:52:51 volumio-x86 kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Sep 01 05:52:51 volumio-x86 systemd[1]: Inserted module 'autofs4' Sep 01 05:52:51 volumio-x86 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) Sep 01 05:52:51 volumio-x86 systemd[1]: Detected architecture x86-64. Sep 01 05:52:51 volumio-x86 systemd[1]: Set hostname to . Sep 01 05:52:51 volumio-x86 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. Sep 01 05:52:51 volumio-x86 systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Sep 01 05:52:51 volumio-x86 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. Sep 01 05:52:51 volumio-x86 systemd[1]: Listening on udev Kernel Socket. Sep 01 05:52:51 volumio-x86 systemd[1]: Listening on Journal Socket. Sep 01 05:52:51 volumio-x86 systemd[1]: Started dynamicswap service. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounting POSIX Message Queue File System... Sep 01 05:52:51 volumio-x86 systemd-journald[504]: Journal started Sep 01 05:52:51 volumio-x86 systemd-journald[504]: Runtime journal (/run/log/journal/724bcbf01eebf9d6210f389b672e577a) is 3.7M, max 30.0M, 26.2M free. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted POSIX Message Queue File System. Sep 01 05:52:51 volumio-x86 systemd[1]: Started Remount Root and Kernel File Systems. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted Kernel Debug File System. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted Huge Pages File System. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted Kernel Configuration File System. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted FUSE Control File System. Sep 01 05:52:51 volumio-x86 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 01 05:52:51 volumio-x86 systemd[1]: Starting Load/Save Random Seed... Sep 01 05:52:51 volumio-x86 systemd[1]: Starting Create System Users... Sep 01 05:52:51 volumio-x86 systemd[1]: Started Apply Kernel Variables. Sep 01 05:52:51 volumio-x86 fake-hwclock[505]: Current system time: 2025-08-31 21:52:51 Sep 01 05:52:51 volumio-x86 fake-hwclock[505]: fake-hwclock saved clock information is in the past: 2025-08-31 13:01:17 Sep 01 05:52:51 volumio-x86 fake-hwclock[505]: To set system time to this saved clock anyway, use "force" Sep 01 05:52:51 volumio-x86 systemd[1]: Started Restore / save the current clock. Sep 01 05:52:51 volumio-x86 systemd[1]: Started Load/Save Random Seed. Sep 01 05:52:51 volumio-x86 kernel: RPC: Registered named UNIX socket transport module. Sep 01 05:52:51 volumio-x86 kernel: RPC: Registered udp transport module. Sep 01 05:52:51 volumio-x86 kernel: RPC: Registered tcp transport module. Sep 01 05:52:51 volumio-x86 kernel: RPC: Registered tcp-with-tls transport module. Sep 01 05:52:51 volumio-x86 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted RPC Pipe File System. Sep 01 05:52:51 volumio-x86 systemd[1]: Started Create System Users. Sep 01 05:52:51 volumio-x86 systemd[1]: Starting Create Static Device Nodes in /dev... Sep 01 05:52:51 volumio-x86 systemd[1]: dynamicswap.service: Succeeded. Sep 01 05:52:51 volumio-x86 systemd[1]: Started Create Static Device Nodes in /dev. Sep 01 05:52:51 volumio-x86 systemd[1]: Starting udev Kernel Device Manager... Sep 01 05:52:51 volumio-x86 systemd[1]: Reached target Local File Systems (Pre). Sep 01 05:52:51 volumio-x86 systemd[1]: Mounting /var/spool/cups... Sep 01 05:52:51 volumio-x86 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounting /tmp... Sep 01 05:52:51 volumio-x86 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounting /var/log... Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted /var/spool/cups. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted /tmp. Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted /var/log. Sep 01 05:52:51 volumio-x86 systemd[1]: Starting Flush Journal to Persistent Storage... Sep 01 05:52:51 volumio-x86 systemd[1]: Mounting /var/spool/cups/tmp... Sep 01 05:52:51 volumio-x86 systemd[1]: Mounted /var/spool/cups/tmp. Sep 01 05:52:51 volumio-x86 systemd-udevd[528]: Network interface NamePolicy= disabled on kernel command line, ignoring. Sep 01 05:52:51 volumio-x86 systemd-journald[504]: Runtime journal (/run/log/journal/724bcbf01eebf9d6210f389b672e577a) is 3.7M, max 30.0M, 26.2M free. Sep 01 05:52:51 volumio-x86 systemd[1]: Started Flush Journal to Persistent Storage. Sep 01 05:52:51 volumio-x86 systemd[1]: Started udev Kernel Device Manager. Sep 01 05:52:51 volumio-x86 systemd[1]: Started udev Coldplug all Devices. Sep 01 05:52:51 volumio-x86 systemd[1]: Starting Show Plymouth Boot Screen... Sep 01 05:52:51 volumio-x86 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Sep 01 05:52:51 volumio-x86 systemd[1]: plymouth-start.service: Succeeded. Sep 01 05:52:51 volumio-x86 systemd[1]: Started Show Plymouth Boot Screen. Sep 01 05:52:51 volumio-x86 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 01 05:52:51 volumio-x86 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 01 05:52:51 volumio-x86 systemd[1]: Reached target Local Encrypted Volumes. Sep 01 05:52:51 volumio-x86 systemd[1]: Reached target Paths. Sep 01 05:52:51 volumio-x86 systemd-udevd[554]: Using default interface naming scheme 'v240'. Sep 01 05:52:51 volumio-x86 systemd-udevd[554]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 01 05:52:51 volumio-x86 kernel: sr 2:0:0:0: Attached scsi generic sg0 type 5 Sep 01 05:52:51 volumio-x86 systemd-udevd[542]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 01 05:52:51 volumio-x86 kernel: sd 4:0:0:0: Attached scsi generic sg1 type 0 Sep 01 05:52:51 volumio-x86 kernel: sd 5:0:0:0: Attached scsi generic sg2 type 0 Sep 01 05:52:51 volumio-x86 kernel: at24 0-0050: supply vcc not found, using dummy regulator Sep 01 05:52:51 volumio-x86 kernel: at24 0-0050: 256 byte spd EEPROM, read-only Sep 01 05:52:51 volumio-x86 kernel: at24 0-0052: supply vcc not found, using dummy regulator Sep 01 05:52:51 volumio-x86 kernel: at24 0-0052: 256 byte spd EEPROM, read-only Sep 01 05:52:51 volumio-x86 kernel: cryptd: max_cpu_qlen set to 1000 Sep 01 05:52:51 volumio-x86 systemd-udevd[536]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:51 volumio-x86 kernel: AVX2 version of gcm_enc/dec engaged. Sep 01 05:52:51 volumio-x86 kernel: AES CTR mode by8 optimization enabled Sep 01 05:52:51 volumio-x86 systemd[1]: Started Helper to synchronize boot up for ifupdown. Sep 01 05:52:51 volumio-x86 kernel: input: Ideapad extra buttons as /devices/pci0000:00/0000:00:1f.0/PNP0C09:00/VPC2004:00/input/input8 Sep 01 05:52:51 volumio-x86 kernel: ideapad_acpi VPC2004:00: Keyboard backlight control not available Sep 01 05:52:51 volumio-x86 kernel: ideapad_acpi VPC2004:00: DYTC interface is not available Sep 01 05:52:51 volumio-x86 kernel: snd_hda_intel 0000:00:03.0: bound 0000:00:02.0 (ops __SCT__tp_func_intel_frontbuffer_flush [i915]) Sep 01 05:52:52 volumio-x86 kernel: input: HDA Intel HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:03.0/sound/card0/input9 Sep 01 05:52:52 volumio-x86 kernel: input: HDA Intel HDMI HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:03.0/sound/card0/input10 Sep 01 05:52:52 volumio-x86 kernel: input: HDA Intel HDMI HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:03.0/sound/card0/input11 Sep 01 05:52:52 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC282: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker Sep 01 05:52:52 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Sep 01 05:52:52 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: hp_outs=1 (0x21/0x0/0x0/0x0/0x0) Sep 01 05:52:52 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: mono: mono_out=0x0 Sep 01 05:52:52 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: inputs: Sep 01 05:52:52 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: Mic=0x19 Sep 01 05:52:52 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: Internal Mic=0x12 Sep 01 05:52:52 volumio-x86 systemd-udevd[555]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[569]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[552]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[541]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 kernel: input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card1/input12 Sep 01 05:52:52 volumio-x86 kernel: input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card1/input13 Sep 01 05:52:52 volumio-x86 systemd-udevd[547]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[549]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[570]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[539]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[566]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[568]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[567]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: Core ver 2.22 Sep 01 05:52:52 volumio-x86 kernel: NET: Registered PF_BLUETOOTH protocol family Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: HCI device and connection manager initialized Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: HCI socket layer initialized Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: L2CAP socket layer initialized Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: SCO socket layer initialized Sep 01 05:52:52 volumio-x86 systemd-udevd[552]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 systemd-udevd[541]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 05:52:52 volumio-x86 kernel: usbcore: registered new interface driver btusb Sep 01 05:52:52 volumio-x86 kernel: alsactl[691]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Sep 01 05:52:52 volumio-x86 systemd-udevd[548]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 99. Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: hci0: BCM: chip id 70 Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: hci0: BCM: features 0x06 Sep 01 05:52:52 volumio-x86 systemd[1]: Found device USB boot. Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: hci0: BCM43142A Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: hci0: BCM43142A0 (001.001.011) build 0000 Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: hci0: BCM: firmware Patch file not found, tried: Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: hci0: BCM: 'brcm/BCM43142A0-105b-e065.hcd' Sep 01 05:52:52 volumio-x86 kernel: Bluetooth: hci0: BCM: 'brcm/BCM-105b-e065.hcd' Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in RTL810xE PCI Express Fast Ethernet controller being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Show Plymouth Boot Screen... Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Created slice system-systemd\x2dbacklight.slice. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:ideapad... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting File System Check on /dev/disk/by-uuid/79FB-2BA3... Sep 01 05:52:52 volumio-x86 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Load/Save RF Kill Switch Status... Sep 01 05:52:52 volumio-x86 systemd[1]: Started File System Check Daemon to report status. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Load/Save RF Kill Switch Status. Sep 01 05:52:52 volumio-x86 systemd[1]: plymouth-start.service: Succeeded. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Show Plymouth Boot Screen. Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:ideapad. Sep 01 05:52:52 volumio-x86 systemd-fsck[737]: fsck.fat 4.1 (2017-01-24) Sep 01 05:52:52 volumio-x86 systemd-fsck[737]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sep 01 05:52:52 volumio-x86 systemd-fsck[737]: Automatically removing dirty bit. Sep 01 05:52:52 volumio-x86 systemd-fsck[737]: Performing changes. Sep 01 05:52:52 volumio-x86 systemd-fsck[737]: /dev/sdb1: 28 files, 175919/344788 clusters Sep 01 05:52:52 volumio-x86 systemd[1]: Started File System Check on /dev/disk/by-uuid/79FB-2BA3. Sep 01 05:52:52 volumio-x86 systemd[1]: Mounting /boot... Sep 01 05:52:52 volumio-x86 kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 01 05:52:52 volumio-x86 systemd[1]: Mounted /boot. Sep 01 05:52:52 volumio-x86 systemd[1]: Reached target Local File Systems. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Create Volatile Files and Directories... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 01 05:52:52 volumio-x86 systemd[1]: Started ifup for eth0. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Raise network interfaces... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Preprocess NFS configuration... Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Received SIGRTMIN+20 from PID 375 (plymouthd). Sep 01 05:52:52 volumio-x86 systemd[1]: plymouth-read-write.service: Succeeded. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 01 05:52:52 volumio-x86 systemd[1]: nfs-config.service: Succeeded. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Preprocess NFS configuration. Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Reached target NFS client services. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Create Volatile Files and Directories. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting RPC bind portmap service... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 01 05:52:52 volumio-x86 systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 01 05:52:52 volumio-x86 systemd[1]: Reached target System Initialization. Sep 01 05:52:52 volumio-x86 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Sep 01 05:52:52 volumio-x86 systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Daily apt download activities. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 01 05:52:52 volumio-x86 systemd[1]: Listening on triggerhappy.socket. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Daily apt upgrade and clean activities. Sep 01 05:52:52 volumio-x86 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 01 05:52:52 volumio-x86 systemd[1]: Reached target Sockets. Sep 01 05:52:52 volumio-x86 systemd[1]: Started Daily man-db regeneration. Sep 01 05:52:52 volumio-x86 systemd[1]: Reached target Timers. Sep 01 05:52:52 volumio-x86 systemd[1]: Reached target Basic System. Sep 01 05:52:52 volumio-x86 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Sep 01 05:52:52 volumio-x86 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Volumio Time Update Utility... Sep 01 05:52:52 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting triggerhappy global hotkey daemon... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Wireless Services... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 01 05:52:52 volumio-x86 systemd[1]: Started Manage Sound Card State (restore and store). Sep 01 05:52:52 volumio-x86 ifup[748]: command failed: No such device (-19) Sep 01 05:52:52 volumio-x86 systemd[1]: Started My Shell Script. Sep 01 05:52:52 volumio-x86 sh[747]: command failed: No such device (-19) Sep 01 05:52:52 volumio-x86 systemd[1]: Started D-Bus System Message Bus. Sep 01 05:52:52 volumio-x86 alsactl[785]: alsactl 1.1.8 daemon started Sep 01 05:52:52 volumio-x86 systemd[1]: Started Volumio Log Rotation Service. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting WPA supplicant... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Bluetooth service... Sep 01 05:52:52 volumio-x86 systemd[1]: Started MPD Monitor Service. Sep 01 05:52:52 volumio-x86 systemd[1]: Started volumio-remote-updater.service. Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting DHCP Client Daemon... Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Save/Restore Sound Card State... Sep 01 05:52:52 volumio-x86 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Login Service... Sep 01 05:52:52 volumio-x86 systemd[1]: Started Volumio Iptables Module. Sep 01 05:52:52 volumio-x86 systemd[1]: Starting Daily man-db regeneration... Sep 01 05:52:52 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:52:52 volumio-x86 alsactl[785]: Found hardware: "HDA-Intel" "Intel Haswell HDMI" "HDA:80862807,80860101,00100000" "0x17aa" "0x3978" Sep 01 05:52:52 volumio-x86 alsactl[785]: Hardware is initialized using a generic method Sep 01 05:52:52 volumio-x86 alsactl[785]: Found hardware: "HDA-Intel" "Realtek ALC282" "HDA:10ec0282,17aa3801,00100003" "0x17aa" "0x3978" Sep 01 05:52:52 volumio-x86 alsactl[785]: Hardware is initialized using a generic method Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #3 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #4 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #6 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #7 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #8 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #9 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #10 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #11 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #12 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #13 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #14 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #15 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #16 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #17 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #18 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #19 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #20 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #23 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #24 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #25 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #26 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #27 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[785]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #30 (No such file or directory) Sep 01 05:52:52 volumio-x86 thd[781]: Unable to parse trigger line: Sep 01 05:52:52 volumio-x86 thd[781]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 01 05:52:52 volumio-x86 thd[781]: Unable to parse trigger line: Sep 01 05:52:52 volumio-x86 thd[781]: Found socket passed from systemd Sep 01 05:52:52 volumio-x86 systemd-logind[795]: New seat seat0. Sep 01 05:52:52 volumio-x86 alsactl[794]: Found hardware: "HDA-Intel" "Intel Haswell HDMI" "HDA:80862807,80860101,00100000" "0x17aa" "0x3978" Sep 01 05:52:52 volumio-x86 alsactl[794]: Hardware is initialized using a generic method Sep 01 05:52:52 volumio-x86 alsactl[794]: Found hardware: "HDA-Intel" "Realtek ALC282" "HDA:10ec0282,17aa3801,00100003" "0x17aa" "0x3978" Sep 01 05:52:52 volumio-x86 alsactl[794]: Hardware is initialized using a generic method Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #3 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #4 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #6 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #7 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #8 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #9 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #10 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #11 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #12 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #13 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #14 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #15 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #16 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #17 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #18 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #19 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #20 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #23 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #24 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #25 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #26 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #27 (No such file or directory) Sep 01 05:52:52 volumio-x86 alsactl[794]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #30 (No such file or directory) Sep 01 05:52:52 volumio-x86 systemd-logind[795]: Watching system buttons on /dev/input/event2 (Power Button) Sep 01 05:52:52 volumio-x86 haveged[767]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Sep 01 05:52:52 volumio-x86 haveged[767]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 31410/52825 Sep 01 05:52:52 volumio-x86 haveged[767]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99996 Sep 01 05:52:52 volumio-x86 haveged[767]: haveged: fills: 0, generated: 0 Sep 01 05:52:52 volumio-x86 systemd-logind[795]: Watching system buttons on /dev/input/event1 (Power Button) Sep 01 05:52:52 volumio-x86 systemd-logind[795]: Watching system buttons on /dev/input/event0 (Lid Switch) Sep 01 05:52:52 volumio-x86 mpd_monitor.sh[791]: MPD error: Connection refused Sep 01 05:52:52 volumio-x86 mpd_monitor.sh[791]: mpd: no process found Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Successfully dropped root privileges. Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: avahi-daemon 0.7 starting up. Sep 01 05:52:53 volumio-x86 sh[747]: sending commands to master dhcpcd process Sep 01 05:52:53 volumio-x86 dhcpcd[840]: sending commands to master dhcpcd process Sep 01 05:52:53 volumio-x86 kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 01 05:52:53 volumio-x86 ifup[748]: command failed: No such device (-19) Sep 01 05:52:53 volumio-x86 systemd-logind[795]: Watching system buttons on /dev/input/event3 (AT Translated Set 2 keyboard) Sep 01 05:52:53 volumio-x86 systemd-udevd[546]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 99. Sep 01 05:52:53 volumio-x86 bluetoothd[790]: Bluetooth daemon 5.50 Sep 01 05:52:53 volumio-x86 bluetoothd[790]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 01 05:52:53 volumio-x86 systemd[1]: Started RPC bind portmap service. Sep 01 05:52:53 volumio-x86 systemd[1]: Started triggerhappy global hotkey daemon. Sep 01 05:52:53 volumio-x86 systemd[1]: Started Save/Restore Sound Card State. Sep 01 05:52:53 volumio-x86 wpa_supplicant[789]: Successfully initialized wpa_supplicant Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Successfully called chroot(). Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Successfully dropped remaining capabilities. Sep 01 05:52:53 volumio-x86 bluetoothd[790]: Starting SDP server Sep 01 05:52:53 volumio-x86 bluetoothd[790]: Excluding (cli) sap Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Loading service file /services/volumio.service. Sep 01 05:52:53 volumio-x86 systemd[1]: Started Login Service. Sep 01 05:52:53 volumio-x86 systemd[1]: Started WPA supplicant. Sep 01 05:52:53 volumio-x86 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 01 05:52:53 volumio-x86 systemd[1]: Started Bluetooth service. Sep 01 05:52:53 volumio-x86 systemd[1]: Reached target Bluetooth. Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Network interface enumeration completed. Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Server startup complete. Host name is volumio-x86.local. Local service cookie is 3205597154. Sep 01 05:52:53 volumio-x86 avahi-daemon[784]: Service "Volumio-x86" (/services/volumio.service) successfully established. Sep 01 05:52:53 volumio-x86 sh[747]: eth0=eth0 Sep 01 05:52:53 volumio-x86 systemd[1]: Listening on mpd.socket. Sep 01 05:52:53 volumio-x86 systemd[1]: Reached target Sound Card. Sep 01 05:52:53 volumio-x86 systemd[1]: Reached target Remote File Systems (Pre). Sep 01 05:52:53 volumio-x86 systemd[1]: Reached target Remote File Systems. Sep 01 05:52:53 volumio-x86 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 01 05:52:53 volumio-x86 systemd[1]: Reached target RPC Port Mapper. Sep 01 05:52:53 volumio-x86 dhcpcd[793]: no interfaces have a carrier Sep 01 05:52:53 volumio-x86 dhcpcd[793]: forked to background, child pid 919 Sep 01 05:52:53 volumio-x86 dhcpcd[793]: no interfaces have a carrier Sep 01 05:52:53 volumio-x86 dhcpcd[793]: forked to background, child pid 919 Sep 01 05:52:53 volumio-x86 systemd[1]: Started DHCP Client Daemon. Sep 01 05:52:53 volumio-x86 systemd[1]: Started Raise network interfaces. Sep 01 05:52:53 volumio-x86 systemd[1]: Reached target Network. Sep 01 05:52:53 volumio-x86 systemd[1]: Reached target Network is Online. Sep 01 05:52:53 volumio-x86 systemd[1]: Starting LSB: Brings up/down network automatically... Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Music Player Daemon... Sep 01 05:52:53 volumio-x86 bluetoothd[790]: Bluetooth management interface 1.22 initialized Sep 01 05:52:53 volumio-x86 dbus-daemon[787]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=790 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 01 05:52:53 volumio-x86 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 01 05:52:53 volumio-x86 kernel: Bluetooth: BNEP filters: protocol multicast Sep 01 05:52:53 volumio-x86 kernel: Bluetooth: BNEP socket layer initialized Sep 01 05:52:53 volumio-x86 systemd[1]: Starting /etc/rc.local Compatibility... Sep 01 05:52:53 volumio-x86 kernel: RTL8208 Fast Ethernet r8169-0-800:00: attached PHY driver (mii_bus:phy_addr=r8169-0-800:00, irq=MAC) Sep 01 05:52:53 volumio-x86 volumio-remote-updater[792]: Error: No active session Sep 01 05:52:53 volumio-x86 systemd[1]: Starting OpenBSD Secure Shell server... Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Network Time Service... Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Daily apt download activities... Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Samba NMB Daemon... Sep 01 05:52:53 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Permit User Sessions... Sep 01 05:52:53 volumio-x86 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Sep 01 05:52:53 volumio-x86 systemd[1]: Started /etc/rc.local Compatibility. Sep 01 05:52:53 volumio-x86 volumio[779]: Could not open config: /tmp/upmpdcli.conf Sep 01 05:52:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:52:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:52:53 volumio-x86 dhcpcd[919]: eth0: waiting for carrier Sep 01 05:52:53 volumio-x86 dhcpcd[919]: eth0: carrier acquired Sep 01 05:52:53 volumio-x86 volumio-remote-updater[792]: [2025-09-01 05:52:53] [info] asio async_connect error: system:111 (Connection refused) Sep 01 05:52:53 volumio-x86 volumio-remote-updater[792]: [2025-09-01 05:52:53] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 05:52:53 volumio-x86 kernel: r8169 0000:08:00.0 eth0: Link is Down Sep 01 05:52:53 volumio-x86 dhcpcd[919]: DUID 00:04:9a:c1:1e:93:c2:1e:11:e4:a9:61:68:f7:28:99:c4:4d Sep 01 05:52:53 volumio-x86 dhcpcd[919]: eth0: IAID 28:99:c4:4d Sep 01 05:52:53 volumio-x86 dhcpcd[919]: eth0: adding address fe80::850a:218d:19:fd85 Sep 01 05:52:53 volumio-x86 dhcpcd[919]: ipv6_addaddr1: Permission denied Sep 01 05:52:53 volumio-x86 dhcpcd[919]: control command: /sbin/dhcpcd eth0 Sep 01 05:52:53 volumio-x86 dhcpcd[919]: eth0: carrier lost Sep 01 05:52:53 volumio-x86 volumio-remote-updater[792]: [2025-09-01 05:52:53] [error] handle_connect error: Underlying Transport Error Sep 01 05:52:53 volumio-x86 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:52:53 volumio-x86 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 01 05:52:53 volumio-x86 systemd[1]: Started Permit User Sessions. Sep 01 05:52:53 volumio-x86 systemd[1]: iptables.service: Succeeded. Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Hostname Service... Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Hold until boot process finishes up... Sep 01 05:52:53 volumio-x86 sshd[959]: Server listening on 0.0.0.0 port 22. Sep 01 05:52:53 volumio-x86 sshd[959]: Server listening on :: port 22. Sep 01 05:52:53 volumio-x86 systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 01 05:52:53 volumio-x86 systemd[1]: Started OpenBSD Secure Shell server. Sep 01 05:52:53 volumio-x86 ntpd[932]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 01 05:52:53 volumio-x86 ntpd[932]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 01 05:52:53 volumio-x86 ntpd[963]: proto: precision = 0.047 usec (-24) Sep 01 05:52:53 volumio-x86 ntpd[963]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 01 05:52:53 volumio-x86 ntpd[963]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 01 05:52:53 volumio-x86 ntpd[963]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 978 days ago Sep 01 05:52:53 volumio-x86 ntpd[963]: Listen and drop on 0 v6wildcard [::]:123 Sep 01 05:52:53 volumio-x86 ntpd[963]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 01 05:52:53 volumio-x86 ntpd[963]: Listen normally on 2 lo 127.0.0.1:123 Sep 01 05:52:53 volumio-x86 ntpd[963]: Listening on routing socket on fd #19 for interface updates Sep 01 05:52:53 volumio-x86 ntpd[963]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 05:52:53 volumio-x86 ntpd[963]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 05:52:53 volumio-x86 dbus-daemon[787]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 01 05:52:53 volumio-x86 systemd[1]: man-db.service: Succeeded. Sep 01 05:52:53 volumio-x86 systemd[1]: Started Daily man-db regeneration. Sep 01 05:52:54 volumio-x86 systemd[1]: Started Network Time Service. Sep 01 05:52:54 volumio-x86 systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 01 05:52:54 volumio-x86 systemd[1]: Started Hold until boot process finishes up. Sep 01 05:52:54 volumio-x86 systemd[1]: plymouth-quit.service: Succeeded. Sep 01 05:52:54 volumio-x86 systemd[1]: Started Terminate Plymouth Boot Screen. Sep 01 05:52:54 volumio-x86 systemd[1]: Received SIGRTMIN+21 from PID 375 (plymouthd). Sep 01 05:52:54 volumio-x86 systemd[1]: Received SIGRTMIN+21 from PID 375 (plymouthd). Sep 01 05:52:54 volumio-x86 systemd[1]: Started Hostname Service. Sep 01 05:52:54 volumio-x86 ifplugd(eth0)[982]: ifplugd 0.28 initializing. Sep 01 05:52:54 volumio-x86 ifplugd(eth0)[982]: Using interface eth0/68:F7:28:99:C4:4D with driver (version: 6.6.32-volumio) Sep 01 05:52:54 volumio-x86 systemd[1]: Started Getty on tty1. Sep 01 05:52:54 volumio-x86 ifplugd(eth0)[982]: Using detection mode: SIOCETHTOOL Sep 01 05:52:54 volumio-x86 systemd[1]: Reached target Login Prompts. Sep 01 05:52:54 volumio-x86 ifplugd(eth0)[982]: Initialization complete, link beat not detected. Sep 01 05:52:54 volumio-x86 loadcpufreq[915]: Loading cpufreq kernel modules...done (acpi-cpufreq). Sep 01 05:52:54 volumio-x86 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 01 05:52:54 volumio-x86 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 01 05:52:54 volumio-x86 ifplugd[923]: Network Interface Plugging Daemon...start eth0...done. Sep 01 05:52:54 volumio-x86 systemd[1]: Started LSB: Brings up/down network automatically. Sep 01 05:52:54 volumio-x86 sudo[924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 05:52:54 volumio-x86 sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:54 volumio-x86 sudo[924]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 01 05:52:54 volumio-x86 sudo[924]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:54 volumio-x86 cpufrequtils[1014]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Sep 01 05:52:54 volumio-x86 systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 01 05:52:54 volumio-x86 kernel: Bluetooth: hci0: Opcode 0x1003 failed: -110 Sep 01 05:52:54 volumio-x86 ntpd[963]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 05:52:55 volumio-x86 nmbd[937]: [2025/09/01 05:52:55.283441, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 01 05:52:55 volumio-x86 nmbd[937]: started asyncdns process 1040 Sep 01 05:52:55 volumio-x86 nmbd[937]: [2025/09/01 05:52:55.283948, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 01 05:52:55 volumio-x86 nmbd[937]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 01 05:52:55 volumio-x86 nmbd[937]: [2025/09/01 05:52:55.283978, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 01 05:52:55 volumio-x86 nmbd[937]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 01 05:52:55 volumio-x86 wireless.js[782]: WIRELESS: No wireless interface, exiting Sep 01 05:52:55 volumio-x86 iw[1045]: command failed: No such device (-19) Sep 01 05:52:55 volumio-x86 iwconfig[1048]: Error for wireless request "Set Power Management" (8B2C) : Sep 01 05:52:55 volumio-x86 iwconfig[1048]: SET failed on device wlan0 ; No such device. Sep 01 05:52:55 volumio-x86 systemd[1]: wireless.service: Succeeded. Sep 01 05:52:55 volumio-x86 systemd[1]: Started Wireless Services. Sep 01 05:52:55 volumio-x86 systemd[1]: Started Volumio Backend Module. Sep 01 05:52:55 volumio-x86 systemd[1]: Started Start Volumio Kiosk. Sep 01 05:52:55 volumio-x86 systemd[1]: Started Volumio Cpu Tweaker. Sep 01 05:52:55 volumio-x86 systemd[1]: Started Intel SST and HDA soundcard init service. Sep 01 05:52:55 volumio-x86 volumio-cpu-tweak[1053]: Setting RT Priority for mpd Sep 01 05:52:55 volumio-x86 volumio-cpu-tweak[1053]: pid 35's current scheduling policy: SCHED_FIFO Sep 01 05:52:55 volumio-x86 volumio-cpu-tweak[1053]: pid 35's current scheduling priority: 99 Sep 01 05:52:55 volumio-x86 volumio-cpu-tweak[1053]: Setting MPD Affinity Sep 01 05:52:55 volumio-x86 volumio-cpu-tweak[1053]: pid 3's current affinity mask: f Sep 01 05:52:55 volumio-x86 volumio-cpu-tweak[1053]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 01 05:52:55 volumio-x86 systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 01 05:52:55 volumio-x86 ntpd[963]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 01 05:52:56 volumio-x86 systemd[1]: soundcard-init.service: Succeeded. Sep 01 05:52:56 volumio-x86 startx[1052]: X.Org X Server 1.20.4 Sep 01 05:52:56 volumio-x86 startx[1052]: X Protocol Version 11, Revision 0 Sep 01 05:52:56 volumio-x86 startx[1052]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Sep 01 05:52:56 volumio-x86 startx[1052]: Current Operating System: Linux volumio-x86 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Sep 01 05:52:56 volumio-x86 startx[1052]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Sep 01 05:52:56 volumio-x86 startx[1052]: Build Date: 18 December 2021 09:05:36AM Sep 01 05:52:56 volumio-x86 startx[1052]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Sep 01 05:52:56 volumio-x86 startx[1052]: Current version of pixman: 0.36.0 Sep 01 05:52:56 volumio-x86 startx[1052]: Before reporting problems, check http://wiki.x.org Sep 01 05:52:56 volumio-x86 startx[1052]: to make sure that you have the latest version. Sep 01 05:52:56 volumio-x86 startx[1052]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 01 05:52:56 volumio-x86 startx[1052]: (++) from command line, (!!) notice, (II) informational, Sep 01 05:52:56 volumio-x86 startx[1052]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 01 05:52:56 volumio-x86 startx[1052]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Sep 1 05:52:56 2025 Sep 01 05:52:56 volumio-x86 startx[1052]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 01 05:52:56 volumio-x86 systemd[1]: apt-daily.service: Succeeded. Sep 01 05:52:56 volumio-x86 systemd[1]: Started Daily apt download activities. Sep 01 05:52:56 volumio-x86 kernel: broken atomic modeset userspace detected, disabling atomic Sep 01 05:52:56 volumio-x86 ntpd[963]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 01 05:52:57 volumio-x86 systemd[1]: systemd-rfkill.service: Succeeded. Sep 01 05:52:57 volumio-x86 mpd[1021]: Sep 01 05:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 05:52:57 volumio-x86 systemd[1]: Started Music Player Daemon. Sep 01 05:52:57 volumio-x86 mpd_monitor.sh[791]: MPD restarted due to no mpc output. Sep 01 05:52:57 volumio-x86 startx[1052]: (II) modeset(G0): Initializing kms color map for depth 24, 8 bpc. Sep 01 05:52:57 volumio-x86 ntpd[963]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 01 05:52:57 volumio-x86 volumio[1051]: info: ------------------------------------------- Sep 01 05:52:57 volumio-x86 volumio[1051]: info: ----- Volumio3 ---- Sep 01 05:52:57 volumio-x86 volumio[1051]: info: ------------------------------------------- Sep 01 05:52:57 volumio-x86 volumio[1051]: info: ----- System startup ---- Sep 01 05:52:57 volumio-x86 volumio[1051]: info: ------------------------------------------- Sep 01 05:52:58 volumio-x86 startx[1052]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Sep 01 05:52:58 volumio-x86 volumio[1051]: info: MYVOLUMIO Environment detected Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Plugin folders cleanup Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning into folder /volumio/app/plugins/ Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category audio_interface Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category miscellanea Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category music_service Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category plugins.json Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category system_controller Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category user_interface Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning into folder /data/plugins/ Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category music_service Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category system_controller Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Scanning category user_interface Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Plugin folders cleanup completed Sep 01 05:52:58 volumio-x86 volumio[1051]: info: ------------------------------------------- Sep 01 05:52:58 volumio-x86 volumio[1051]: info: ----- Core plugins startup ---- Sep 01 05:52:58 volumio-x86 volumio[1051]: info: ------------------------------------------- Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Adding plugin upnp to MyMusic Plugins Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Loading plugins from folder /data/plugins/ Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Loading plugin "system"... Sep 01 05:52:58 volumio-x86 volumio[1051]: info: Loading plugin "appearance"... Sep 01 05:52:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:52:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:52:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:52:58 volumio-x86 volumio-remote-updater[792]: [2025-09-01 05:52:58] [connect] Successful connection Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "network"... Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Refreshing Cached IP Addresses Sep 01 05:52:59 volumio-x86 sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 05:52:59 volumio-x86 sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 05:52:59 volumio-x86 sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:59 volumio-x86 sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "services"... Sep 01 05:52:59 volumio-x86 sudo[1301]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "alsa_controller"... Sep 01 05:52:59 volumio-x86 sudo[1303]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:59 volumio-x86 sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 05:52:59 volumio-x86 sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:59 volumio-x86 sudo[1312]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:59 volumio-x86 volumio[1051]: warn: Unable to locate the audio output device Combo384 Amanero. Please configure a valid output device. Sep 01 05:52:59 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "wizard"... Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "networkfs"... Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Starting Udev Watcher for removable devices Sep 01 05:52:59 volumio-x86 sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Sep 01 05:52:59 volumio-x86 sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Ignoring mount for partition: boot Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Mounting Device d35eb915-f24a-437a-a43b-4afe991304f7 Sep 01 05:52:59 volumio-x86 kernel: FS-Cache: Loaded Sep 01 05:52:59 volumio-x86 kernel: Key type dns_resolver registered Sep 01 05:52:59 volumio-x86 sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/d35eb915-f24a-437a-a43b-4afe991304f7 -o noatime Sep 01 05:52:59 volumio-x86 sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:59 volumio-x86 sudo[1341]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Mounting Device 2fc5abe1-9579-4377-a3f7-b00c1ebaeecd Sep 01 05:52:59 volumio-x86 sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb3 /mnt/USB/2fc5abe1-9579-4377-a3f7-b00c1ebaeecd -o noatime Sep 01 05:52:59 volumio-x86 sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:59 volumio-x86 sudo[1348]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Mounting Device Music Sep 01 05:52:59 volumio-x86 sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb4 /mnt/USB/Music -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 01 05:52:59 volumio-x86 sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:52:59 volumio-x86 kernel: Key type cifs.spnego registered Sep 01 05:52:59 volumio-x86 kernel: Key type cifs.idmap registered Sep 01 05:52:59 volumio-x86 sudo[1331]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:59 volumio-x86 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. Sep 01 05:52:59 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Sep 01 05:52:59 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 01 05:52:59 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Sep 01 05:52:59 volumio-x86 ntfs-3g[1370]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 01 05:52:59 volumio-x86 ntfs-3g[1370]: Mounted /dev/sdb4 (Read-Write, label "Music", NTFS 3.1) Sep 01 05:52:59 volumio-x86 ntfs-3g[1370]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 01 05:52:59 volumio-x86 ntfs-3g[1370]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb4,blkdev,blksize=4096 Sep 01 05:52:59 volumio-x86 ntfs-3g[1370]: Global ownership and permissions enforced, configuration type 7 Sep 01 05:52:59 volumio-x86 sudo[1354]: pam_unix(sudo:session): session closed for user root Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Ignoring mount for partition: 1E36994E369927B3 Sep 01 05:52:59 volumio-x86 volumio[1051]: error: Cannot associate FS Label, not mounting Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Ignoring mount for partition: 0002CA3E000B4DB4 Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Ignoring mount for partition: 1AE6A8CAE6A8A809 Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Ignoring mount for partition: 0D9C07430D9C0743 Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Ignoring mount for partition: 系统 Sep 01 05:52:59 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "volumio_command_line_client"... Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "upnp"... Sep 01 05:52:59 volumio-x86 volumio[1051]: info: [1756677179919] Starting Upmpd Daemon Sep 01 05:52:59 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "my_music"... Sep 01 05:52:59 volumio-x86 volumio[1051]: info: Loading plugin "mpd"... Sep 01 05:53:00 volumio-x86 volumio[1051]: info: Loading plugin "upnp_browser"... Sep 01 05:53:00 volumio-x86 volumio[1051]: info: Loading plugin "alarm-clock"... Sep 01 05:53:00 volumio-x86 volumio[1051]: info: Loading plugin "airplay_emulation"... Sep 01 05:53:00 volumio-x86 volumio[1051]: info: Starting Shairport Sync Sep 01 05:53:00 volumio-x86 volumio[1051]: info: Loading plugin "last_100"... Sep 01 05:53:00 volumio-x86 volumio[1051]: info: Loading plugin "webradio"... Sep 01 05:53:01 volumio-x86 volumio[1051]: info: Loading plugin "i2s_dacs"... Sep 01 05:53:01 volumio-x86 volumio[1051]: info: I2S DAC not set, start Auto-detection Sep 01 05:53:01 volumio-x86 volumio[1051]: info: Loading plugin "volumiodiscovery"... Sep 01 05:53:01 volumio-x86 volumio[1051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 05:53:01 volumio-x86 volumio[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 05:53:01 volumio-x86 volumio[1051]: *** WARNING *** For more information see Sep 01 05:53:01 volumio-x86 volumio[1051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 05:53:01 volumio-x86 node[1051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 05:53:01 volumio-x86 volumio[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 05:53:01 volumio-x86 volumio[1051]: *** WARNING *** For more information see Sep 01 05:53:01 volumio-x86 node[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 05:53:01 volumio-x86 node[1051]: *** WARNING *** For more information see Sep 01 05:53:01 volumio-x86 node[1051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 05:53:01 volumio-x86 node[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 05:53:01 volumio-x86 node[1051]: *** WARNING *** For more information see Sep 01 05:53:01 volumio-x86 volumio[1051]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 05:53:01 volumio-x86 volumio[1051]: info: Discovery: Started advertising with name: Volumio-x86 Sep 01 05:53:01 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 05:53:01 volumio-x86 volumio[1051]: info: Loading plugin "jellyfin"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "autostart"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Applying required configuration parameters for plugin autostart Sep 01 05:53:02 volumio-x86 volumio[1051]: info: AutoStart - onVolumioStart - read config.json Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "peppy_screensaver"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Applying required configuration parameters for plugin peppy_screensaver Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "outputs"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "albumart"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Plugin example_plugin is not enabled Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "inputs"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "updater_comm"... Sep 01 05:53:02 volumio-x86 volumio[1051]: Forking 3 albumart workers Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Plugin mpdemulation is not enabled Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "rest_api"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "websocket"... Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Starting Socket.io Server version 2.3.0 Sep 01 05:53:02 volumio-x86 volumio[1051]: info: Loading plugin "RoonBridge"... Sep 01 05:53:03 volumio-x86 volumio[1051]: Starting albumart workers Sep 01 05:53:03 volumio-x86 volumio[1051]: Starting albumart workers Sep 01 05:53:03 volumio-x86 volumio[1051]: Starting albumart workers Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Applying required configuration parameters for plugin RoonBridge Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Loading plugin "Systeminfo"... Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Loading i18n strings for locale zh Sep 01 05:53:03 volumio-x86 volumio[1051]: Updating browse sources language Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::initPlayerControls Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: Express server listening on port 3000 Sep 01 05:53:03 volumio-x86 volumio[1051]: [Metrics] WebUI: 5s 792.01ms Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Setting Device type: x86 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::resetVolumioState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::getcurrentVolume Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Completed loading Core Plugins Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Preparing to generate the ALSA configuration file Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Volumio Network Manager: Network status updated: 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::pushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::updateTrackBlock Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrackBlock Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds Sep 01 05:53:03 volumio-x86 volumio[1051]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 01 05:53:03 volumio-x86 volumio[1051]: wlan0 Interface doesn't support scanning. Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Cannot use regular scanning, forcing with ap-force Sep 01 05:53:03 volumio-x86 sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 01 05:53:03 volumio-x86 sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 sudo[1542]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: command failed: No such device (-19) Sep 01 05:53:03 volumio-x86 volumio[1051]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 01 05:53:03 volumio-x86 volumio[1051]: command failed: No such device (-19) Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 01 05:53:03 volumio-x86 volumio[1051]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 01 05:53:03 volumio-x86 volumio[1051]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Reading ALSA contributions from plugins. Sep 01 05:53:03 volumio-x86 volumio-remote-updater[792]: [2025-09-01 05:53:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756677178 101 Sep 01 05:53:03 volumio-x86 volumio[1051]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::pushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Reloading queue from file Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::setRepeat null single undefined Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::pushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::setRandom null Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreStateMachine::pushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPushState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Asound.conf file unchanged, so no further update is needed Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Output device has changed, restarting MPD Sep 01 05:53:03 volumio-x86 sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 05:53:03 volumio-x86 sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 sudo[1554]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Output device has changed, restarting Shairport Sync Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 05:53:03 volumio-x86 sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 systemd[1]: Stopping Music Player Daemon... Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: ___________ START PLUGINS ___________ Sep 01 05:53:03 volumio-x86 volumio[1051]: info: ControllerMpd::onStart: Initializing MPD Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Creating MPD Configuration file Sep 01 05:53:03 volumio-x86 sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 05:53:03 volumio-x86 sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 sudo[1563]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 05:53:03 volumio-x86 volumio[1051]: info: [1756677183614] CoreMusicLibrary::Adding element 媒体服务器 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 05:53:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:03 volumio-x86 sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 05:53:03 volumio-x86 sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 05:53:03 volumio-x86 volumio[1051]: info: [1756677183648] CoreMusicLibrary::Adding element Last_100 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 05:53:03 volumio-x86 volumio[1051]: info: [1756677183649] CoreMusicLibrary::Adding element Webradio Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Initializing BBC Radios Sep 01 05:53:03 volumio-x86 systemd[1]: mpd.service: Succeeded. Sep 01 05:53:03 volumio-x86 systemd[1]: Stopped Music Player Daemon. Sep 01 05:53:03 volumio-x86 systemd[1]: Starting Music Player Daemon... Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Discovery: Getting this device information Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 05:53:03 volumio-x86 volumio[1051]: info: [1756677183678] CoreMusicLibrary::Adding element Jellyfin Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 05:53:03 volumio-x86 volumio[1051]: Cannot find translation for source Jellyfin Sep 01 05:53:03 volumio-x86 volumio[1051]: info: [jellyfin] Initialized plugin with device info: {"id":"c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a","host":"http://127.0.0.1","name":"Volumio-x86","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"","mute":false,"track":"BBC Radio 6 Music (128k)","albumart":"https://cdn-radiotime-logos.tunein.com/s44491q.png"}} Sep 01 05:53:03 volumio-x86 volumio[1051]: info: AutoStart - onStart Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Loading i18n strings for locale zh Sep 01 05:53:03 volumio-x86 sudo[1579]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 05:53:03 volumio-x86 sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 volumio[1051]: info: /tmp/myfifo created Sep 01 05:53:03 volumio-x86 sudo[1579]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: info: /tmp/myfifosa created Sep 01 05:53:03 volumio-x86 sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Sep 01 05:53:03 volumio-x86 sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 sudo[1597]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: info: snd-dummy loaded Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Sep 01 05:53:03 volumio-x86 sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 sudo[1611]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Volumio Calling Home Sep 01 05:53:03 volumio-x86 sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 01 05:53:03 volumio-x86 sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 systemd[1]: Started RoonBridge. Sep 01 05:53:03 volumio-x86 sudo[1617]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: Unhandled rejection Error: No sockets available, cannot start. Sep 01 05:53:03 volumio-x86 volumio[1051]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 01 05:53:03 volumio-x86 volumio[1051]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 01 05:53:03 volumio-x86 volumio[1051]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 01 05:53:03 volumio-x86 volumio[1051]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 01 05:53:03 volumio-x86 volumio[1051]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 01 05:53:03 volumio-x86 volumio[1051]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 01 05:53:03 volumio-x86 volumio[1051]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 01 05:53:03 volumio-x86 volumio[1051]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 01 05:53:03 volumio-x86 volumio[1051]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 01 05:53:03 volumio-x86 volumio[1051]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 01 05:53:03 volumio-x86 volumio[1051]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 01 05:53:03 volumio-x86 volumio[1051]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 01 05:53:03 volumio-x86 volumio[1051]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 01 05:53:03 volumio-x86 volumio[1051]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 01 05:53:03 volumio-x86 volumio[1051]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 01 05:53:03 volumio-x86 volumio[1051]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 01 05:53:03 volumio-x86 volumio[1051]: info: MPD Permissions set Sep 01 05:53:03 volumio-x86 volumio[1051]: info: MPD Permissions set Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Starting Shairport Sync Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Starting Shairport Sync Sep 01 05:53:03 volumio-x86 sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 05:53:03 volumio-x86 sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Starting Shairport Sync Sep 01 05:53:03 volumio-x86 sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 05:53:03 volumio-x86 sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 05:53:03 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Sep 01 05:53:03 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Starting Shairport Sync Sep 01 05:53:03 volumio-x86 sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Sep 01 05:53:03 volumio-x86 mpd[1593]: Sep 01 05:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 05:53:03 volumio-x86 sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 05:53:03 volumio-x86 sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 05:53:03 volumio-x86 sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Sep 01 05:53:03 volumio-x86 sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 sudo[1659]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 sudo[1643]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Shairport-Sync Started Sep 01 05:53:03 volumio-x86 systemd[1]: Started Music Player Daemon. Sep 01 05:53:03 volumio-x86 volumio[1051]: Error adding Membership: Error: addMembership EINVAL Sep 01 05:53:03 volumio-x86 sudo[1566]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 05:53:03 volumio-x86 sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 sudo[1664]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 sudo[1557]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 05:53:03 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Sep 01 05:53:03 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 05:53:03 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 05:53:03 volumio-x86 sudo[1649]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 sudo[1653]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 sudo[1646]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:03 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:03 volumio-x86 volumio[1051]: info: Shairport-Sync Started Sep 01 05:53:03 volumio-x86 volumio[1051]: info: MPD Permissions set Sep 01 05:53:04 volumio-x86 volumio[1051]: info: Completed starting Core Plugins Sep 01 05:53:04 volumio-x86 volumio[1051]: info: ------------------------------------------- Sep 01 05:53:04 volumio-x86 volumio[1051]: info: ----- MyVolumio plugins startup ---- Sep 01 05:53:04 volumio-x86 volumio[1051]: info: ------------------------------------------- Sep 01 05:53:04 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 05:53:04 volumio-x86 volumio[1051]: info: MPD running with PID1593 Sep 01 05:53:04 volumio-x86 volumio[1051]: ,establishing connection Sep 01 05:53:04 volumio-x86 volumio[1051]: error: MPD error: The expression evaluated to a falsy value: Sep 01 05:53:04 volumio-x86 volumio[1051]: assert.ok(self.idling) Sep 01 05:53:04 volumio-x86 volumio[1051]: error: The expression evaluated to a falsy value: Sep 01 05:53:04 volumio-x86 volumio[1051]: assert.ok(self.idling) Sep 01 05:53:04 volumio-x86 volumio[1051]: error: MPD error: The expression evaluated to a falsy value: Sep 01 05:53:04 volumio-x86 volumio[1051]: assert.ok(self.idling) Sep 01 05:53:04 volumio-x86 volumio[1051]: error: The expression evaluated to a falsy value: Sep 01 05:53:04 volumio-x86 volumio[1051]: assert.ok(self.idling) Sep 01 05:53:04 volumio-x86 volumio[1051]: error: updateQueue error: null Sep 01 05:53:04 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Sep 01 05:53:04 volumio-x86 sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 05:53:04 volumio-x86 sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:04 volumio-x86 systemd[1]: Stopping Music Player Daemon... Sep 01 05:53:04 volumio-x86 systemd[1]: mpd.service: Succeeded. Sep 01 05:53:04 volumio-x86 systemd[1]: Stopped Music Player Daemon. Sep 01 05:53:04 volumio-x86 systemd[1]: Starting Music Player Daemon... Sep 01 05:53:04 volumio-x86 sudo[1745]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 05:53:04 volumio-x86 sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:04 volumio-x86 sudo[1745]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.006 Warn: get lock file path: /tmp/.rnbgem0- Sep 01 05:53:04 volumio-x86 volumio[1051]: 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 Engine version: 3 Transport: polling Total Clients: 4 Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.214 Trace: [childprocess] using unix child process Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.248 Debug: PathForResource, filename: ../.update Sep 01 05:53:04 volumio-x86 mpd[1795]: Sep 01 05:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 05:53:04 volumio-x86 systemd[1]: Started Music Player Daemon. Sep 01 05:53:04 volumio-x86 sudo[1724]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:04 volumio-x86 volumio[1051]: error: updateQueue error: null Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.311 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.313 Debug: PathForResource, filename: RAATServer Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.313 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.313 Debug: PathForResource, filename: RoonBridgeHelper Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.313 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: Initializing Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.325 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: 00:00:00.347 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 01 05:53:04 volumio-x86 RoonBridge[1621]: Not Running (.o) Sep 01 05:53:05 volumio-x86 RoonBridge[1621]: Running Sep 01 05:53:05 volumio-x86 RoonBridge[1621]: 00:00:00.009 Warn: get lock file path: /tmp/.rnbhgem0- Sep 01 05:53:05 volumio-x86 volumio[1051]: 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 Engine version: 3 Transport: polling Total Clients: 5 Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetQueue Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreStateMachine::getQueue Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CorePlayQueue::getQueue Sep 01 05:53:05 volumio-x86 volumio[1051]: info: Listing playlists Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 05:53:05 volumio-x86 volumio[1051]: info: Received Get System Info Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 05:53:05 volumio-x86 volumio[1051]: info: Discovery: Getting this device information Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 05:53:05 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 05:53:07 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 05:53:07 volumio-x86 volumio[1051]: info: Received Get System Info Sep 01 05:53:07 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 05:53:07 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 05:53:07 volumio-x86 volumio[1051]: info: Discovery: Getting this device information Sep 01 05:53:07 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:07 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:07 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 05:53:07 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 01 05:53:07 volumio-x86 kernel: ACPI: \_SB_.PCI0.PEG1.PEGP: failed to evaluate _DSM Sep 01 05:53:08 volumio-x86 sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Sep 01 05:53:08 volumio-x86 sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:08 volumio-x86 sudo[1916]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:08 volumio-x86 volumio[1051]: info: Cannot mount NAS local at system boot, trial number 2 ,retrying in 5 seconds Sep 01 05:53:08 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Sep 01 05:53:08 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 01 05:53:08 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Sep 01 05:53:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:09 volumio-x86 sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 05:53:09 volumio-x86 sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:09 volumio-x86 sudo[1940]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:09 volumio-x86 sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 05:53:09 volumio-x86 sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:09 volumio-x86 sudo[1943]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:09 volumio-x86 sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 05:53:09 volumio-x86 sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:09 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 05:53:09 volumio-x86 sudo[1948]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:09 volumio-x86 volumio[1051]: info: Upmpdcli Daemon Started Sep 01 05:53:13 volumio-x86 sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Sep 01 05:53:13 volumio-x86 sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:13 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Sep 01 05:53:13 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 01 05:53:13 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Sep 01 05:53:13 volumio-x86 sudo[1968]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:13 volumio-x86 volumio[1051]: info: Cannot mount NAS local at system boot, trial number 3 ,retrying in 5 seconds Sep 01 05:53:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:13 volumio-x86 volumio[1051]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=fc1bc81a24546d04acb9527c555c4161" http://updates.volumio.org/downloader-v1/track-device Sep 01 05:53:13 volumio-x86 volumio[1051]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 05:53:13 volumio-x86 volumio[1051]: Dload Upload Total Spent Left Speed Sep 01 05:53:13 volumio-x86 volumio[1051]: [132B blob data] Sep 01 05:53:13 volumio-x86 volumio[1051]: retrying in 5 seconds, trial 0 Sep 01 05:53:13 volumio-x86 volumio[1051]: info: Volumio Calling Home Sep 01 05:53:15 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 01 05:53:18 volumio-x86 sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Sep 01 05:53:18 volumio-x86 sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:18 volumio-x86 sudo[1986]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:18 volumio-x86 volumio[1051]: info: Cannot mount NAS local at system boot, trial number 4 ,retrying in 5 seconds Sep 01 05:53:18 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Sep 01 05:53:18 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 01 05:53:18 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Sep 01 05:53:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:22 volumio-x86 systemd[1]: systemd-fsckd.service: Succeeded. Sep 01 05:53:23 volumio-x86 sudo[2010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Sep 01 05:53:23 volumio-x86 sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:53:23 volumio-x86 sudo[2010]: pam_unix(sudo:session): session closed for user root Sep 01 05:53:23 volumio-x86 volumio[1051]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 01 05:53:23 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Sep 01 05:53:23 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 01 05:53:23 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Sep 01 05:53:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:23 volumio-x86 volumio[1051]: info: AutoStart - Plugin is starting Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetQueue Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::getQueue Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CorePlayQueue::getQueue Sep 01 05:53:23 volumio-x86 volumio[1051]: info: AutoStart - start playing Sep 01 05:53:23 volumio-x86 volumio[1051]: info: AutoStart - start playing with no specific position Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPlay Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::play index 0 Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::stop Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::play index undefined Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::startPlaybackTimer Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:23 volumio-x86 volumio[1051]: info: [1756677203682] ControllerWebradio::clearAddPlayTrack Sep 01 05:53:23 volumio-x86 volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 05:53:23 volumio-x86 volumio[1051]: info: sendMpdCommand stop took 0 milliseconds Sep 01 05:53:23 volumio-x86 volumio[1051]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 05:53:23 volumio-x86 volumio[1051]: info: Sep 01 05:53:23 volumio-x86 volumio[1051]: ---------------------------- MPD announces system playlist update Sep 01 05:53:23 volumio-x86 volumio[1051]: info: Ignoring MPD Status Update Sep 01 05:53:23 volumio-x86 volumio[1051]: info: sendMpdCommand clear took 0 milliseconds Sep 01 05:53:23 volumio-x86 volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_81827798/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio%3d128000.norewind.m3u8" Sep 01 05:53:23 volumio-x86 volumio[1051]: error: updateQueue error: null Sep 01 05:53:23 volumio-x86 volumio[1051]: info: Sep 01 05:53:23 volumio-x86 volumio[1051]: ---------------------------- MPD announces system playlist update Sep 01 05:53:23 volumio-x86 volumio[1051]: info: Ignoring MPD Status Update Sep 01 05:53:23 volumio-x86 volumio[1051]: info: ------------------------------ 1ms Sep 01 05:53:23 volumio-x86 volumio[1051]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_81827798/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio%3d128000.norewind.m3u8" took 1 milliseconds Sep 01 05:53:23 volumio-x86 volumio[1051]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 05:53:23 volumio-x86 volumio[1051]: verbose: ControllerMpd::sendMpdCommand play Sep 01 05:53:23 volumio-x86 volumio[1051]: info: ------------------------------ 1ms Sep 01 05:53:23 volumio-x86 volumio[1051]: info: sendMpdCommand play took 0 milliseconds Sep 01 05:53:23 volumio-x86 volumio[1051]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=fc1bc81a24546d04acb9527c555c4161" http://updates.volumio.org/downloader-v1/track-device Sep 01 05:53:23 volumio-x86 volumio[1051]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 05:53:23 volumio-x86 volumio[1051]: Dload Upload Total Spent Left Speed Sep 01 05:53:23 volumio-x86 volumio[1051]: [132B blob data] Sep 01 05:53:23 volumio-x86 volumio[1051]: retrying in 5 seconds, trial 1 Sep 01 05:53:23 volumio-x86 volumio[1051]: info: Volumio Calling Home Sep 01 05:53:24 volumio-x86 volumio[1051]: error: MyVolumio Plugin failed to start in a timely fashion Sep 01 05:53:24 volumio-x86 volumio[1051]: [Metrics] CommandRouter: 26s 28.47ms Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumiosetStartupVolume Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:24 volumio-x86 systemd[1]: systemd-hostnamed.service: Succeeded. Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::Close All Modals sent Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::Close All Modals sent Sep 01 05:53:24 volumio-x86 volumio[1051]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Sep 01 05:53:24 volumio-x86 volumio[1051]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Sep 01 05:53:24 volumio-x86 volumio[1051]: aplay: main:828: audio open error: No such device Sep 01 05:53:24 volumio-x86 volumio[1051]: info: Sep 01 05:53:24 volumio-x86 volumio[1051]: ---------------------------- MPD announces state update: player Sep 01 05:53:24 volumio-x86 volumio[1051]: info: ControllerMpd::getState Sep 01 05:53:24 volumio-x86 volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Sep 01 05:53:24 volumio-x86 volumio[1051]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 05:53:24 volumio-x86 volumio[1051]: info: sendMpdCommand status took 1 milliseconds Sep 01 05:53:24 volumio-x86 volumio[1051]: verbose: ControllerMpd::parseState Sep 01 05:53:24 volumio-x86 volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 05:53:24 volumio-x86 volumio[1051]: info: sendMpdCommand clearerror took 2 milliseconds Sep 01 05:53:24 volumio-x86 volumio[1051]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 05:53:24 volumio-x86 volumio[1051]: verbose: ControllerMpd::parseTrackInfo Sep 01 05:53:24 volumio-x86 volumio[1051]: info: ControllerMpd::pushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::servicePushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreStateMachine::pushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CorePlayQueue::getTrack 0 Sep 01 05:53:24 volumio-x86 volumio[1051]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_6music-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_81827798/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Sep 01 05:53:24 volumio-x86 volumio[1051]: verbose: CURRENT POSITION 0 Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreStateMachine::syncState stateService stop Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreStateMachine::pushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: No code Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreStateMachine::pushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioPushState Sep 01 05:53:24 volumio-x86 volumio[1051]: info: ------------------------------ 11ms Sep 01 05:53:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:25 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 05:53:25 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 05:53:25 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Sep 01 05:53:25 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 05:53:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:31 volumio-x86 volumio[1051]: info: BOOT COMPLETED Sep 01 05:53:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:33 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 05:53:33 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:53:33 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 05:53:33 volumio-x86 volumio[1051]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=fc1bc81a24546d04acb9527c555c4161" http://updates.volumio.org/downloader-v1/track-device Sep 01 05:53:33 volumio-x86 volumio[1051]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 01 05:53:33 volumio-x86 volumio[1051]: Dload Upload Total Spent Left Speed Sep 01 05:53:33 volumio-x86 volumio[1051]: [132B blob data] Sep 01 05:53:33 volumio-x86 volumio[1051]: retrying in 5 seconds, trial 2 Sep 01 05:53:33 volumio-x86 volumio[1051]: info: Volumio Calling Home Sep 01 05:53:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:45 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:53:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:53:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:53:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:53:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:01 volumio-x86 ntpd[963]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 05:54:02 volumio-x86 ntpd[963]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 01 05:54:02 volumio-x86 ntpd[963]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 01 05:54:02 volumio-x86 ntpd[963]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 01 05:54:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:06 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:54:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:10 volumio-x86 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:54:10 volumio-x86 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 01 05:54:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:23 volumio-x86 systemd[1]: nmbd.service: Start operation timed out. Terminating. Sep 01 05:54:23 volumio-x86 systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Sep 01 05:54:23 volumio-x86 systemd[1]: nmbd.service: Failed with result 'timeout'. Sep 01 05:54:23 volumio-x86 systemd[1]: Failed to start Samba NMB Daemon. Sep 01 05:54:23 volumio-x86 systemd[1]: Starting Samba Winbind Daemon... Sep 01 05:54:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:23 volumio-x86 winbindd[2264]: [2025/09/01 05:54:23.740597, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 01 05:54:23 volumio-x86 winbindd[2264]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 01 05:54:23 volumio-x86 winbindd[2264]: [2025/09/01 05:54:23.761096, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 01 05:54:23 volumio-x86 winbindd[2264]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 01 05:54:23 volumio-x86 systemd[1]: Started Samba Winbind Daemon. Sep 01 05:54:23 volumio-x86 systemd[1]: Starting Samba SMB Daemon... Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.892283, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Sep 01 05:54:23 volumio-x86 smbd[2273]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x0 at offset=525988 Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.892447, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Sep 01 05:54:23 volumio-x86 smbd[2273]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x0 at offset=525988 Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.892563, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Sep 01 05:54:23 volumio-x86 smbd[2273]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x0 at offset=525988 Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.892628, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Sep 01 05:54:23 volumio-x86 smbd[2273]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x0 at offset=525988 Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.892669, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Sep 01 05:54:23 volumio-x86 smbd[2273]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x0 at offset=525988 Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.892694, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Sep 01 05:54:23 volumio-x86 smbd[2273]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x0 at offset=525988 Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.892717, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Sep 01 05:54:23 volumio-x86 smbd[2273]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x0 at offset=525988 Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.897666, 0] ../source3/registry/reg_backend_db.c:1166(regdb_store_subkey_list) Sep 01 05:54:23 volumio-x86 smbd[2273]: regdb_store_keys: Failed to store new record for key [HKLM\SOFTWARE\Microsoft\Windows NT\CurrentVersion\Group Policy]: WERR_GEN_FAILURE Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.897714, 0] ../source3/registry/reg_init_basic.c:51(registry_init_common) Sep 01 05:54:23 volumio-x86 smbd[2273]: Failed to initialize data in registry! Sep 01 05:54:23 volumio-x86 smbd[2273]: [2025/09/01 05:54:23.897802, 0] ../lib/util/become_daemon.c:124(exit_daemon) Sep 01 05:54:23 volumio-x86 smbd[2273]: exit_daemon: STATUS=daemon failed to start: Samba cannot init registry, error code 13 Sep 01 05:54:23 volumio-x86 systemd[1]: smbd.service: Main process exited, code=exited, status=1/FAILURE Sep 01 05:54:23 volumio-x86 systemd[1]: smbd.service: Failed with result 'exit-code'. Sep 01 05:54:23 volumio-x86 systemd[1]: Failed to start Samba SMB Daemon. Sep 01 05:54:27 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:54:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:48 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:54:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:54:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:54:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:54:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:07 volumio-x86 ntpd[963]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 01 05:55:07 volumio-x86 ntpd[963]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 05:55:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:08 volumio-x86 ntpd[963]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 01 05:55:09 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:55:09 volumio-x86 ntpd[963]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 01 05:55:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:30 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:55:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:51 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:55:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:55:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:55:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:55:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:11 volumio-x86 ntpd[963]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 05:56:12 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:56:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:14 volumio-x86 ntpd[963]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 01 05:56:14 volumio-x86 ntpd[963]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 01 05:56:15 volumio-x86 ntpd[963]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 01 05:56:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:33 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:56:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:56:54 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:56:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:56:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:56:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:13 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:15 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:57:15 volumio-x86 ntpd[963]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 05:57:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:18 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:18 volumio-x86 ntpd[963]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 01 05:57:18 volumio-x86 ntpd[963]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 01 05:57:19 volumio-x86 ntpd[963]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 01 05:57:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:23 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:28 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:33 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:36 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:57:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:38 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:43 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:48 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:53 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:57:57 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:57:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:57:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:57:58 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:58:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:58:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:58:03 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:58:08 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:58:09 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:58:09 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:58:11 volumio-x86 dhcpcd[919]: eth0: carrier acquired Sep 01 05:58:11 volumio-x86 dhcpcd[919]: eth0: IAID 28:99:c4:4d Sep 01 05:58:11 volumio-x86 kernel: r8169 0000:08:00.0 eth0: Link is Up - 100Mbps/Full - flow control off Sep 01 05:58:11 volumio-x86 kernel: r8169 0000:08:00.0 eth0: Link is Down Sep 01 05:58:11 volumio-x86 dhcpcd[919]: eth0: soliciting a DHCP lease Sep 01 05:58:11 volumio-x86 dhcpcd[919]: eth0: soliciting an IPv6 router Sep 01 05:58:12 volumio-x86 dhcpcd[919]: eth0: carrier lost Sep 01 05:58:12 volumio-x86 dhcpcd[919]: eth0: carrier acquired Sep 01 05:58:12 volumio-x86 kernel: r8169 0000:08:00.0 eth0: Link is Up - 100Mbps/Full - flow control off Sep 01 05:58:12 volumio-x86 dhcpcd[919]: eth0: IAID 28:99:c4:4d Sep 01 05:58:13 volumio-x86 dhcpcd[919]: eth0: soliciting an IPv6 router Sep 01 05:58:13 volumio-x86 dhcpcd[919]: eth0: soliciting a DHCP lease Sep 01 05:58:13 volumio-x86 ifplugd(eth0)[982]: Link beat detected. Sep 01 05:58:13 volumio-x86 ifplugd(eth0)[982]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 01 05:58:13 volumio-x86 ifplugd(eth0)[982]: client: ifup: interface eth0 already configured Sep 01 05:58:13 volumio-x86 ifplugd(eth0)[982]: Program executed successfully. Sep 01 05:58:14 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:58:14 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:58:14 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:58:18 volumio-x86 dhcpcd[919]: eth0: offered 192.168.0.115 from 192.168.0.1 Sep 01 05:58:18 volumio-x86 dhcpcd[919]: eth0: probing address 192.168.0.115/24 Sep 01 05:58:18 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:58:19 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:58:19 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Date not found in response Sep 01 05:58:19 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 05:58:22 volumio-x86 ntpd[963]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 05:58:23 volumio-x86 dhcpcd[919]: eth0: leased 192.168.0.115 for 7200 seconds Sep 01 05:58:23 volumio-x86 dhcpcd[919]: eth0: adding route to 192.168.0.0/24 Sep 01 05:58:23 volumio-x86 dhcpcd[919]: eth0: adding default route via 192.168.0.1 Sep 01 05:58:23 volumio-x86 avahi-daemon[784]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.115. Sep 01 05:58:23 volumio-x86 avahi-daemon[784]: New relevant interface eth0.IPv4 for mDNS. Sep 01 05:58:23 volumio-x86 avahi-daemon[784]: Registering new address record for 192.168.0.115 on eth0.IPv4. Sep 01 05:58:23 volumio-x86 ntpd[963]: ntpd exiting on signal 15 (Terminated) Sep 01 05:58:23 volumio-x86 systemd[1]: Stopping Network Time Service... Sep 01 05:58:23 volumio-x86 systemd[1]: ntp.service: Succeeded. Sep 01 05:58:23 volumio-x86 systemd[1]: Stopped Network Time Service. Sep 01 05:58:23 volumio-x86 systemd[1]: Starting Network Time Service... Sep 01 05:58:23 volumio-x86 ntpd[3233]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 01 05:58:23 volumio-x86 ntpd[3233]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 01 05:58:23 volumio-x86 systemd[1]: Started Network Time Service. Sep 01 05:58:23 volumio-x86 ntpd[3240]: proto: precision = 0.081 usec (-23) Sep 01 05:58:23 volumio-x86 ntpd[3240]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 01 05:58:23 volumio-x86 ntpd[3240]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 01 05:58:23 volumio-x86 ntpd[3240]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 978 days ago Sep 01 05:58:23 volumio-x86 ntpd[3240]: Listen and drop on 0 v6wildcard [::]:123 Sep 01 05:58:23 volumio-x86 ntpd[3240]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 01 05:58:23 volumio-x86 ntpd[3240]: Listen normally on 2 lo 127.0.0.1:123 Sep 01 05:58:23 volumio-x86 ntpd[3240]: Listen normally on 3 eth0 192.168.0.115:123 Sep 01 05:58:23 volumio-x86 ntpd[3240]: Listening on routing socket on fd #20 for interface updates Sep 01 05:58:23 volumio-x86 ntpd[3240]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 05:58:23 volumio-x86 ntpd[3240]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 05:58:24 volumio-x86 volumio-time-update[774]: volumio-time-update-util: Fetching time from Volumio... Sep 01 05:58:24 volumio-x86 volumio[1051]: info: Discovery: adding c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a Sep 01 05:58:24 volumio-x86 volumio[1051]: info: Discovery: Found device Volumio-x86 Sep 01 05:58:24 volumio-x86 volumio[1051]: info: CoreCommandRouter::volumioGetState Sep 01 05:58:24 volumio-x86 ntpd[3240]: Soliciting pool server 111.230.189.174 Sep 01 05:58:25 volumio-x86 ntpd[3240]: Soliciting pool server 81.168.83.93 Sep 01 05:58:25 volumio-x86 ntpd[3240]: Soliciting pool server 84.16.67.12 Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 05:58:26 volumio-x86 volumio[1051]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 05:58:26 volumio-x86 volumio[1051]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 05:58:26 volumio-x86 volumio[1051]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 05:58:26 volumio-x86 volumio[1051]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 05:58:26 volumio-x86 volumio[1051]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 05:58:26 volumio-x86 volumio[1051]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 05:58:26 volumio-x86 ntpd[3240]: Soliciting pool server 139.199.214.202 Sep 01 05:58:26 volumio-x86 ntpd[3240]: Soliciting pool server 119.28.183.184 Sep 01 05:58:26 volumio-x86 ntpd[3240]: Soliciting pool server 84.16.73.33 Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 05:58:26 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:58:26 volumio-x86 volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 05:58:26 volumio-x86 volumio[1051]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 05:58:26 volumio-x86 volumio[1051]: info: MyVolumio login type: Token Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 05:58:26 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 05:58:27 volumio-x86 volumio[1051]: info: Starting Streaming Service Transparent Proxy Sep 01 05:58:27 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 05:58:27 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 05:58:27 volumio-x86 volumio[1051]: info: Streaming services startup Sep 01 05:58:27 volumio-x86 volumio[1051]: info: Starting Streaming Daemon Sep 01 05:58:27 volumio-x86 sudo[3265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 05:58:27 volumio-x86 sudo[3265]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 05:58:27 volumio-x86 sudo[3265]: pam_unix(sudo:session): session closed for user root Sep 01 05:58:27 volumio-x86 volumio[1051]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 05:58:27 volumio-x86 volumio[1051]: error: Cannot start Volumio Streaming Daemon Sep 01 05:58:27 volumio-x86 volumio[1051]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 05:58:27 volumio-x86 volumio[1051]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 05:58:27 volumio-x86 volumio[1051]: STREAMING PROXY: Starting server on port 3245 Sep 01 05:58:27 volumio-x86 volumio[1051]: Node JS runtime: 14 Sep 01 05:58:27 volumio-x86 ntpd[3240]: Soliciting pool server 116.203.151.74 Sep 01 05:58:27 volumio-x86 ntpd[3240]: Soliciting pool server 193.182.111.14 Sep 01 05:58:27 volumio-x86 ntpd[3240]: Soliciting pool server 162.159.200.1 Sep 01 05:58:28 volumio-x86 ntpd[3240]: Soliciting pool server 202.118.1.81 Sep 01 05:58:28 volumio-x86 ntpd[3240]: Soliciting pool server 193.182.111.141 Sep 01 05:58:28 volumio-x86 ntpd[3240]: Soliciting pool server 193.182.111.141 Sep 01 05:58:29 volumio-x86 volumio[1051]: info: Volumio Network Manager: Network status updated: 1 Sep 01 05:58:29 volumio-x86 ntpd[3240]: Soliciting pool server 84.16.73.33 Sep 01 05:58:30 volumio-x86 ntpd[3240]: Soliciting pool server 108.59.2.24 Sep 01 05:58:31 volumio-x86 ntpd[3240]: Soliciting pool server 139.199.215.251 Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 22:10:00 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 22:10:05 volumio-x86 volumio[5394]: info: MyVolumio login type: Token Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Aug 31 22:10:30 volumio-x86 volumio[5394]: ---------------------------- MPD announces system playlist update Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Ignoring MPD Status Update Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Aug 31 22:10:30 volumio-x86 volumio[5394]: ---------------------------- MPD announces state update: player Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ControllerMpd::getState Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Aug 31 22:10:30 volumio-x86 volumio[5394]: ---------------------------- MPD announces system playlist update Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Ignoring MPD Status Update Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Aug 31 22:10:30 volumio-x86 volumio[5394]: ---------------------------- MPD announces state update: player Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ControllerMpd::getState Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ------------------------------ 1ms Aug 31 22:10:30 volumio-x86 volumio[5394]: info: sendMpdCommand status took 1 milliseconds Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ------------------------------ 0ms Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::parseState Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:10:30 volumio-x86 volumio[5394]: info: sendMpdCommand status took 1 milliseconds Aug 31 22:10:30 volumio-x86 volumio[5394]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::parseState Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ControllerMpd::pushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::servicePushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CorePlayQueue::getTrack 0 Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102891,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: CURRENT POSITION 0 Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::syncState stateService play Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Received an update from plugin. extracting info from payload Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::pushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::volumioPushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::pushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::volumioPushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ------------------------------ 10ms Aug 31 22:10:30 volumio-x86 volumio[5394]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ControllerMpd::pushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::servicePushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CorePlayQueue::getTrack 0 Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102891,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Aug 31 22:10:30 volumio-x86 volumio[5394]: verbose: CURRENT POSITION 0 Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::syncState stateService play Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:10:30 volumio-x86 volumio[5394]: info: Received an update from plugin. extracting info from payload Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::pushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::volumioPushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreStateMachine::pushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: CoreCommandRouter::volumioPushState Aug 31 22:10:30 volumio-x86 volumio[5394]: info: ------------------------------ 21ms Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 31 22:10:32 volumio-x86 sudo[7629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 22:10:32 volumio-x86 sudo[7629]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 22:10:32 volumio-x86 sudo[7629]: pam_unix(sudo:session): session closed for user root Aug 31 22:10:32 volumio-x86 volumio[5394]: info: MPD Permissions set Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 sudo[7632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 22:10:32 volumio-x86 sudo[7632]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 22:10:32 volumio-x86 systemd[1]: Stopping Music Player Daemon... Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 31 22:10:32 volumio-x86 systemd[1]: mpd.service: Succeeded. Aug 31 22:10:32 volumio-x86 systemd[1]: Stopped Music Player Daemon. Aug 31 22:10:32 volumio-x86 volumio[5394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 22:10:32 volumio-x86 systemd[1]: Starting Music Player Daemon... Aug 31 22:10:32 volumio-x86 sudo[7640]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 22:10:32 volumio-x86 sudo[7640]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 22:10:32 volumio-x86 sudo[7640]: pam_unix(sudo:session): session closed for user root Aug 31 22:10:32 volumio-x86 mpd[7642]: Aug 31 22:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 22:10:32 volumio-x86 systemd[1]: Started Music Player Daemon. Aug 31 22:10:32 volumio-x86 sudo[7632]: pam_unix(sudo:session): session closed for user root Aug 31 22:10:32 volumio-x86 volumio[5394]: error: updateQueue error: null Aug 31 22:10:35 volumio-x86 volumio[5394]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 31 22:10:35 volumio-x86 volumio[5394]: info: Retrying Login Due to Network Error Aug 31 22:10:45 volumio-x86 volumio[5394]: info: MyVolumio login type: Token Aug 31 22:11:01 volumio-x86 volumio[5394]: info: CoreCommandRouter::volumioGetState Aug 31 22:11:05 volumio-x86 volumio[5394]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 22:11:05 volumio-x86 volumio[5394]: Error: connect ETIMEDOUT 199.59.148.8:80 Aug 31 22:11:05 volumio-x86 volumio[5394]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 31 22:11:05 volumio-x86 volumio[5394]: errno: -110, Aug 31 22:11:05 volumio-x86 volumio[5394]: code: 'ETIMEDOUT', Aug 31 22:11:05 volumio-x86 volumio[5394]: syscall: 'connect', Aug 31 22:11:05 volumio-x86 volumio[5394]: address: '199.59.148.8', Aug 31 22:11:05 volumio-x86 volumio[5394]: port: 80 Aug 31 22:11:05 volumio-x86 volumio[5394]: } Aug 31 22:11:05 volumio-x86 volumio[5394]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 22:11:05 volumio-x86 sudo[7767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 22:10 Aug 31 22:11:05 volumio-x86 sudo[7767]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"