Jun 10 14:07:15 volumio-test kernel: Linux version 6.12.30-volumio (andrew@rig) (gcc (Ubuntu 14.2.0-4ubuntu2~24.04) 14.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Wed May 28 19:37:30 BST 2025 Jun 10 14:07:15 volumio-test kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=291d6001-c14a-4478-94b7-c7c05a8f0b84 bootpart=UUID=85D9-F09E datapart=UUID=830a8ade-a16c-4bd6-86ac-93943f6c29fc hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Jun 10 14:07:15 volumio-test kernel: KERNEL supported cpus: Jun 10 14:07:15 volumio-test kernel: Intel GenuineIntel Jun 10 14:07:15 volumio-test kernel: AMD AuthenticAMD Jun 10 14:07:15 volumio-test kernel: Hygon HygonGenuine Jun 10 14:07:15 volumio-test kernel: Centaur CentaurHauls Jun 10 14:07:15 volumio-test kernel: zhaoxin Shanghai Jun 10 14:07:15 volumio-test kernel: BIOS-provided physical RAM map: Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009d7ff] usable Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x000000000009d800-0x000000000009ffff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000c982ffff] usable Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000c9830000-0x00000000c9836fff] ACPI NVS Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000c9837000-0x00000000c9c68fff] usable Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000c9c69000-0x00000000ca111fff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000ca112000-0x00000000da8e7fff] usable Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000da8e8000-0x00000000da97afff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000da97b000-0x00000000da9dafff] ACPI data Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000da9db000-0x00000000db6cafff] ACPI NVS Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000db6cb000-0x00000000dbffefff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000dbfff000-0x00000000dbffffff] usable Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000dd000000-0x00000000df1fffff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000fed00000-0x00000000fed03fff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved Jun 10 14:07:15 volumio-test kernel: BIOS-e820: [mem 0x0000000100000000-0x000000021fdfffff] usable Jun 10 14:07:15 volumio-test kernel: NX (Execute Disable) protection: active Jun 10 14:07:15 volumio-test kernel: APIC: Static calls initialized Jun 10 14:07:15 volumio-test kernel: SMBIOS 2.8 present. Jun 10 14:07:15 volumio-test kernel: DMI: LENOVO 10AY003UUK/SHARKBAY, BIOS FHKT87AUS 12/21/2021 Jun 10 14:07:15 volumio-test kernel: DMI: Memory slots populated: 2/2 Jun 10 14:07:15 volumio-test kernel: tsc: Fast TSC calibration using PIT Jun 10 14:07:15 volumio-test kernel: tsc: Detected 3092.745 MHz processor Jun 10 14:07:15 volumio-test kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 10 14:07:15 volumio-test kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 10 14:07:15 volumio-test kernel: last_pfn = 0x21fe00 max_arch_pfn = 0x400000000 Jun 10 14:07:15 volumio-test kernel: MTRR map: 9 entries (5 fixed + 4 variable; max 25), built from 10 variable MTRRs Jun 10 14:07:15 volumio-test kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 10 14:07:15 volumio-test kernel: e820: update [mem 0xdd000000-0xffffffff] usable ==> reserved Jun 10 14:07:15 volumio-test kernel: last_pfn = 0xdc000 max_arch_pfn = 0x400000000 Jun 10 14:07:15 volumio-test kernel: found SMP MP-table at [mem 0x000fcc80-0x000fcc8f] Jun 10 14:07:15 volumio-test kernel: Using GB pages for direct mapping Jun 10 14:07:15 volumio-test kernel: RAMDISK: [mem 0x7b351000-0x7fffffff] Jun 10 14:07:15 volumio-test kernel: ACPI: Early table checksum verification disabled Jun 10 14:07:15 volumio-test kernel: ACPI: RSDP 0x00000000000F0450 000024 (v02 LENOVO) Jun 10 14:07:15 volumio-test kernel: ACPI: XSDT 0x00000000DA98E090 0000A4 (v01 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: FACP 0x00000000DA9A31B0 00010C (v05 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: DSDT 0x00000000DA98E1C8 014FE2 (v02 LENOVO TC-FH 00001570 INTL 20120711) Jun 10 14:07:15 volumio-test kernel: ACPI: FACS 0x00000000DB6CAF80 000040 Jun 10 14:07:15 volumio-test kernel: ACPI: APIC 0x00000000DA9A32C0 000062 (v03 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: FPDT 0x00000000DA9A3328 000044 (v01 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: FIDT 0x00000000DA9A3370 00009C (v01 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: ASF! 0x00000000DA9A3410 0000A5 (v32 LENOVO TC-FH 00001570 TFSM 000F4240) Jun 10 14:07:15 volumio-test kernel: ACPI: DBGP 0x00000000DA9A34B8 000034 (v01 LENOVO TC-FH 00001570 MSFT 00000005) Jun 10 14:07:15 volumio-test kernel: ACPI: MSDM 0x00000000DA9A34F0 000055 (v03 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: SSDT 0x00000000DA9A3548 000539 (v01 LENOVO TC-FH 00001570 INTL 20120711) Jun 10 14:07:15 volumio-test kernel: ACPI: SSDT 0x00000000DA9A3A88 000AD8 (v01 LENOVO TC-FH 00001570 INTL 20120711) Jun 10 14:07:15 volumio-test kernel: ACPI: MCFG 0x00000000DA9A4560 00003C (v01 LENOVO TC-FH 00001570 MSFT 00000097) Jun 10 14:07:15 volumio-test kernel: ACPI: SLIC 0x00000000DA9A45A0 000176 (v01 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: HPET 0x00000000DA9A4718 000038 (v01 LENOVO TC-FH 00001570 AMI. 00000005) Jun 10 14:07:15 volumio-test kernel: ACPI: SSDT 0x00000000DA9A4750 00036D (v01 LENOVO TC-FH 00001570 INTL 20120711) Jun 10 14:07:15 volumio-test kernel: ACPI: SSDT 0x00000000DA9A4AC0 003528 (v01 LENOVO TC-FH 00001570 INTL 20091112) Jun 10 14:07:15 volumio-test kernel: ACPI: BGRT 0x00000000DA9A7FE8 000038 (v00 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: LUFT 0x00000000DA9A8020 0329E2 (v01 LENOVO TC-FH 00001570 AMI 00010013) Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving FACP table memory at [mem 0xda9a31b0-0xda9a32bb] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving DSDT table memory at [mem 0xda98e1c8-0xda9a31a9] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving FACS table memory at [mem 0xdb6caf80-0xdb6cafbf] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving APIC table memory at [mem 0xda9a32c0-0xda9a3321] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving FPDT table memory at [mem 0xda9a3328-0xda9a336b] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving FIDT table memory at [mem 0xda9a3370-0xda9a340b] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving ASF! table memory at [mem 0xda9a3410-0xda9a34b4] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving DBGP table memory at [mem 0xda9a34b8-0xda9a34eb] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving MSDM table memory at [mem 0xda9a34f0-0xda9a3544] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving SSDT table memory at [mem 0xda9a3548-0xda9a3a80] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving SSDT table memory at [mem 0xda9a3a88-0xda9a455f] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving MCFG table memory at [mem 0xda9a4560-0xda9a459b] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving SLIC table memory at [mem 0xda9a45a0-0xda9a4715] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving HPET table memory at [mem 0xda9a4718-0xda9a474f] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving SSDT table memory at [mem 0xda9a4750-0xda9a4abc] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving SSDT table memory at [mem 0xda9a4ac0-0xda9a7fe7] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving BGRT table memory at [mem 0xda9a7fe8-0xda9a801f] Jun 10 14:07:15 volumio-test kernel: ACPI: Reserving LUFT table memory at [mem 0xda9a8020-0xda9daa01] Jun 10 14:07:15 volumio-test kernel: Zone ranges: Jun 10 14:07:15 volumio-test kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 10 14:07:15 volumio-test kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jun 10 14:07:15 volumio-test kernel: Normal [mem 0x0000000100000000-0x000000021fdfffff] Jun 10 14:07:15 volumio-test kernel: Movable zone start for each node Jun 10 14:07:15 volumio-test kernel: Early memory node ranges Jun 10 14:07:15 volumio-test kernel: node 0: [mem 0x0000000000001000-0x000000000009cfff] Jun 10 14:07:15 volumio-test kernel: node 0: [mem 0x0000000000100000-0x00000000c982ffff] Jun 10 14:07:15 volumio-test kernel: node 0: [mem 0x00000000c9837000-0x00000000c9c68fff] Jun 10 14:07:15 volumio-test kernel: node 0: [mem 0x00000000ca112000-0x00000000da8e7fff] Jun 10 14:07:15 volumio-test kernel: node 0: [mem 0x00000000dbfff000-0x00000000dbffffff] Jun 10 14:07:15 volumio-test kernel: node 0: [mem 0x0000000100000000-0x000000021fdfffff] Jun 10 14:07:15 volumio-test kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000021fdfffff] Jun 10 14:07:15 volumio-test kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 10 14:07:15 volumio-test kernel: On node 0, zone DMA: 99 pages in unavailable ranges Jun 10 14:07:15 volumio-test kernel: On node 0, zone DMA32: 7 pages in unavailable ranges Jun 10 14:07:15 volumio-test kernel: On node 0, zone DMA32: 1193 pages in unavailable ranges Jun 10 14:07:15 volumio-test kernel: On node 0, zone DMA32: 5911 pages in unavailable ranges Jun 10 14:07:15 volumio-test kernel: On node 0, zone Normal: 16384 pages in unavailable ranges Jun 10 14:07:15 volumio-test kernel: On node 0, zone Normal: 512 pages in unavailable ranges Jun 10 14:07:15 volumio-test kernel: Reserving Intel graphics memory at [mem 0xdd200000-0xdf1fffff] Jun 10 14:07:15 volumio-test kernel: ACPI: PM-Timer IO Port: 0x1808 Jun 10 14:07:15 volumio-test kernel: ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1]) Jun 10 14:07:15 volumio-test kernel: IOAPIC[0]: apic_id 8, version 32, address 0xfec00000, GSI 0-23 Jun 10 14:07:15 volumio-test kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jun 10 14:07:15 volumio-test kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 10 14:07:15 volumio-test kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 10 14:07:15 volumio-test kernel: ACPI: HPET id: 0x8086a701 base: 0xfed00000 Jun 10 14:07:15 volumio-test kernel: TSC deadline timer available Jun 10 14:07:15 volumio-test kernel: CPU topo: Max. logical packages: 1 Jun 10 14:07:15 volumio-test kernel: CPU topo: Max. logical dies: 1 Jun 10 14:07:15 volumio-test kernel: CPU topo: Max. dies per package: 1 Jun 10 14:07:15 volumio-test kernel: CPU topo: Max. threads per core: 1 Jun 10 14:07:15 volumio-test kernel: CPU topo: Num. cores per package: 2 Jun 10 14:07:15 volumio-test kernel: CPU topo: Num. threads per package: 2 Jun 10 14:07:15 volumio-test kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Jun 10 14:07:15 volumio-test kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Jun 10 14:07:15 volumio-test kernel: PM: hibernation: Registered nosave memory: [mem 0x0009d000-0x000fffff] Jun 10 14:07:15 volumio-test kernel: PM: hibernation: Registered nosave memory: [mem 0xc9830000-0xc9836fff] Jun 10 14:07:15 volumio-test kernel: PM: hibernation: Registered nosave memory: [mem 0xc9c69000-0xca111fff] Jun 10 14:07:15 volumio-test kernel: PM: hibernation: Registered nosave memory: [mem 0xda8e8000-0xdbffefff] Jun 10 14:07:15 volumio-test kernel: PM: hibernation: Registered nosave memory: [mem 0xdc000000-0xffffffff] Jun 10 14:07:15 volumio-test kernel: [mem 0xdf200000-0xf7ffffff] available for PCI devices Jun 10 14:07:15 volumio-test kernel: Booting paravirtualized kernel on bare hardware Jun 10 14:07:15 volumio-test kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Jun 10 14:07:15 volumio-test kernel: setup_percpu: NR_CPUS:64 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Jun 10 14:07:15 volumio-test kernel: percpu: Embedded 59 pages/cpu s201432 r8192 d32040 u1048576 Jun 10 14:07:15 volumio-test kernel: pcpu-alloc: s201432 r8192 d32040 u1048576 alloc=1*2097152 Jun 10 14:07:15 volumio-test kernel: pcpu-alloc: [0] 0 1 Jun 10 14:07:15 volumio-test kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=291d6001-c14a-4478-94b7-c7c05a8f0b84 bootpart=UUID=85D9-F09E datapart=UUID=830a8ade-a16c-4bd6-86ac-93943f6c29fc hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Jun 10 14:07:15 volumio-test kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=291d6001-c14a-4478-94b7-c7c05a8f0b84 bootpart=UUID=85D9-F09E datapart=UUID=830a8ade-a16c-4bd6-86ac-93943f6c29fc hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Jun 10 14:07:15 volumio-test kernel: random: crng init done Jun 10 14:07:15 volumio-test kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Jun 10 14:07:15 volumio-test kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 10 14:07:15 volumio-test kernel: Built 1 zonelists, mobility grouping on. Total pages: 2073045 Jun 10 14:07:15 volumio-test kernel: mem auto-init: stack:all(pattern), heap alloc:off, heap free:off Jun 10 14:07:15 volumio-test kernel: software IO TLB: area num 2. Jun 10 14:07:15 volumio-test kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jun 10 14:07:15 volumio-test kernel: Kernel/User page tables isolation: enabled Jun 10 14:07:15 volumio-test kernel: ftrace: allocating 43661 entries in 171 pages Jun 10 14:07:15 volumio-test kernel: ftrace: allocated 171 pages with 5 groups Jun 10 14:07:15 volumio-test kernel: Dynamic Preempt: voluntary Jun 10 14:07:15 volumio-test kernel: rcu: Preemptible hierarchical RCU implementation. Jun 10 14:07:15 volumio-test kernel: rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2. Jun 10 14:07:15 volumio-test kernel: Trampoline variant of Tasks RCU enabled. Jun 10 14:07:15 volumio-test kernel: Rude variant of Tasks RCU enabled. Jun 10 14:07:15 volumio-test kernel: Tracing variant of Tasks RCU enabled. Jun 10 14:07:15 volumio-test kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Jun 10 14:07:15 volumio-test kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jun 10 14:07:15 volumio-test kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jun 10 14:07:15 volumio-test kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jun 10 14:07:15 volumio-test kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jun 10 14:07:15 volumio-test kernel: NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16 Jun 10 14:07:15 volumio-test kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 10 14:07:15 volumio-test kernel: Console: colour VGA+ 80x25 Jun 10 14:07:15 volumio-test kernel: printk: legacy console [tty0] enabled Jun 10 14:07:15 volumio-test kernel: ACPI: Core revision 20240827 Jun 10 14:07:15 volumio-test kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns Jun 10 14:07:15 volumio-test kernel: APIC: Switch to symmetric I/O mode setup Jun 10 14:07:15 volumio-test kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0 Jun 10 14:07:15 volumio-test kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2c9481dcd4b, max_idle_ns: 440795220221 ns Jun 10 14:07:15 volumio-test kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 6185.49 BogoMIPS (lpj=12370980) Jun 10 14:07:15 volumio-test kernel: x86/cpu: VMX (outside TXT) disabled by BIOS Jun 10 14:07:15 volumio-test kernel: CPU0: Thermal monitoring enabled (TM1) Jun 10 14:07:15 volumio-test kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 Jun 10 14:07:15 volumio-test kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 Jun 10 14:07:15 volumio-test kernel: process: using mwait in idle threads Jun 10 14:07:15 volumio-test kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 10 14:07:15 volumio-test kernel: Spectre V2 : Mitigation: Retpolines Jun 10 14:07:15 volumio-test kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 10 14:07:15 volumio-test kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Jun 10 14:07:15 volumio-test kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Jun 10 14:07:15 volumio-test kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Jun 10 14:07:15 volumio-test kernel: MDS: Mitigation: Clear CPU buffers Jun 10 14:07:15 volumio-test kernel: MMIO Stale Data: Unknown: No mitigations Jun 10 14:07:15 volumio-test kernel: SRBDS: Mitigation: Microcode Jun 10 14:07:15 volumio-test kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jun 10 14:07:15 volumio-test kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jun 10 14:07:15 volumio-test kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jun 10 14:07:15 volumio-test kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jun 10 14:07:15 volumio-test kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jun 10 14:07:15 volumio-test kernel: Freeing SMP alternatives memory: 36K Jun 10 14:07:15 volumio-test kernel: pid_max: default: 32768 minimum: 301 Jun 10 14:07:15 volumio-test kernel: LSM: initializing lsm=capability,yama Jun 10 14:07:15 volumio-test kernel: Yama: becoming mindful. Jun 10 14:07:15 volumio-test kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jun 10 14:07:15 volumio-test kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jun 10 14:07:15 volumio-test kernel: smpboot: CPU0: Intel(R) Core(TM) i3-4160T CPU @ 3.10GHz (family: 0x6, model: 0x3c, stepping: 0x3) Jun 10 14:07:15 volumio-test kernel: Performance Events: PEBS fmt2+, Haswell events, 16-deep LBR, full-width counters, Intel PMU driver. Jun 10 14:07:15 volumio-test kernel: ... version: 3 Jun 10 14:07:15 volumio-test kernel: ... bit width: 48 Jun 10 14:07:15 volumio-test kernel: ... generic registers: 8 Jun 10 14:07:15 volumio-test kernel: ... value mask: 0000ffffffffffff Jun 10 14:07:15 volumio-test kernel: ... max period: 00007fffffffffff Jun 10 14:07:15 volumio-test kernel: ... fixed-purpose events: 3 Jun 10 14:07:15 volumio-test kernel: ... event mask: 00000007000000ff Jun 10 14:07:15 volumio-test kernel: signal: max sigframe size: 1776 Jun 10 14:07:15 volumio-test kernel: Estimated ratio of average max frequency by base frequency (times 1024): 1024 Jun 10 14:07:15 volumio-test kernel: rcu: Hierarchical SRCU implementation. Jun 10 14:07:15 volumio-test kernel: rcu: Max phase no-delay instances is 1000. Jun 10 14:07:15 volumio-test kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jun 10 14:07:15 volumio-test kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. Jun 10 14:07:15 volumio-test kernel: smp: Bringing up secondary CPUs ... Jun 10 14:07:15 volumio-test kernel: smpboot: x86: Booting SMP configuration: Jun 10 14:07:15 volumio-test kernel: .... node #0, CPUs: #1 Jun 10 14:07:15 volumio-test kernel: smp: Brought up 1 node, 2 CPUs Jun 10 14:07:15 volumio-test kernel: smpboot: Total of 2 processors activated (12370.98 BogoMIPS) Jun 10 14:07:15 volumio-test kernel: Memory: 7967836K/8292180K available (16384K kernel code, 2020K rwdata, 5352K rodata, 3048K init, 928K bss, 318020K reserved, 0K cma-reserved) Jun 10 14:07:15 volumio-test kernel: devtmpfs: initialized Jun 10 14:07:15 volumio-test kernel: x86/mm: Memory block size: 128MB Jun 10 14:07:15 volumio-test kernel: ACPI: PM: Registering ACPI NVS region [mem 0xc9830000-0xc9836fff] (28672 bytes) Jun 10 14:07:15 volumio-test kernel: ACPI: PM: Registering ACPI NVS region [mem 0xda9db000-0xdb6cafff] (13565952 bytes) Jun 10 14:07:15 volumio-test kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jun 10 14:07:15 volumio-test kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jun 10 14:07:15 volumio-test kernel: pinctrl core: initialized pinctrl subsystem Jun 10 14:07:15 volumio-test kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 10 14:07:15 volumio-test kernel: audit: initializing netlink subsys (disabled) Jun 10 14:07:15 volumio-test kernel: audit: type=2000 audit(1749560829.000:1): state=initialized audit_enabled=0 res=1 Jun 10 14:07:15 volumio-test kernel: thermal_sys: Registered thermal governor 'fair_share' Jun 10 14:07:15 volumio-test kernel: thermal_sys: Registered thermal governor 'bang_bang' Jun 10 14:07:15 volumio-test kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 10 14:07:15 volumio-test kernel: thermal_sys: Registered thermal governor 'user_space' Jun 10 14:07:15 volumio-test kernel: cpuidle: using governor ladder Jun 10 14:07:15 volumio-test kernel: cpuidle: using governor menu Jun 10 14:07:15 volumio-test kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 10 14:07:15 volumio-test kernel: PCI: ECAM [mem 0xf8000000-0xfbffffff] (base 0xf8000000) for domain 0000 [bus 00-3f] Jun 10 14:07:15 volumio-test kernel: PCI: Using configuration type 1 for base access Jun 10 14:07:15 volumio-test kernel: core: PMU erratum BJ122, BV98, HSD29 workaround disabled, HT off Jun 10 14:07:15 volumio-test kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 10 14:07:15 volumio-test kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 10 14:07:15 volumio-test kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jun 10 14:07:15 volumio-test kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 10 14:07:15 volumio-test kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 10 14:07:15 volumio-test kernel: ACPI: Added _OSI(Module Device) Jun 10 14:07:15 volumio-test kernel: ACPI: Added _OSI(Processor Device) Jun 10 14:07:15 volumio-test kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jun 10 14:07:15 volumio-test kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 10 14:07:15 volumio-test kernel: ACPI: 5 ACPI AML tables successfully acquired and loaded Jun 10 14:07:15 volumio-test kernel: ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored Jun 10 14:07:15 volumio-test kernel: ACPI: Dynamic OEM Table Load: Jun 10 14:07:15 volumio-test kernel: ACPI: SSDT 0xFFFF88810010E800 0003D3 (v01 PmRef Cpu0Cst 00003001 INTL 20120711) Jun 10 14:07:15 volumio-test kernel: ACPI: Dynamic OEM Table Load: Jun 10 14:07:15 volumio-test kernel: ACPI: SSDT 0xFFFF888100974800 0005AA (v01 PmRef ApIst 00003000 INTL 20120711) Jun 10 14:07:15 volumio-test kernel: ACPI: Dynamic OEM Table Load: Jun 10 14:07:15 volumio-test kernel: ACPI: SSDT 0xFFFF8881002DF200 000119 (v01 PmRef ApCst 00003000 INTL 20120711) Jun 10 14:07:15 volumio-test kernel: ACPI: Interpreter enabled Jun 10 14:07:15 volumio-test kernel: ACPI: PM: (supports S0 S3 S4 S5) Jun 10 14:07:15 volumio-test kernel: ACPI: Using IOAPIC for interrupt routing Jun 10 14:07:15 volumio-test kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 10 14:07:15 volumio-test kernel: PCI: Using E820 reservations for host bridge windows Jun 10 14:07:15 volumio-test kernel: ACPI: Enabled 9 GPEs in block 00 to 3F Jun 10 14:07:15 volumio-test kernel: ACPI: \_TZ_.FN00: New power resource Jun 10 14:07:15 volumio-test kernel: ACPI: \_TZ_.FN01: New power resource Jun 10 14:07:15 volumio-test kernel: ACPI: \_TZ_.FN02: New power resource Jun 10 14:07:15 volumio-test kernel: ACPI: \_TZ_.FN03: New power resource Jun 10 14:07:15 volumio-test kernel: ACPI: \_TZ_.FN04: New power resource Jun 10 14:07:15 volumio-test kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3e]) Jun 10 14:07:15 volumio-test kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jun 10 14:07:15 volumio-test kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR] Jun 10 14:07:15 volumio-test kernel: PCI host bridge to bus 0000:00 Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000e7fff window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: root bus resource [mem 0xdf200000-0xfeafffff window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: root bus resource [bus 00-3e] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:00.0: [8086:0c00] type 00 class 0x060000 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: [8086:041e] type 00 class 0x030000 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: BAR 0 [mem 0xf7800000-0xf7bfffff 64bit] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: BAR 2 [mem 0xe0000000-0xefffffff 64bit pref] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: BAR 4 [io 0xf000-0xf03f] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:03.0: [8086:0c0c] type 00 class 0x040300 PCIe Root Complex Integrated Endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:03.0: BAR 0 [mem 0xf7c34000-0xf7c37fff 64bit] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:14.0: [8086:8c31] type 00 class 0x0c0330 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:14.0: BAR 0 [mem 0xf7c20000-0xf7c2ffff 64bit] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:14.0: PME# supported from D3hot D3cold Jun 10 14:07:15 volumio-test kernel: pci 0000:00:16.0: [8086:8c3a] type 00 class 0x078000 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:16.0: BAR 0 [mem 0xf7c3f000-0xf7c3f00f 64bit] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:16.0: PME# supported from D0 D3hot D3cold Jun 10 14:07:15 volumio-test kernel: pci 0000:00:19.0: [8086:153b] type 00 class 0x020000 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:19.0: BAR 0 [mem 0xf7c00000-0xf7c1ffff] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:19.0: BAR 1 [mem 0xf7c3d000-0xf7c3dfff] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:19.0: BAR 2 [io 0xf080-0xf09f] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:19.0: PME# supported from D0 D3hot D3cold Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1a.0: [8086:8c2d] type 00 class 0x0c0320 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1a.0: BAR 0 [mem 0xf7c3c000-0xf7c3c3ff] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1b.0: [8086:8c20] type 00 class 0x040300 PCIe Root Complex Integrated Endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1b.0: BAR 0 [mem 0xf7c30000-0xf7c33fff 64bit] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1d.0: [8086:8c26] type 00 class 0x0c0320 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1d.0: BAR 0 [mem 0xf7c3b000-0xf7c3b3ff] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.0: [8086:8c5c] type 00 class 0x060100 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: [8086:8c02] type 00 class 0x010601 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: BAR 0 [io 0xf0d0-0xf0d7] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: BAR 1 [io 0xf0c0-0xf0c3] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: BAR 2 [io 0xf0b0-0xf0b7] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: BAR 3 [io 0xf0a0-0xf0a3] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: BAR 4 [io 0xf060-0xf07f] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: BAR 5 [mem 0xf7c3a000-0xf7c3a7ff] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.2: PME# supported from D3hot Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.3: [8086:8c22] type 00 class 0x0c0500 conventional PCI endpoint Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.3: BAR 0 [mem 0xf7c39000-0xf7c390ff 64bit] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1f.3: BAR 4 [io 0x0580-0x059f] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: on NUMA node 0 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 11 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 0 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKB disabled Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 10 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 5 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 0 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKF disabled Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 3 Jun 10 14:07:15 volumio-test kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 4 Jun 10 14:07:15 volumio-test kernel: iommu: Default domain type: Translated Jun 10 14:07:15 volumio-test kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 10 14:07:15 volumio-test kernel: SCSI subsystem initialized Jun 10 14:07:15 volumio-test kernel: libata version 3.00 loaded. Jun 10 14:07:15 volumio-test kernel: ACPI: bus type USB registered Jun 10 14:07:15 volumio-test kernel: usbcore: registered new interface driver usbfs Jun 10 14:07:15 volumio-test kernel: usbcore: registered new interface driver hub Jun 10 14:07:15 volumio-test kernel: usbcore: registered new device driver usb Jun 10 14:07:15 volumio-test kernel: PCI: Using ACPI for IRQ routing Jun 10 14:07:15 volumio-test kernel: PCI: pci_cache_line_size set to 64 bytes Jun 10 14:07:15 volumio-test kernel: e820: reserve RAM buffer [mem 0x0009d800-0x0009ffff] Jun 10 14:07:15 volumio-test kernel: e820: reserve RAM buffer [mem 0xc9830000-0xcbffffff] Jun 10 14:07:15 volumio-test kernel: e820: reserve RAM buffer [mem 0xc9c69000-0xcbffffff] Jun 10 14:07:15 volumio-test kernel: e820: reserve RAM buffer [mem 0xda8e8000-0xdbffffff] Jun 10 14:07:15 volumio-test kernel: e820: reserve RAM buffer [mem 0x21fe00000-0x21fffffff] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: vgaarb: bridge control possible Jun 10 14:07:15 volumio-test kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jun 10 14:07:15 volumio-test kernel: vgaarb: loaded Jun 10 14:07:15 volumio-test kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0 Jun 10 14:07:15 volumio-test kernel: hpet0: 8 comparators, 64-bit 14.318180 MHz counter Jun 10 14:07:15 volumio-test kernel: clocksource: Switched to clocksource tsc-early Jun 10 14:07:15 volumio-test kernel: VFS: Disk quotas dquot_6.6.0 Jun 10 14:07:15 volumio-test kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 10 14:07:15 volumio-test kernel: pnp: PnP ACPI init Jun 10 14:07:15 volumio-test kernel: system 00:00: [mem 0xfed40000-0xfed44fff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0x0680-0x069f] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0xffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0xffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0xffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0x1c00-0x1cfe] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0x1d00-0x1dfe] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0x1e00-0x1efe] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0x1f00-0x1ffe] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0x1800-0x18fe] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:01: [io 0x164e-0x164f] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:03: [io 0x1854-0x1857] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:04: [io 0x0a00-0x0a2f] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:04: [io 0x0a30-0x0a3f] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:04: [io 0x0a40-0x0a4f] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:05: [io 0x04d0-0x04d1] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfed1c000-0xfed1ffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfed10000-0xfed17fff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfed18000-0xfed18fff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfed19000-0xfed19fff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xf8000000-0xfbffffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfed20000-0xfed3ffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfed90000-0xfed93fff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfed45000-0xfed8ffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xff000000-0xffffffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xfee00000-0xfeefffff] could not be reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xf7fdf000-0xf7fdffff] has been reserved Jun 10 14:07:15 volumio-test kernel: system 00:06: [mem 0xf7fe0000-0xf7feffff] has been reserved Jun 10 14:07:15 volumio-test kernel: pnp: PnP ACPI: found 7 devices Jun 10 14:07:15 volumio-test kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 10 14:07:15 volumio-test kernel: NET: Registered PF_INET protocol family Jun 10 14:07:15 volumio-test kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 10 14:07:15 volumio-test kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Jun 10 14:07:15 volumio-test kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 10 14:07:15 volumio-test kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Jun 10 14:07:15 volumio-test kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 10 14:07:15 volumio-test kernel: TCP: Hash tables configured (established 65536 bind 65536) Jun 10 14:07:15 volumio-test kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Jun 10 14:07:15 volumio-test kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Jun 10 14:07:15 volumio-test kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: resource 7 [mem 0x000d0000-0x000e7fff window] Jun 10 14:07:15 volumio-test kernel: pci_bus 0000:00: resource 8 [mem 0xdf200000-0xfeafffff window] Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1a.0: quirk_usb_early_handoff+0x0/0x780 took 20248 usecs Jun 10 14:07:15 volumio-test kernel: pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x780 took 19513 usecs Jun 10 14:07:15 volumio-test kernel: PCI: CLS 64 bytes, default 64 Jun 10 14:07:15 volumio-test kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jun 10 14:07:15 volumio-test kernel: software IO TLB: mapped [mem 0x00000000d68e8000-0x00000000da8e8000] (64MB) Jun 10 14:07:15 volumio-test kernel: Trying to unpack rootfs image as initramfs... Jun 10 14:07:15 volumio-test kernel: RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer Jun 10 14:07:15 volumio-test kernel: RAPL PMU: hw unit of domain pp0-core 2^-14 Joules Jun 10 14:07:15 volumio-test kernel: RAPL PMU: hw unit of domain package 2^-14 Joules Jun 10 14:07:15 volumio-test kernel: RAPL PMU: hw unit of domain dram 2^-14 Joules Jun 10 14:07:15 volumio-test kernel: RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules Jun 10 14:07:15 volumio-test kernel: Initialise system trusted keyrings Jun 10 14:07:15 volumio-test kernel: workingset: timestamp_bits=46 max_order=21 bucket_order=0 Jun 10 14:07:15 volumio-test kernel: Key type asymmetric registered Jun 10 14:07:15 volumio-test kernel: Asymmetric key parser 'x509' registered Jun 10 14:07:15 volumio-test kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jun 10 14:07:15 volumio-test kernel: io scheduler mq-deadline registered Jun 10 14:07:15 volumio-test kernel: io scheduler kyber registered Jun 10 14:07:15 volumio-test kernel: ledtrig-cpu: registered to indicate activity on CPUs Jun 10 14:07:15 volumio-test kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Jun 10 14:07:15 volumio-test kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jun 10 14:07:15 volumio-test kernel: ACPI: button: Power Button [PWRB] Jun 10 14:07:15 volumio-test kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1 Jun 10 14:07:15 volumio-test kernel: ACPI: button: Power Button [PWRF] Jun 10 14:07:15 volumio-test kernel: thermal LNXTHERM:00: registered as thermal_zone0 Jun 10 14:07:15 volumio-test kernel: ACPI: thermal: Thermal Zone [TZ00] (28 C) Jun 10 14:07:15 volumio-test kernel: thermal LNXTHERM:01: registered as thermal_zone1 Jun 10 14:07:15 volumio-test kernel: ACPI: thermal: Thermal Zone [TZ01] (30 C) Jun 10 14:07:15 volumio-test kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Jun 10 14:07:15 volumio-test kernel: Linux agpgart interface v0.103 Jun 10 14:07:15 volumio-test kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Jun 10 14:07:15 volumio-test kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 Jun 10 14:07:15 volumio-test kernel: xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x0000000000009810 Jun 10 14:07:15 volumio-test kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Jun 10 14:07:15 volumio-test kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2 Jun 10 14:07:15 volumio-test kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.0 SuperSpeed Jun 10 14:07:15 volumio-test kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jun 10 14:07:15 volumio-test kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 10 14:07:15 volumio-test kernel: usb usb1: Product: xHCI Host Controller Jun 10 14:07:15 volumio-test kernel: usb usb1: Manufacturer: Linux 6.12.30-volumio xhci-hcd Jun 10 14:07:15 volumio-test kernel: usb usb1: SerialNumber: 0000:00:14.0 Jun 10 14:07:15 volumio-test kernel: hub 1-0:1.0: USB hub found Jun 10 14:07:15 volumio-test kernel: hub 1-0:1.0: 10 ports detected Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1a.0: EHCI Host Controller Jun 10 14:07:15 volumio-test kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jun 10 14:07:15 volumio-test kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 10 14:07:15 volumio-test kernel: usb usb2: Product: xHCI Host Controller Jun 10 14:07:15 volumio-test kernel: usb usb2: Manufacturer: Linux 6.12.30-volumio xhci-hcd Jun 10 14:07:15 volumio-test kernel: usb usb2: SerialNumber: 0000:00:14.0 Jun 10 14:07:15 volumio-test kernel: hub 2-0:1.0: USB hub found Jun 10 14:07:15 volumio-test kernel: hub 2-0:1.0: 2 ports detected Jun 10 14:07:15 volumio-test kernel: usbcore: registered new interface driver usb-storage Jun 10 14:07:15 volumio-test kernel: i8042: PNP: No PS/2 controller found. Jun 10 14:07:15 volumio-test kernel: mousedev: PS/2 mouse device common for all mice Jun 10 14:07:15 volumio-test kernel: rtc_cmos 00:02: RTC can wake from S4 Jun 10 14:07:15 volumio-test kernel: rtc_cmos 00:02: registered as rtc0 Jun 10 14:07:15 volumio-test kernel: rtc_cmos 00:02: setting system clock to 2025-06-10T13:07:09 UTC (1749560829) Jun 10 14:07:15 volumio-test kernel: rtc_cmos 00:02: alarms up to one month, y3k, 242 bytes nvram Jun 10 14:07:15 volumio-test kernel: i2c_dev: i2c /dev entries driver Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 3 Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1a.0: debug port 2 Jun 10 14:07:15 volumio-test kernel: intel_pstate: Intel P-state driver initializing Jun 10 14:07:15 volumio-test kernel: hid: raw HID events driver (C) Jiri Kosina Jun 10 14:07:15 volumio-test kernel: NET: Registered PF_INET6 protocol family Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1a.0: irq 16, io mem 0xf7c3c000 Jun 10 14:07:15 volumio-test kernel: Segment Routing with IPv6 Jun 10 14:07:15 volumio-test kernel: In-situ OAM (IOAM) with IPv6 Jun 10 14:07:15 volumio-test kernel: mip6: Mobile IPv6 Jun 10 14:07:15 volumio-test kernel: NET: Registered PF_PACKET protocol family Jun 10 14:07:15 volumio-test kernel: mpls_gso: MPLS GSO support Jun 10 14:07:15 volumio-test kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance' Jun 10 14:07:15 volumio-test kernel: microcode: Current revision: 0x00000028 Jun 10 14:07:15 volumio-test kernel: IPI shorthand broadcast: enabled Jun 10 14:07:15 volumio-test kernel: sched_clock: Marking stable (236003967, 1985878)->(241621915, -3632070) Jun 10 14:07:15 volumio-test kernel: Loading compiled-in X.509 certificates Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00 Jun 10 14:07:15 volumio-test kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jun 10 14:07:15 volumio-test kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 10 14:07:15 volumio-test kernel: usb usb3: Product: EHCI Host Controller Jun 10 14:07:15 volumio-test kernel: usb usb3: Manufacturer: Linux 6.12.30-volumio ehci_hcd Jun 10 14:07:15 volumio-test kernel: usb usb3: SerialNumber: 0000:00:1a.0 Jun 10 14:07:15 volumio-test kernel: hub 3-0:1.0: USB hub found Jun 10 14:07:15 volumio-test kernel: hub 3-0:1.0: 2 ports detected Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1d.0: EHCI Host Controller Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 4 Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1d.0: debug port 2 Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1d.0: irq 23, io mem 0xf7c3b000 Jun 10 14:07:15 volumio-test kernel: clk: Disabling unused clocks Jun 10 14:07:15 volumio-test kernel: PM: genpd: Disabling unused power domains Jun 10 14:07:15 volumio-test kernel: ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00 Jun 10 14:07:15 volumio-test kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jun 10 14:07:15 volumio-test kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 10 14:07:15 volumio-test kernel: usb usb4: Product: EHCI Host Controller Jun 10 14:07:15 volumio-test kernel: usb usb4: Manufacturer: Linux 6.12.30-volumio ehci_hcd Jun 10 14:07:15 volumio-test kernel: usb usb4: SerialNumber: 0000:00:1d.0 Jun 10 14:07:15 volumio-test kernel: hub 4-0:1.0: USB hub found Jun 10 14:07:15 volumio-test kernel: hub 4-0:1.0: 2 ports detected Jun 10 14:07:15 volumio-test kernel: usb 1-2: new high-speed USB device number 2 using xhci_hcd Jun 10 14:07:15 volumio-test kernel: usb 3-1: new high-speed USB device number 2 using ehci-pci Jun 10 14:07:15 volumio-test kernel: usb 4-1: new high-speed USB device number 2 using ehci-pci Jun 10 14:07:15 volumio-test kernel: usb 1-2: New USB device found, idVendor=2357, idProduct=0138, bcdDevice= 2.10 Jun 10 14:07:15 volumio-test kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 10 14:07:15 volumio-test kernel: usb 1-2: Product: 802.11ac NIC Jun 10 14:07:15 volumio-test kernel: usb 1-2: Manufacturer: Realtek Jun 10 14:07:15 volumio-test kernel: usb 1-2: SerialNumber: 123456 Jun 10 14:07:15 volumio-test kernel: usb 3-1: New USB device found, idVendor=8087, idProduct=8008, bcdDevice= 0.04 Jun 10 14:07:15 volumio-test kernel: usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jun 10 14:07:15 volumio-test kernel: hub 3-1:1.0: USB hub found Jun 10 14:07:15 volumio-test kernel: hub 3-1:1.0: 4 ports detected Jun 10 14:07:15 volumio-test kernel: Freeing initrd memory: 78524K Jun 10 14:07:15 volumio-test kernel: usb 4-1: New USB device found, idVendor=8087, idProduct=8000, bcdDevice= 0.04 Jun 10 14:07:15 volumio-test kernel: usb 4-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jun 10 14:07:15 volumio-test kernel: hub 4-1:1.0: USB hub found Jun 10 14:07:15 volumio-test kernel: hub 4-1:1.0: 6 ports detected Jun 10 14:07:15 volumio-test kernel: Freeing unused kernel image (initmem) memory: 3048K Jun 10 14:07:15 volumio-test kernel: Write protecting the kernel read-only data: 22528k Jun 10 14:07:15 volumio-test kernel: Freeing unused kernel image (rodata/data gap) memory: 792K Jun 10 14:07:15 volumio-test kernel: Run /init as init process Jun 10 14:07:15 volumio-test kernel: with arguments: Jun 10 14:07:15 volumio-test kernel: /init Jun 10 14:07:15 volumio-test kernel: splash Jun 10 14:07:15 volumio-test kernel: with environment: Jun 10 14:07:15 volumio-test kernel: HOME=/ Jun 10 14:07:15 volumio-test kernel: TERM=linux Jun 10 14:07:15 volumio-test kernel: BOOT_IMAGE=vmlinuz Jun 10 14:07:15 volumio-test kernel: biosdevname=0 Jun 10 14:07:15 volumio-test kernel: imgpart=UUID=291d6001-c14a-4478-94b7-c7c05a8f0b84 Jun 10 14:07:15 volumio-test kernel: bootpart=UUID=85D9-F09E Jun 10 14:07:15 volumio-test kernel: datapart=UUID=830a8ade-a16c-4bd6-86ac-93943f6c29fc Jun 10 14:07:15 volumio-test kernel: hwdevice=x86 Jun 10 14:07:15 volumio-test kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Jun 10 14:07:15 volumio-test kernel: imgfile=/volumio_current.sqsh Jun 10 14:07:15 volumio-test kernel: use_kmsg=no Jun 10 14:07:15 volumio-test kernel: usb 1-10: new high-speed USB device number 3 using xhci_hcd Jun 10 14:07:15 volumio-test kernel: ahci 0000:00:1f.2: version 3.0 Jun 10 14:07:15 volumio-test kernel: ahci 0000:00:1f.2: AHCI vers 0001.0300, 32 command slots, 6 Gbps, SATA mode Jun 10 14:07:15 volumio-test kernel: ahci 0000:00:1f.2: 1/4 ports implemented (port mask 0x1) Jun 10 14:07:15 volumio-test kernel: ahci 0000:00:1f.2: flags: 64bit ncq pm led clo pio slum part ems Jun 10 14:07:15 volumio-test kernel: e1000e: Intel(R) PRO/1000 Network Driver Jun 10 14:07:15 volumio-test kernel: e1000e: Copyright(c) 1999 - 2015 Intel Corporation. Jun 10 14:07:15 volumio-test kernel: e1000e 0000:00:19.0: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode Jun 10 14:07:15 volumio-test kernel: scsi host0: ahci Jun 10 14:07:15 volumio-test kernel: scsi host1: ahci Jun 10 14:07:15 volumio-test kernel: scsi host2: ahci Jun 10 14:07:15 volumio-test kernel: ACPI: bus type drm_connector registered Jun 10 14:07:15 volumio-test kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Jun 10 14:07:15 volumio-test kernel: i2c i2c-0: Successfully instantiated SPD at 0x50 Jun 10 14:07:15 volumio-test kernel: scsi host3: ahci Jun 10 14:07:15 volumio-test kernel: ata1: SATA max UDMA/133 abar m2048@0xf7c3a000 port 0xf7c3a100 irq 25 lpm-pol 3 Jun 10 14:07:15 volumio-test kernel: ata2: DUMMY Jun 10 14:07:15 volumio-test kernel: ata3: DUMMY Jun 10 14:07:15 volumio-test kernel: ata4: DUMMY Jun 10 14:07:15 volumio-test kernel: usb 1-10: New USB device found, idVendor=3353, idProduct=a001, bcdDevice= 2.00 Jun 10 14:07:15 volumio-test kernel: usb 1-10: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 14:07:15 volumio-test kernel: usb 1-10: Product: Tone1 Jun 10 14:07:15 volumio-test kernel: usb 1-10: Manufacturer: Khadas Jun 10 14:07:15 volumio-test kernel: e1000e 0000:00:19.0 eth0: (PCI Express:2.5GT/s:Width x1) 00:23:24:82:f0:70 Jun 10 14:07:15 volumio-test kernel: e1000e 0000:00:19.0 eth0: Intel(R) PRO/1000 Network Connection Jun 10 14:07:15 volumio-test kernel: e1000e 0000:00:19.0 eth0: MAC: 11, PHY: 12, PBA No: FFFFFF-0FF Jun 10 14:07:15 volumio-test kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Jun 10 14:07:15 volumio-test kernel: ata1.00: Model 'Samsung SSD 850 PRO 256GB', rev 'EXM04B6Q', applying quirks: noncqtrim zeroaftertrim Jun 10 14:07:15 volumio-test kernel: ata1.00: supports DRM functions and may not be fully accessible Jun 10 14:07:15 volumio-test kernel: ata1.00: ATA-9: Samsung SSD 850 PRO 256GB, EXM04B6Q, max UDMA/133 Jun 10 14:07:15 volumio-test kernel: ata1.00: 500118192 sectors, multi 1: LBA48 NCQ (depth 32), AA Jun 10 14:07:15 volumio-test kernel: ata1.00: Features: Trust Dev-Sleep NCQ-sndrcv Jun 10 14:07:15 volumio-test kernel: ata1.00: supports DRM functions and may not be fully accessible Jun 10 14:07:15 volumio-test kernel: ata1.00: configured for UDMA/133 Jun 10 14:07:15 volumio-test kernel: scsi 0:0:0:0: Direct-Access ATA Samsung SSD 850 4B6Q PQ: 0 ANSI: 5 Jun 10 14:07:15 volumio-test kernel: sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB) Jun 10 14:07:15 volumio-test kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 10 14:07:15 volumio-test kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 Jun 10 14:07:15 volumio-test kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jun 10 14:07:15 volumio-test kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Jun 10 14:07:15 volumio-test kernel: sda: sda1 sda2 sda3 Jun 10 14:07:15 volumio-test kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 10 14:07:15 volumio-test kernel: tsc: Refined TSC clocksource calibration: 3092.837 MHz Jun 10 14:07:15 volumio-test kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2c94d933972, max_idle_ns: 440795249834 ns Jun 10 14:07:15 volumio-test kernel: clocksource: Switched to clocksource tsc Jun 10 14:07:15 volumio-test kernel: i915 0000:00:02.0: [drm] Found HASWELL (device ID 041e) display version 7.00 stepping N/A Jun 10 14:07:15 volumio-test kernel: i915 0000:00:02.0: [drm] PipeC fused off Jun 10 14:07:15 volumio-test kernel: i915 0000:00:02.0: vgaarb: deactivate vga console Jun 10 14:07:15 volumio-test kernel: Console: switching to colour dummy device 80x25 Jun 10 14:07:15 volumio-test kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem Jun 10 14:07:15 volumio-test kernel: [drm] Initialized i915 1.6.0 for 0000:00:02.0 on minor 0 Jun 10 14:07:15 volumio-test kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Jun 10 14:07:15 volumio-test kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input2 Jun 10 14:07:15 volumio-test kernel: fbcon: i915drmfb (fb0) is primary device Jun 10 14:07:15 volumio-test kernel: Console: switching to colour frame buffer device 320x90 Jun 10 14:07:15 volumio-test kernel: i915 0000:00:02.0: [drm] fb0: i915drmfb frame buffer device Jun 10 14:07:15 volumio-test kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 10 14:07:15 volumio-test kernel: sdhci: Secure Digital Host Controller Interface driver Jun 10 14:07:15 volumio-test kernel: sdhci: Copyright(c) Pierre Ossman Jun 10 14:07:15 volumio-test kernel: usbcore: registered new interface driver usbhid Jun 10 14:07:15 volumio-test kernel: usbhid: USB HID core driver Jun 10 14:07:15 volumio-test kernel: fuse: init (API version 7.41) Jun 10 14:07:15 volumio-test kernel: usbcore: registered new interface driver uas Jun 10 14:07:15 volumio-test kernel: [drm] radeon kernel modesetting enabled. Jun 10 14:07:15 volumio-test kernel: scsi host4: pata_legacy Jun 10 14:07:15 volumio-test kernel: ata5: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 lpm-pol 0 Jun 10 14:07:15 volumio-test kernel: scsi host4: pata_legacy Jun 10 14:07:15 volumio-test kernel: ata5: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 lpm-pol 0 Jun 10 14:07:15 volumio-test kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jun 10 14:07:15 volumio-test kernel: EXT4-fs (sda2): recovery complete Jun 10 14:07:15 volumio-test kernel: EXT4-fs (sda2): mounted filesystem 291d6001-c14a-4478-94b7-c7c05a8f0b84 r/w with ordered data mode. Quota mode: none. Jun 10 14:07:15 volumio-test kernel: loop: module loaded Jun 10 14:07:15 volumio-test kernel: block device autoloading is deprecated and will be removed. Jun 10 14:07:15 volumio-test kernel: loop0: detected capacity change from 0 to 2312608 Jun 10 14:07:15 volumio-test kernel: EXT4-fs (sda3): recovery complete Jun 10 14:07:15 volumio-test kernel: EXT4-fs (sda3): mounted filesystem 830a8ade-a16c-4bd6-86ac-93943f6c29fc r/w with ordered data mode. Quota mode: none. Jun 10 14:07:15 volumio-test systemd[1]: Inserted module 'autofs4' Jun 10 14:07:15 volumio-test systemd[1]: systemd 252.36-1~deb12u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Jun 10 14:07:15 volumio-test systemd[1]: Detected architecture x86-64. Jun 10 14:07:15 volumio-test systemd[1]: Hostname set to . Jun 10 14:07:15 volumio-test systemd[1]: Queued start job for default target graphical.target. Jun 10 14:07:15 volumio-test systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 10 14:07:15 volumio-test systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 10 14:07:15 volumio-test systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 10 14:07:15 volumio-test systemd[1]: Created slice user.slice - User and Session Slice. Jun 10 14:07:15 volumio-test systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 10 14:07:15 volumio-test systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 10 14:07:15 volumio-test systemd[1]: Expecting device dev-disk-by\x2duuid-85D9\x2dF09E.device - /dev/disk/by-uuid/85D9-F09E... Jun 10 14:07:15 volumio-test systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 10 14:07:15 volumio-test systemd[1]: Reached target slices.target - Slice Units. Jun 10 14:07:15 volumio-test systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 10 14:07:15 volumio-test systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Jun 10 14:07:15 volumio-test systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Jun 10 14:07:15 volumio-test systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Jun 10 14:07:15 volumio-test systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Jun 10 14:07:15 volumio-test systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 10 14:07:15 volumio-test systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jun 10 14:07:15 volumio-test systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 10 14:07:15 volumio-test systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 10 14:07:15 volumio-test systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 10 14:07:15 volumio-test systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 10 14:07:15 volumio-test systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 10 14:07:15 volumio-test systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 10 14:07:15 volumio-test systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jun 10 14:07:15 volumio-test systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 10 14:07:15 volumio-test systemd[1]: Reached target swap.target - Swaps. Jun 10 14:07:15 volumio-test systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Jun 10 14:07:15 volumio-test systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 10 14:07:15 volumio-test systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 10 14:07:15 volumio-test systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 10 14:07:15 volumio-test systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 10 14:07:15 volumio-test systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 10 14:07:15 volumio-test systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 10 14:07:15 volumio-test systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-journald.service - Journal Service... Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 10 14:07:15 volumio-test systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 10 14:07:15 volumio-test systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 10 14:07:15 volumio-test systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 10 14:07:15 volumio-test systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 10 14:07:15 volumio-test systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 10 14:07:15 volumio-test systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 10 14:07:15 volumio-test systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 10 14:07:15 volumio-test systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 10 14:07:15 volumio-test systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 10 14:07:15 volumio-test systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 10 14:07:15 volumio-test systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 10 14:07:15 volumio-test systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 10 14:07:15 volumio-test systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 10 14:07:15 volumio-test systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 10 14:07:15 volumio-test systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 10 14:07:15 volumio-test systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 10 14:07:15 volumio-test systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 10 14:07:15 volumio-test systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 10 14:07:15 volumio-test systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 10 14:07:15 volumio-test systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 10 14:07:15 volumio-test systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:15 volumio-test systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Jun 10 14:07:15 volumio-test systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 10 14:07:15 volumio-test systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 10 14:07:15 volumio-test systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 10 14:07:15 volumio-test systemd[1]: dynamicswap.service: Deactivated successfully. Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Jun 10 14:07:15 volumio-test systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 10 14:07:15 volumio-test systemd-journald[470]: Journal started Jun 10 14:07:15 volumio-test systemd-journald[470]: Runtime Journal (/run/log/journal/f0340d7310d25610579f86ca6843b4ba) is 3.7M, max 30.0M, 26.2M free. Jun 10 14:07:15 volumio-test systemd[1]: Started systemd-journald.service - Journal Service. Jun 10 14:07:15 volumio-test fake-hwclock[461]: Current system time: 2025-06-10 13:07:15 Jun 10 14:07:15 volumio-test fake-hwclock[461]: fake-hwclock saved clock information is in the past: 2025-06-09 19:57:25 Jun 10 14:07:15 volumio-test fake-hwclock[461]: To set system time to this saved clock anyway, use "force" Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 10 14:07:15 volumio-test systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 10 14:07:15 volumio-test systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 10 14:07:15 volumio-test systemd[1]: Mounting tmp.mount - /tmp... Jun 10 14:07:15 volumio-test systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 10 14:07:15 volumio-test systemd[1]: Mounting var-log.mount - /var/log... Jun 10 14:07:15 volumio-test systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 10 14:07:15 volumio-test systemd[1]: Mounted tmp.mount - /tmp. Jun 10 14:07:15 volumio-test systemd[1]: Mounted var-log.mount - /var/log. Jun 10 14:07:15 volumio-test systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 10 14:07:15 volumio-test systemd-journald[470]: Runtime Journal (/run/log/journal/f0340d7310d25610579f86ca6843b4ba) is 3.7M, max 30.0M, 26.2M free. Jun 10 14:07:15 volumio-test systemd-journald[470]: Received client request to flush runtime journal. Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 10 14:07:15 volumio-test systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Jun 10 14:07:15 volumio-test systemd-udevd[496]: Using default interface naming scheme 'v252'. Jun 10 14:07:15 volumio-test systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 10 14:07:15 volumio-test systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Jun 10 14:07:15 volumio-test systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Jun 10 14:07:15 volumio-test systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jun 10 14:07:15 volumio-test systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Jun 10 14:07:15 volumio-test systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 10 14:07:15 volumio-test (udev-worker)[503]: Network interface NamePolicy= disabled on kernel command line. Jun 10 14:07:15 volumio-test kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jun 10 14:07:15 volumio-test systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Jun 10 14:07:15 volumio-test systemd[1]: Found device dev-disk-by\x2duuid-85D9\x2dF09E.device - Samsung_SSD_850_PRO_256GB boot. Jun 10 14:07:15 volumio-test systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-85D9\x2dF09E.service - File System Check on /dev/disk/by-uuid/85D9-F09E... Jun 10 14:07:15 volumio-test systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Jun 10 14:07:15 volumio-test systemd[1]: Found device sys-subsystem-net-devices-eth0.device - Ethernet Connection I217-V. Jun 10 14:07:15 volumio-test systemd-fsck[547]: fsck.fat 4.2 (2021-01-31) Jun 10 14:07:15 volumio-test systemd-fsck[547]: /dev/sda1: 18 files, 22738/93500 clusters Jun 10 14:07:15 volumio-test systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-85D9\x2dF09E.service - File System Check on /dev/disk/by-uuid/85D9-F09E. Jun 10 14:07:15 volumio-test (udev-worker)[522]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:15 volumio-test (udev-worker)[523]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:15 volumio-test (udev-worker)[507]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:15 volumio-test kernel: cryptd: max_cpu_qlen set to 1000 Jun 10 14:07:15 volumio-test kernel: at24 0-0050: supply vcc not found, using dummy regulator Jun 10 14:07:16 volumio-test kernel: AES CTR mode by8 optimization enabled Jun 10 14:07:16 volumio-test systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 10 14:07:16 volumio-test kernel: at24 0-0050: 256 byte spd EEPROM, read-only Jun 10 14:07:16 volumio-test kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 10 14:07:16 volumio-test kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 10 14:07:16 volumio-test kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 10 14:07:16 volumio-test kernel: snd_hda_intel 0000:00:03.0: bound 0000:00:02.0 (ops __SCT__tp_func_intel_frontbuffer_flush [i915]) Jun 10 14:07:16 volumio-test kernel: input: HDA Intel HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:03.0/sound/card0/input3 Jun 10 14:07:16 volumio-test kernel: input: HDA Intel HDMI HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:03.0/sound/card0/input4 Jun 10 14:07:16 volumio-test kernel: input: HDA Intel HDMI HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:03.0/sound/card0/input5 Jun 10 14:07:16 volumio-test kernel: snd_hda_codec_realtek hdaudioC1D2: autoconfig for ALC3239: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker Jun 10 14:07:16 volumio-test kernel: snd_hda_codec_realtek hdaudioC1D2: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Jun 10 14:07:16 volumio-test kernel: snd_hda_codec_realtek hdaudioC1D2: hp_outs=1 (0x21/0x0/0x0/0x0/0x0) Jun 10 14:07:16 volumio-test kernel: snd_hda_codec_realtek hdaudioC1D2: mono: mono_out=0x0 Jun 10 14:07:16 volumio-test kernel: snd_hda_codec_realtek hdaudioC1D2: inputs: Jun 10 14:07:16 volumio-test kernel: snd_hda_codec_realtek hdaudioC1D2: Front Mic=0x19 Jun 10 14:07:16 volumio-test kernel: snd_hda_codec_realtek hdaudioC1D2: Rear Mic=0x1a Jun 10 14:07:16 volumio-test systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jun 10 14:07:16 volumio-test kernel: input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:1b.0/sound/card1/input6 Jun 10 14:07:16 volumio-test kernel: input: HDA Intel PCH Rear Mic as /devices/pci0000:00/0000:00:1b.0/sound/card1/input7 Jun 10 14:07:16 volumio-test kernel: input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card1/input8 Jun 10 14:07:16 volumio-test systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 10 14:07:16 volumio-test systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 10 14:07:16 volumio-test systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 10 14:07:16 volumio-test systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:16 volumio-test systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:16 volumio-test systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 10 14:07:16 volumio-test systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 10 14:07:16 volumio-test systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 10 14:07:16 volumio-test systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 10 14:07:16 volumio-test systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 10 14:07:16 volumio-test systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 10 14:07:16 volumio-test systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 10 14:07:16 volumio-test systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 10 14:07:16 volumio-test systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jun 10 14:07:16 volumio-test kernel: rtw_8822bu 1-2:1.0: Firmware version 30.20.0, H2C version 14 Jun 10 14:07:16 volumio-test kernel: usbcore: registered new interface driver snd-usb-audio Jun 10 14:07:16 volumio-test systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 10 14:07:16 volumio-test systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 10 14:07:16 volumio-test systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 10 14:07:16 volumio-test systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:16 volumio-test systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:16 volumio-test (udev-worker)[520]: event5: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:16 volumio-test systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jun 10 14:07:16 volumio-test systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:16 volumio-test systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 10 14:07:16 volumio-test (udev-worker)[519]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:16 volumio-test systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 10 14:07:16 volumio-test systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 10 14:07:16 volumio-test systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 10 14:07:16 volumio-test systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 10 14:07:16 volumio-test systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 10 14:07:16 volumio-test systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 10 14:07:16 volumio-test systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 10 14:07:16 volumio-test systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 10 14:07:16 volumio-test (udev-worker)[506]: event4: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:16 volumio-test (udev-worker)[507]: event8: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:16 volumio-test (udev-worker)[523]: event7: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:16 volumio-test (udev-worker)[522]: event6: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 10 14:07:16 volumio-test (udev-worker)[521]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jun 10 14:07:16 volumio-test kernel: usb 1-2: USB disconnect, device number 2 Jun 10 14:07:16 volumio-test kernel: rtw_8822bu 1-2:1.0: write register 0xc4 failed with -71 Jun 10 14:07:16 volumio-test kernel: usbcore: registered new interface driver rtw_8822bu Jun 10 14:07:16 volumio-test systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Jun 10 14:07:16 volumio-test systemd[1]: Mounting boot.mount - /boot... Jun 10 14:07:16 volumio-test systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Jun 10 14:07:16 volumio-test kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jun 10 14:07:16 volumio-test systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Jun 10 14:07:16 volumio-test systemd[1]: Mounted boot.mount - /boot. Jun 10 14:07:16 volumio-test systemd[1]: Reached target local-fs.target - Local File Systems. Jun 10 14:07:16 volumio-test systemd[1]: Started ifup@eth0.service - ifup for eth0. Jun 10 14:07:16 volumio-test systemd[1]: Starting networking.service - Raise network interfaces... Jun 10 14:07:16 volumio-test systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Jun 10 14:07:16 volumio-test systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Jun 10 14:07:16 volumio-test systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jun 10 14:07:16 volumio-test systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 10 14:07:16 volumio-test systemd[1]: Received SIGRTMIN+20 from PID 331 (plymouthd). Jun 10 14:07:16 volumio-test systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Jun 10 14:07:16 volumio-test systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 675 (systemd-binfmt) Jun 10 14:07:16 volumio-test systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Jun 10 14:07:16 volumio-test ifup[682]: command failed: No such device (-19) Jun 10 14:07:16 volumio-test sh[683]: command failed: No such device (-19) Jun 10 14:07:16 volumio-test systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 10 14:07:16 volumio-test systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Jun 10 14:07:16 volumio-test systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Jun 10 14:07:16 volumio-test systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Jun 10 14:07:16 volumio-test systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Jun 10 14:07:16 volumio-test systemd[1]: Starting rpcbind.service - RPC bind portmap service... Jun 10 14:07:16 volumio-test systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 10 14:07:16 volumio-test systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 10 14:07:16 volumio-test systemd[1]: Reached target sysinit.target - System Initialization. Jun 10 14:07:16 volumio-test systemd[1]: Started acpid.path - ACPI Events Check. Jun 10 14:07:16 volumio-test haveged[698]: haveged: command socket is listening at fd 3 Jun 10 14:07:16 volumio-test systemd[1]: Started apt-daily.timer - Daily apt download activities. Jun 10 14:07:16 volumio-test systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Jun 10 14:07:16 volumio-test systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Jun 10 14:07:16 volumio-test systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Jun 10 14:07:16 volumio-test systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Jun 10 14:07:16 volumio-test systemd[1]: Started man-db.timer - Daily man-db regeneration. Jun 10 14:07:16 volumio-test systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Jun 10 14:07:16 volumio-test systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Jun 10 14:07:16 volumio-test systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 10 14:07:16 volumio-test systemd[1]: Reached target paths.target - Path Units. Jun 10 14:07:16 volumio-test systemd[1]: Reached target timers.target - Timer Units. Jun 10 14:07:16 volumio-test systemd[1]: Listening on acpid.socket - ACPID Listen Socket. Jun 10 14:07:16 volumio-test systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Jun 10 14:07:16 volumio-test systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 10 14:07:16 volumio-test systemd[1]: Listening on triggerhappy.socket. Jun 10 14:07:16 volumio-test systemd[1]: Reached target sockets.target - Socket Units. Jun 10 14:07:16 volumio-test systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jun 10 14:07:16 volumio-test systemd[1]: Reached target basic.target - Basic System. Jun 10 14:07:16 volumio-test ifup[719]: command failed: No such device (-19) Jun 10 14:07:16 volumio-test systemd[1]: Started acpid.service - ACPI event daemon. Jun 10 14:07:16 volumio-test systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Jun 10 14:07:16 volumio-test acpid[721]: starting up with netlink and the input layer Jun 10 14:07:16 volumio-test kernel: RPC: Registered named UNIX socket transport module. Jun 10 14:07:16 volumio-test kernel: RPC: Registered udp transport module. Jun 10 14:07:16 volumio-test kernel: RPC: Registered tcp transport module. Jun 10 14:07:16 volumio-test kernel: RPC: Registered tcp-with-tls transport module. Jun 10 14:07:16 volumio-test kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jun 10 14:07:16 volumio-test systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Jun 10 14:07:16 volumio-test acpid[721]: 3 rules loaded Jun 10 14:07:16 volumio-test acpid[721]: waiting for events: event logging is off Jun 10 14:07:16 volumio-test systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 10 14:07:16 volumio-test systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 10 14:07:16 volumio-test systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Jun 10 14:07:16 volumio-test systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Jun 10 14:07:16 volumio-test systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Jun 10 14:07:16 volumio-test systemd[1]: Started iptables.service - Volumio Iptables Module. Jun 10 14:07:16 volumio-test avahi-daemon[731]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 10 14:07:16 volumio-test avahi-daemon[731]: Successfully dropped root privileges. Jun 10 14:07:16 volumio-test avahi-daemon[731]: avahi-daemon 0.8 starting up. Jun 10 14:07:16 volumio-test systemd[1]: Starting systemd-logind.service - User Login Management... Jun 10 14:07:16 volumio-test systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Jun 10 14:07:16 volumio-test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: Jun 10 14:07:16 volumio-test thd[740]: Unable to parse trigger line: Jun 10 14:07:16 volumio-test thd[740]: Found socket passed from systemd Jun 10 14:07:16 volumio-test avahi-daemon[731]: Successfully called chroot(). Jun 10 14:07:16 volumio-test avahi-daemon[731]: Successfully dropped remaining capabilities. Jun 10 14:07:16 volumio-test systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Jun 10 14:07:16 volumio-test avahi-daemon[731]: Loading service file /services/volumio.service. Jun 10 14:07:16 volumio-test systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Jun 10 14:07:16 volumio-test systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Jun 10 14:07:16 volumio-test systemd[1]: Starting wireless.service - Wireless Services... Jun 10 14:07:16 volumio-test systemd[1]: Started rpcbind.service - RPC bind portmap service. Jun 10 14:07:16 volumio-test systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 10 14:07:16 volumio-test volumio_rfkill_unblock.sh[748]: Volumio WiFi Soft Blocked check script Jun 10 14:07:16 volumio-test volumio_rfkill_unblock.sh[748]: Check if rfkill is available Jun 10 14:07:16 volumio-test systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Jun 10 14:07:16 volumio-test systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Jun 10 14:07:16 volumio-test volumio_rfkill_unblock.sh[748]: Check if rfkill listed devices are already unblocked Jun 10 14:07:16 volumio-test systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Jun 10 14:07:16 volumio-test systemd[1]: e2scrub_reap.service: Deactivated successfully. Jun 10 14:07:16 volumio-test systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Jun 10 14:07:16 volumio-test systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 10 14:07:16 volumio-test systemd[1]: Reached target rpc_pipefs.target. Jun 10 14:07:16 volumio-test systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Jun 10 14:07:16 volumio-test systemd[1]: Reached target sound.target - Sound Card. Jun 10 14:07:16 volumio-test volumio_rfkill_unblock.sh[748]: Wi-Fi is already unblocked. Jun 10 14:07:16 volumio-test systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jun 10 14:07:16 volumio-test systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jun 10 14:07:16 volumio-test systemd[1]: Reached target nfs-client.target - NFS client services. Jun 10 14:07:16 volumio-test systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 10 14:07:16 volumio-test systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 10 14:07:16 volumio-test systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Jun 10 14:07:16 volumio-test systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jun 10 14:07:16 volumio-test systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Jun 10 14:07:16 volumio-test systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Jun 10 14:07:16 volumio-test systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Jun 10 14:07:16 volumio-test systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jun 10 14:07:16 volumio-test volumio-remote-updater[747]: Volumio OTA Client: 1.7.0 Jun 10 14:07:16 volumio-test systemd-logind[738]: New seat seat0. Jun 10 14:07:16 volumio-test volumio-remote-updater[747]: Error: No active session Jun 10 14:07:16 volumio-test systemd-logind[738]: Watching system buttons on /dev/input/event1 (Power Button) Jun 10 14:07:16 volumio-test systemd-logind[738]: Watching system buttons on /dev/input/event0 (Power Button) Jun 10 14:07:16 volumio-test systemd[1]: Started systemd-logind.service - User Login Management. Jun 10 14:07:16 volumio-test kernel: usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd Jun 10 14:07:16 volumio-test kernel: usb 2-2: New USB device found, idVendor=2357, idProduct=0138, bcdDevice= 3.00 Jun 10 14:07:16 volumio-test kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 10 14:07:16 volumio-test kernel: usb 2-2: Product: 802.11ac NIC Jun 10 14:07:16 volumio-test kernel: usb 2-2: Manufacturer: Realtek Jun 10 14:07:16 volumio-test kernel: usb 2-2: SerialNumber: 123456 Jun 10 14:07:16 volumio-test haveged[698]: haveged: ver: 1.9.14; arch: x86; vend: GenuineIntel; build: (gcc 12.2.0 ITV); collect: 128K Jun 10 14:07:16 volumio-test haveged[698]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 32010/53875 Jun 10 14:07:16 volumio-test haveged[698]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9995 Jun 10 14:07:16 volumio-test haveged[698]: haveged: fills: 0, generated: 0 Jun 10 14:07:16 volumio-test kernel: rtw_8822bu 2-2:1.0: Firmware version 30.20.0, H2C version 14 Jun 10 14:07:16 volumio-test ifup[780]: command failed: No such device (-19) Jun 10 14:07:16 volumio-test avahi-daemon[731]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 10 14:07:16 volumio-test avahi-daemon[731]: New relevant interface lo.IPv4 for mDNS. Jun 10 14:07:16 volumio-test avahi-daemon[731]: Network interface enumeration completed. Jun 10 14:07:16 volumio-test avahi-daemon[731]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 10 14:07:16 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:16] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 10 14:07:16 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:16] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 10 14:07:16 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:16] [error] handle_connect error: Connection refused Jun 10 14:07:16 volumio-test dhcpcd[761]: dhcpcd-9.4.1 starting Jun 10 14:07:16 volumio-test upmpdcli[743]: Could not open config: /tmp/upmpdcli.conf Jun 10 14:07:16 volumio-test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 10 14:07:16 volumio-test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 10 14:07:16 volumio-test systemd[1]: Started wpa_supplicant.service - WPA supplicant. Jun 10 14:07:16 volumio-test wpa_supplicant[760]: Successfully initialized wpa_supplicant Jun 10 14:07:16 volumio-test sh[842]: eth0=eth0 Jun 10 14:07:16 volumio-test dhcpcd[824]: dev: loaded udev Jun 10 14:07:16 volumio-test dhcpcd[824]: DUID 00:04:b0:ce:a1:78:6f:98:11:e4:a9:81:40:e0:dc:69:13:00 Jun 10 14:07:17 volumio-test kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 10 14:07:17 volumio-test systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Jun 10 14:07:17 volumio-test loadcpufreq[759]: Loading cpufreq kernel modules...done (acpi-cpufreq). Jun 10 14:07:17 volumio-test systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Jun 10 14:07:17 volumio-test systemd[1]: iptables.service: Deactivated successfully. Jun 10 14:07:17 volumio-test systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Jun 10 14:07:17 volumio-test dhcpcd[824]: eth0: waiting for carrier Jun 10 14:07:17 volumio-test cpufrequtils[892]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Jun 10 14:07:17 volumio-test systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Jun 10 14:07:17 volumio-test systemd[1]: Finished networking.service - Raise network interfaces. Jun 10 14:07:17 volumio-test systemd[1]: Reached target network.target - Network. Jun 10 14:07:17 volumio-test systemd[1]: Reached target network-online.target - Network is Online. Jun 10 14:07:17 volumio-test systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Jun 10 14:07:17 volumio-test systemd[1]: Starting nmbd.service - Samba NMB Daemon... Jun 10 14:07:17 volumio-test systemd[1]: Starting ntpsec.service - Network Time Service... Jun 10 14:07:17 volumio-test systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Jun 10 14:07:17 volumio-test systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Jun 10 14:07:17 volumio-test systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Jun 10 14:07:17 volumio-test sm-notify[937]: Version 2.6.2 starting Jun 10 14:07:17 volumio-test systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jun 10 14:07:17 volumio-test systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 10 14:07:17 volumio-test dbus-daemon[732]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.4' (uid=0 pid=946 comm="timedatectl show --property=NTPSynchronized --valu") Jun 10 14:07:17 volumio-test systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Jun 10 14:07:17 volumio-test systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 10 14:07:17 volumio-test ntpd[940]: INIT: ntpd ntpsec-1.2.2: Starting Jun 10 14:07:17 volumio-test ntp-systemd-wrapper[940]: 2025-06-10T14:07:17 ntpd[940]: INIT: ntpd ntpsec-1.2.2: Starting Jun 10 14:07:17 volumio-test ntp-systemd-wrapper[940]: 2025-06-10T14:07:17 ntpd[940]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 10 14:07:17 volumio-test ntpd[940]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 10 14:07:17 volumio-test systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Jun 10 14:07:17 volumio-test systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 10 14:07:17 volumio-test systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Jun 10 14:07:17 volumio-test systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Jun 10 14:07:17 volumio-test ntpd[951]: INIT: precision = 0.061 usec (-24) Jun 10 14:07:17 volumio-test ntpd[951]: INIT: successfully locked into RAM Jun 10 14:07:17 volumio-test ntpd[951]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Jun 10 14:07:17 volumio-test ntpd[951]: CONFIG: restrict nopeer ignored Jun 10 14:07:17 volumio-test ntpd[951]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 10 14:07:17 volumio-test ntpd[951]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2025-12-28T00:00Z last=2017-01-01T00:00Z ofs=37 Jun 10 14:07:17 volumio-test ntpd[951]: INIT: Using SO_TIMESTAMPNS(ns) Jun 10 14:07:17 volumio-test ntpd[951]: IO: Listen and drop on 0 v6wildcard [::]:123 Jun 10 14:07:17 volumio-test ntpd[951]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 10 14:07:17 volumio-test ntpd[951]: IO: Listen normally on 2 lo 127.0.0.1:123 Jun 10 14:07:17 volumio-test ntpd[951]: IO: Listening on routing socket on fd #19 for interface updates Jun 10 14:07:17 volumio-test systemd[1]: Started ntpsec.service - Network Time Service. Jun 10 14:07:17 volumio-test systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 10 14:07:17 volumio-test ntpd[951]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jun 10 14:07:17 volumio-test ntpd[951]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Jun 10 14:07:17 volumio-test ntpd[951]: INIT: Running with OpenSSL 3.0.16 11 Feb 2025, 30000100 Jun 10 14:07:17 volumio-test ntpd[951]: NTSc: Using system default root certificates. Jun 10 14:07:17 volumio-test welcome[956]: Resolved ip:[0] Jun 10 14:07:17 volumio-test ntpd[951]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Jun 10 14:07:17 volumio-test systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Jun 10 14:07:17 volumio-test systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jun 10 14:07:17 volumio-test ifplugd(eth0)[982]: ifplugd 0.28 initializing. Jun 10 14:07:17 volumio-test ifplugd(eth0)[982]: Using interface eth0/00:23:24:82:F0:70 with driver (version: 6.12.30-volumio) Jun 10 14:07:17 volumio-test ifplugd(eth0)[982]: Using detection mode: SIOCETHTOOL Jun 10 14:07:17 volumio-test ifplugd(eth0)[982]: Initialization complete, link beat not detected. Jun 10 14:07:17 volumio-test systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 10 14:07:17 volumio-test systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 10 14:07:17 volumio-test systemd[1]: Received SIGRTMIN+21 from PID 331 (plymouthd). Jun 10 14:07:17 volumio-test sshd[985]: Server listening on 0.0.0.0 port 22. Jun 10 14:07:17 volumio-test systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Jun 10 14:07:17 volumio-test sshd[985]: Server listening on :: port 22. Jun 10 14:07:17 volumio-test systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Jun 10 14:07:17 volumio-test (udev-worker)[520]: Network interface NamePolicy= disabled on kernel command line. Jun 10 14:07:17 volumio-test systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Jun 10 14:07:17 volumio-test systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jun 10 14:07:17 volumio-test systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jun 10 14:07:17 volumio-test systemd[1]: Received SIGRTMIN+21 from PID 331 (plymouthd). Jun 10 14:07:17 volumio-test dbus-daemon[732]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 10 14:07:17 volumio-test bash[945]: Time is already synchronized. Jun 10 14:07:17 volumio-test ifplugd[931]: Network Interface Plugging Daemon...start eth0...done. Jun 10 14:07:17 volumio-test systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 10 14:07:17 volumio-test systemd[1]: Reached target getty.target - Login Prompts. Jun 10 14:07:17 volumio-test systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Jun 10 14:07:17 volumio-test systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 10 14:07:17 volumio-test systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 10 14:07:17 volumio-test systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 10 14:07:17 volumio-test systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Jun 10 14:07:17 volumio-test rfkill[1007]: unblock set for type wifi Jun 10 14:07:17 volumio-test systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - 802.11ac_NIC. Jun 10 14:07:17 volumio-test systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Jun 10 14:07:17 volumio-test systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Jun 10 14:07:17 volumio-test systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Jun 10 14:07:17 volumio-test avahi-daemon[731]: Server startup complete. Host name is volumio-test.local. Local service cookie is 4009077201. Jun 10 14:07:18 volumio-test dhcpcd[824]: wlan0: waiting for carrier Jun 10 14:07:18 volumio-test dhcpcd[824]: wlan0: carrier acquired Jun 10 14:07:18 volumio-test dhcpcd[824]: wlan0: IAID 5f:19:0a:74 Jun 10 14:07:18 volumio-test dhcpcd[824]: wlan0: adding address fe80::9aba:5fff:fe19:a74 Jun 10 14:07:18 volumio-test dhcpcd[824]: ipv6_addaddr1: Permission denied Jun 10 14:07:18 volumio-test sh[1021]: ifup: interface wlan0 already configured Jun 10 14:07:18 volumio-test sh[1036]: wlan0=wlan0 Jun 10 14:07:18 volumio-test dhcpcd[824]: wlan0: carrier lost Jun 10 14:07:18 volumio-test nmbd[1025]: [2025/06/10 14:07:18.200685, 0] ../../source3/nmbd/nmbd.c:901(main) Jun 10 14:07:18 volumio-test nmbd[1025]: nmbd version 4.17.12-Debian started. Jun 10 14:07:18 volumio-test nmbd[1025]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 10 14:07:18 volumio-test nmbd[1025]: [2025/06/10 14:07:18.204073, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 10 14:07:18 volumio-test nmbd[1025]: started asyncdns process 1042 Jun 10 14:07:18 volumio-test nmbd[1025]: [2025/06/10 14:07:18.204372, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jun 10 14:07:18 volumio-test nmbd[1025]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 10 14:07:18 volumio-test nmbd[1025]: [2025/06/10 14:07:18.204388, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jun 10 14:07:18 volumio-test nmbd[1025]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 10 14:07:18 volumio-test sudo[1034]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 10 14:07:18 volumio-test sudo[1034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 10 14:07:18 volumio-test sudo[1034]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:18 volumio-test sudo[1046]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 10 14:07:18 volumio-test sudo[1046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 10 14:07:18 volumio-test sudo[1046]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:18 volumio-test ntpd[951]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:18 volumio-test ntpd[951]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 10 14:07:18 volumio-test ntpd[951]: DNS: dns_check: DNS error: -11, System error Jun 10 14:07:18 volumio-test ntpd[951]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jun 10 14:07:18 volumio-test sudo[1055]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 10 14:07:18 volumio-test sudo[1055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:18 volumio-test kernel: e1000e 0000:00:19.0 eth0: NIC Link is Up 100 Mbps Full Duplex, Flow Control: Rx/Tx Jun 10 14:07:18 volumio-test avahi-daemon[731]: Service "Volumio-test" (/services/volumio.service) successfully established. Jun 10 14:07:18 volumio-test dhcpcd[824]: eth0: carrier acquired Jun 10 14:07:18 volumio-test ifplugd(eth0)[982]: Link beat detected. Jun 10 14:07:18 volumio-test sudo[1055]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:18 volumio-test dhcpcd[824]: eth0: IAID 24:82:f0:70 Jun 10 14:07:18 volumio-test dhcpcd[824]: eth0: adding address fe80::223:24ff:fe82:f070 Jun 10 14:07:18 volumio-test dhcpcd[824]: ipv6_addaddr1: Permission denied Jun 10 14:07:18 volumio-test dhcpcd[824]: eth0: probing address 172.16.0.202/24 Jun 10 14:07:18 volumio-test sudo[1058]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 10 14:07:18 volumio-test sudo[1058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:18 volumio-test sudo[1058]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:18 volumio-test sudo[1067]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 10 14:07:18 volumio-test sudo[1067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:18 volumio-test sudo[1067]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:18 volumio-test sudo[1069]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 10 14:07:18 volumio-test sudo[1069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:19 volumio-test ifplugd(eth0)[982]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 10 14:07:19 volumio-test ifplugd(eth0)[982]: client: ifup: interface eth0 already configured Jun 10 14:07:19 volumio-test ifplugd(eth0)[982]: Program executed successfully. Jun 10 14:07:19 volumio-test ntpd[951]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:19 volumio-test ntpd[951]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 10 14:07:19 volumio-test ntpd[951]: DNS: dns_check: DNS error: -11, System error Jun 10 14:07:19 volumio-test ntpd[951]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jun 10 14:07:19 volumio-test dhcpcd[824]: eth0: soliciting an IPv6 router Jun 10 14:07:20 volumio-test ntpd[951]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:20 volumio-test ntpd[951]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 10 14:07:20 volumio-test ntpd[951]: DNS: dns_check: DNS error: -11, System error Jun 10 14:07:20 volumio-test ntpd[951]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jun 10 14:07:21 volumio-test ntpd[951]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:21 volumio-test ntpd[951]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 10 14:07:21 volumio-test ntpd[951]: DNS: dns_check: DNS error: -11, System error Jun 10 14:07:21 volumio-test ntpd[951]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jun 10 14:07:21 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:21] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 10 14:07:21 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:21] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 10 14:07:21 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:21] [error] handle_connect error: Connection refused Jun 10 14:07:22 volumio-test systemd[1]: systemd-rfkill.service: Deactivated successfully. Jun 10 14:07:23 volumio-test dhcpcd[824]: eth0: using static address 172.16.0.202/24 Jun 10 14:07:23 volumio-test avahi-daemon[731]: Joining mDNS multicast group on interface eth0.IPv4 with address 172.16.0.202. Jun 10 14:07:23 volumio-test avahi-daemon[731]: New relevant interface eth0.IPv4 for mDNS. Jun 10 14:07:23 volumio-test avahi-daemon[731]: Registering new address record for 172.16.0.202 on eth0.IPv4. Jun 10 14:07:23 volumio-test dhcpcd[824]: eth0: adding route to 172.16.0.0/24 Jun 10 14:07:23 volumio-test dhcpcd[824]: eth0: adding default route via 172.16.0.1 Jun 10 14:07:23 volumio-test systemd[1]: welcome.service: Deactivated successfully. Jun 10 14:07:23 volumio-test systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 10 14:07:23 volumio-test systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 10 14:07:23 volumio-test systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 10 14:07:23 volumio-test welcome[1095]: Resolved ip:[1] 172.16.0.202 Jun 10 14:07:23 volumio-test systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 10 14:07:23 volumio-test systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 10 14:07:23 volumio-test systemd[1]: Started nmbd.service - Samba NMB Daemon. Jun 10 14:07:23 volumio-test systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jun 10 14:07:23 volumio-test sudo[1069]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:23 volumio-test sudo[1110]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 10 14:07:23 volumio-test sudo[1110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:23 volumio-test sudo[1110]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:23 volumio-test sudo[1112]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB Jun 10 14:07:23 volumio-test sudo[1112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:23 volumio-test sudo[1112]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:23 volumio-test sudo[1119]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 10 14:07:23 volumio-test sudo[1119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 10 14:07:23 volumio-test sudo[1119]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:23 volumio-test sudo[1121]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 10 14:07:23 volumio-test sudo[1121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 10 14:07:23 volumio-test sudo[1121]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:23 volumio-test systemd[1]: welcome.service: Deactivated successfully. Jun 10 14:07:23 volumio-test systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 10 14:07:23 volumio-test systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 10 14:07:23 volumio-test systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 10 14:07:24 volumio-test avahi-daemon[731]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 10 14:07:24 volumio-test avahi-daemon[731]: New relevant interface wlan0.IPv4 for mDNS. Jun 10 14:07:24 volumio-test welcome[1126]: Resolved ip:[2] 172.16.0.202 192.168.211.1 Jun 10 14:07:24 volumio-test avahi-daemon[731]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 10 14:07:24 volumio-test winbindd[1107]: [2025/06/10 14:07:24.156611, 0] ../../source3/winbindd/winbindd.c:1440(main) Jun 10 14:07:24 volumio-test winbindd[1107]: winbindd version 4.17.12-Debian started. Jun 10 14:07:24 volumio-test winbindd[1107]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 10 14:07:24 volumio-test winbindd[1107]: [2025/06/10 14:07:24.164236, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Jun 10 14:07:24 volumio-test winbindd[1107]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 10 14:07:24 volumio-test systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 10 14:07:24 volumio-test systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 10 14:07:24 volumio-test systemd[1]: Started winbind.service - Samba Winbind Daemon. Jun 10 14:07:24 volumio-test systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jun 10 14:07:24 volumio-test systemd[1]: Starting smbd.service - Samba SMB Daemon... Jun 10 14:07:24 volumio-test smbd[1149]: [2025/06/10 14:07:24.304673, 0] ../../source3/smbd/server.c:1741(main) Jun 10 14:07:24 volumio-test smbd[1149]: smbd version 4.17.12-Debian started. Jun 10 14:07:24 volumio-test smbd[1149]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 10 14:07:24 volumio-test systemd[1]: Started smbd.service - Samba SMB Daemon. Jun 10 14:07:24 volumio-test dhcpcd[824]: wlan0: carrier acquired Jun 10 14:07:24 volumio-test hostapd[1135]: wlan0: interface state UNINITIALIZED->ENABLED Jun 10 14:07:24 volumio-test hostapd[1135]: wlan0: AP-ENABLED Jun 10 14:07:24 volumio-test systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory Jun 10 14:07:24 volumio-test systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jun 10 14:07:24 volumio-test dhcpcd[824]: wlan0: IAID 5f:19:0a:74 Jun 10 14:07:24 volumio-test systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jun 10 14:07:24 volumio-test iw[1162]: command failed: Operation not supported (-95) Jun 10 14:07:24 volumio-test iwconfig[1163]: Error for wireless request "Set Power Management" (8B2C) : Jun 10 14:07:24 volumio-test iwconfig[1163]: SET failed on device wlan0 ; Invalid argument. Jun 10 14:07:24 volumio-test systemd[1]: wireless.service: Deactivated successfully. Jun 10 14:07:24 volumio-test systemd[1]: Started wireless.service - Wireless Services. Jun 10 14:07:24 volumio-test systemd[1]: Started volumio.service - Volumio Backend Module. Jun 10 14:07:24 volumio-test kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jun 10 14:07:24 volumio-test systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Jun 10 14:07:24 volumio-test systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Jun 10 14:07:24 volumio-test systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Jun 10 14:07:24 volumio-test systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jun 10 14:07:24 volumio-test systemd[1]: Reached target multi-user.target - Multi-User System. Jun 10 14:07:24 volumio-test systemd[1]: Reached target graphical.target - Graphical Interface. Jun 10 14:07:24 volumio-test systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jun 10 14:07:25 volumio-test systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jun 10 14:07:25 volumio-test systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jun 10 14:07:25 volumio-test dnsmasq[1205]: started, version 2.90 cachesize 150 Jun 10 14:07:25 volumio-test dnsmasq[1205]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Jun 10 14:07:25 volumio-test dnsmasq-dhcp[1205]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jun 10 14:07:25 volumio-test dnsmasq[1205]: reading /etc/resolv.conf Jun 10 14:07:25 volumio-test dnsmasq[1205]: using nameserver 208.67.222.222#53 Jun 10 14:07:25 volumio-test dnsmasq[1205]: using nameserver 208.67.220.220#53 Jun 10 14:07:25 volumio-test dnsmasq[1205]: using nameserver 172.16.0.1#53 Jun 10 14:07:25 volumio-test dnsmasq[1205]: using nameserver 208.67.222.222#53 Jun 10 14:07:25 volumio-test dnsmasq[1205]: read /etc/hosts - 4 names Jun 10 14:07:25 volumio-test volumio-cpu-tweak[1180]: Setting RT Priority for mpd Jun 10 14:07:25 volumio-test volumio-cpu-tweak[1210]: pid 35's current scheduling policy: SCHED_OTHER Jun 10 14:07:25 volumio-test volumio-cpu-tweak[1210]: pid 35's current scheduling priority: 0 Jun 10 14:07:25 volumio-test volumio-cpu-tweak[1180]: Not enough cores to set MPD affinity Jun 10 14:07:25 volumio-test volumio-cpu-tweak[1180]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 10 14:07:25 volumio-test systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jun 10 14:07:25 volumio-test systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 10 14:07:25 volumio-test systemd[1]: Startup finished in 5.963s (kernel) + 9.807s (userspace) = 15.770s. Jun 10 14:07:25 volumio-test systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jun 10 14:07:25 volumio-test startx[1241]: X.Org X Server 1.21.1.7 Jun 10 14:07:25 volumio-test startx[1241]: X Protocol Version 11, Revision 0 Jun 10 14:07:25 volumio-test startx[1241]: Current Operating System: Linux volumio-test 6.12.30-volumio #2 SMP PREEMPT_DYNAMIC Wed May 28 19:37:30 BST 2025 x86_64 Jun 10 14:07:25 volumio-test startx[1241]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=291d6001-c14a-4478-94b7-c7c05a8f0b84 bootpart=UUID=85D9-F09E datapart=UUID=830a8ade-a16c-4bd6-86ac-93943f6c29fc hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Jun 10 14:07:25 volumio-test startx[1241]: xorg-server 2:21.1.7-3+deb12u9 (https://www.debian.org/support) Jun 10 14:07:25 volumio-test startx[1241]: Current version of pixman: 0.42.2 Jun 10 14:07:25 volumio-test startx[1241]: Before reporting problems, check http://wiki.x.org Jun 10 14:07:25 volumio-test startx[1241]: to make sure that you have the latest version. Jun 10 14:07:25 volumio-test startx[1241]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 10 14:07:25 volumio-test startx[1241]: (++) from command line, (!!) notice, (II) informational, Jun 10 14:07:25 volumio-test startx[1241]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 10 14:07:25 volumio-test startx[1241]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jun 10 14:07:25 2025 Jun 10 14:07:25 volumio-test startx[1241]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 10 14:07:25 volumio-test soundcard-init.sh[1271]: Simple mixer control 'Master',0 Jun 10 14:07:25 volumio-test soundcard-init.sh[1271]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Jun 10 14:07:25 volumio-test soundcard-init.sh[1271]: Playback channels: Mono Jun 10 14:07:25 volumio-test soundcard-init.sh[1271]: Limits: Playback 0 - 87 Jun 10 14:07:25 volumio-test soundcard-init.sh[1271]: Mono: Playback 65 [75%] [-16.50dB] [on] Jun 10 14:07:25 volumio-test dhcpcd[824]: wlan0: soliciting an IPv6 router Jun 10 14:07:25 volumio-test systemd[1]: soundcard-init.service: Deactivated successfully. Jun 10 14:07:25 volumio-test volumio[1164]: info: ------------------------------------------- Jun 10 14:07:25 volumio-test volumio[1164]: info: ----- Volumio3 ---- Jun 10 14:07:25 volumio-test volumio[1164]: info: ------------------------------------------- Jun 10 14:07:25 volumio-test volumio[1164]: info: ----- System startup ---- Jun 10 14:07:25 volumio-test volumio[1164]: info: ------------------------------------------- Jun 10 14:07:26 volumio-test volumio[1164]: info: MYVOLUMIO Environment detected Jun 10 14:07:26 volumio-test volumio[1164]: info: Plugin folders cleanup Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning into folder /volumio/app/plugins/ Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category audio_interface Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category miscellanea Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category music_service Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category plugins.json Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category system_controller Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category user_interface Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning into folder /data/plugins/ Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category music_service Jun 10 14:07:26 volumio-test volumio[1164]: info: Scanning category user_interface Jun 10 14:07:26 volumio-test volumio[1164]: info: Plugin folders cleanup completed Jun 10 14:07:26 volumio-test volumio[1164]: info: ------------------------------------------- Jun 10 14:07:26 volumio-test volumio[1164]: info: ----- Core plugins startup ---- Jun 10 14:07:26 volumio-test volumio[1164]: info: ------------------------------------------- Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 14:07:26 volumio-test volumio[1164]: info: Adding plugin upnp to MyMusic Plugins Jun 10 14:07:26 volumio-test volumio[1164]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 14:07:26 volumio-test volumio[1164]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugins from folder /data/plugins/ Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "system"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "appearance"... Jun 10 14:07:26 volumio-test ntpd[951]: IO: Listen normally on 3 eth0 172.16.0.202:123 Jun 10 14:07:26 volumio-test ntpd[951]: IO: Listen normally on 4 wlan0 192.168.211.1:123 Jun 10 14:07:26 volumio-test ntpd[951]: IO: new interface(s) found: waking up resolver Jun 10 14:07:26 volumio-test ntpd[951]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:26 volumio-test ntpd[951]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 10 14:07:26 volumio-test ntpd[951]: DNS: Pool taking: 77.68.81.77 Jun 10 14:07:26 volumio-test ntpd[951]: DNS: Pool taking: 131.111.8.63 Jun 10 14:07:26 volumio-test ntpd[951]: DNS: Pool taking: 176.58.115.34 Jun 10 14:07:26 volumio-test ntpd[951]: DNS: Pool taking: 213.5.132.231 Jun 10 14:07:26 volumio-test ntpd[951]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "network"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Refreshing Cached IP Addresses Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "services"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "alsa_controller"... Jun 10 14:07:26 volumio-test sudo[1300]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 14:07:26 volumio-test sudo[1300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:26 volumio-test dhcpcd[824]: wlan0: soliciting a DHCP lease Jun 10 14:07:26 volumio-test sudo[1313]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 14:07:26 volumio-test sudo[1313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:26 volumio-test sudo[1302]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 14:07:26 volumio-test sudo[1302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:26 volumio-test sudo[1313]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:26 volumio-test sudo[1300]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:26 volumio-test sudo[1302]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:26 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "wizard"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "networkfs"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Starting Udev Watcher for removable devices Jun 10 14:07:26 volumio-test sudo[1335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Mercury1809!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.12/AUDIO /mnt/NAS/SERVER Jun 10 14:07:26 volumio-test sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:26 volumio-test volumio[1164]: info: Ignoring mount for partition: boot Jun 10 14:07:26 volumio-test volumio[1164]: info: Ignoring mount for partition: volumio Jun 10 14:07:26 volumio-test volumio[1164]: info: Ignoring mount for partition: volumio_data Jun 10 14:07:26 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "volumio_command_line_client"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Plugin upnp is not enabled Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "my_music"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "mpd"... Jun 10 14:07:26 volumio-test kernel: netfs: FS-Cache loaded Jun 10 14:07:26 volumio-test kernel: Key type dns_resolver registered Jun 10 14:07:26 volumio-test volumio[1164]: info: Plugin upnp_browser is not enabled Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "alarm-clock"... Jun 10 14:07:26 volumio-test kernel: Key type cifs.spnego registered Jun 10 14:07:26 volumio-test kernel: Key type cifs.idmap registered Jun 10 14:07:26 volumio-test kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jun 10 14:07:26 volumio-test kernel: CIFS: Attempting to mount //192.168.0.12/AUDIO Jun 10 14:07:26 volumio-test volumio[1164]: info: Plugin airplay_emulation is not enabled Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "last_100"... Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "webradio"... Jun 10 14:07:26 volumio-test sudo[1335]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "i2s_dacs"... Jun 10 14:07:26 volumio-test volumio[1164]: info: I2S DAC not set, start Auto-detection Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "volumiodiscovery"... Jun 10 14:07:26 volumio-test volumio[1164]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 14:07:26 volumio-test volumio[1164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 14:07:26 volumio-test volumio[1164]: *** WARNING *** For more information see Jun 10 14:07:26 volumio-test volumio[1164]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 14:07:26 volumio-test volumio[1164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 14:07:26 volumio-test volumio[1164]: *** WARNING *** For more information see Jun 10 14:07:26 volumio-test node[1164]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 14:07:26 volumio-test node[1164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 14:07:26 volumio-test node[1164]: *** WARNING *** For more information see Jun 10 14:07:26 volumio-test node[1164]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 14:07:26 volumio-test node[1164]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 14:07:26 volumio-test node[1164]: *** WARNING *** For more information see Jun 10 14:07:26 volumio-test volumio[1164]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 14:07:26 volumio-test volumio[1164]: info: Discovery: Started advertising with name: Volumio-test Jun 10 14:07:26 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 14:07:26 volumio-test volumio[1164]: info: Loading plugin "spop"... Jun 10 14:07:27 volumio-test ntpd[951]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 162.159.200.123 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 195.171.43.10 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 213.210.39.123 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 81.2.125.2 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 2001:8b0:1827:ef43::14:1 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 2a02:8010:d015::123 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 2a05:b400:c::123:61 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: Pool taking: 2001:8b0:1827:ef43::13:1 Jun 10 14:07:27 volumio-test ntpd[951]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading plugin "outputs"... Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading plugin "albumart"... Jun 10 14:07:27 volumio-test volumio[1164]: info: Plugin example_plugin is not enabled Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading plugin "inputs"... Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading plugin "updater_comm"... Jun 10 14:07:27 volumio-test volumio[1446]: Forking 1 albumart workers Jun 10 14:07:27 volumio-test volumio[1164]: info: Plugin mpdemulation is not enabled Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading plugin "rest_api"... Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading plugin "websocket"... Jun 10 14:07:27 volumio-test volumio[1164]: info: Starting Socket.io Server version 1.7.4 Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading plugin "Systeminfo"... Jun 10 14:07:27 volumio-test volumio[1456]: Starting albumart workers Jun 10 14:07:27 volumio-test volumio[1164]: info: Loading i18n strings for locale en Jun 10 14:07:27 volumio-test volumio[1164]: Updating browse sources language Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::initPlayerControls Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: Express server listening on port 3000 Jun 10 14:07:27 volumio-test volumio[1164]: [Metrics] WebUI: 2s 275.27ms Jun 10 14:07:27 volumio-test volumio[1164]: info: Setting Device type: x86 Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreStateMachine::resetVolumioState Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreStateMachine::getcurrentVolume Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 14:07:27 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 14:07:27 volumio-test volumio[1164]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 10 14:07:27 volumio-test volumio[1164]: info: Completed loading Core Plugins Jun 10 14:07:27 volumio-test volumio[1164]: info: Preparing to generate the ALSA configuration file Jun 10 14:07:27 volumio-test volumio[1164]: info: Cannot read play queue from file Jun 10 14:07:27 volumio-test volumio[1164]: info: Volumio Network Manager: Network status updated: 1 Jun 10 14:07:27 volumio-test volumio[1164]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 10 14:07:27 volumio-test volumio[1164]: wlan0 Interface doesn't support scanning : Operation not supported Jun 10 14:07:27 volumio-test volumio[1164]: info: Cannot use regular scanning, forcing with ap-force Jun 10 14:07:27 volumio-test sudo[1495]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 10 14:07:27 volumio-test sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:27 volumio-test sudo[1495]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:27 volumio-test volumio[1164]: command failed: Operation not supported (-95) Jun 10 14:07:27 volumio-test volumio[1164]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 10 14:07:27 volumio-test volumio[1164]: command failed: Operation not supported (-95) Jun 10 14:07:27 volumio-test volumio[1164]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 10 14:07:27 volumio-test volumio[1164]: info: Asound.conf file unchanged, so no further update is needed Jun 10 14:07:27 volumio-test volumio[1164]: info: Output device has changed, restarting MPD Jun 10 14:07:27 volumio-test volumio[1164]: info: ___________ START PLUGINS ___________ Jun 10 14:07:28 volumio-test sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 14:07:28 volumio-test sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:28 volumio-test volumio[1164]: info: ControllerMpd::onStart: Initializing MPD Jun 10 14:07:28 volumio-test volumio[1164]: info: Creating MPD Configuration file Jun 10 14:07:28 volumio-test sudo[1507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 14:07:28 volumio-test sudo[1507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:28 volumio-test sudo[1505]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 14:07:28 volumio-test sudo[1514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 14:07:28 volumio-test volumio[1164]: info: [1749560848037] CoreMusicLibrary::Adding element Last_100 Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 14:07:28 volumio-test sudo[1514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 14:07:28 volumio-test volumio[1164]: info: [1749560848039] CoreMusicLibrary::Adding element Webradio Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 14:07:28 volumio-test volumio[1164]: info: Initializing BBC Radios Jun 10 14:07:28 volumio-test sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 14:07:28 volumio-test sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:28 volumio-test sudo[1511]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:28 volumio-test systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 10 14:07:28 volumio-test systemd[1]: Starting mpd.service - Music Player Daemon... Jun 10 14:07:28 volumio-test systemd[1]: mpd.service: Deactivated successfully. Jun 10 14:07:28 volumio-test systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 10 14:07:28 volumio-test systemd[1]: mpd.socket: Deactivated successfully. Jun 10 14:07:28 volumio-test systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 10 14:07:28 volumio-test systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 10 14:07:28 volumio-test systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 10 14:07:28 volumio-test systemd[1]: Starting mpd.service - Music Player Daemon... Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: Creating Spotify config file Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test sudo[1530]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 14:07:28 volumio-test sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 10 14:07:28 volumio-test sudo[1534]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 10 14:07:28 volumio-test sudo[1530]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:28 volumio-test volumio[1164]: info: Volumio Calling Home Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreStateMachine::setRepeat null single undefined Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreStateMachine::pushState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioPushState Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreStateMachine::setRandom null Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreStateMachine::pushState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioPushState Jun 10 14:07:28 volumio-test volumio[1164]: info: VolumeController:: Volume=100 Mute =false Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreStateMachine::pushState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioPushState Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreStateMachine::updateTrackBlock Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrackBlock Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 14:07:28 volumio-test ntpd[951]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:28 volumio-test volumio[1164]: info: MPD Permissions set Jun 10 14:07:28 volumio-test volumio[1164]: info: MPD Permissions set Jun 10 14:07:28 volumio-test volumio[1164]: info: Spotify config file written Jun 10 14:07:28 volumio-test sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 10 14:07:28 volumio-test sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:28 volumio-test systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 10 14:07:28 volumio-test systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 10 14:07:28 volumio-test volumio[1164]: info: Discovery: adding 539b4ab7-0894-47c7-bad6-b00efcd39636 Jun 10 14:07:28 volumio-test volumio[1164]: info: Discovery: Found device Volumio-test Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test volumio[1164]: info: Discovery: this is already registered, 539b4ab7-0894-47c7-bad6-b00efcd39636 Jun 10 14:07:28 volumio-test volumio[1164]: info: Discovery: Found device Volumio-test Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test volumio[1164]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 10 14:07:28 volumio-test volumio[1164]: info: Volumio called home Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 10 14:07:28 volumio-test go-librespot[1549]: go-librespot daemon starting... Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 14:07:28 volumio-test ntpd[951]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 10 14:07:28 volumio-test ntpd[951]: DNS: Pool taking: 162.159.200.1 Jun 10 14:07:28 volumio-test ntpd[951]: DNS: Pool taking: 178.62.68.79 Jun 10 14:07:28 volumio-test ntpd[951]: DNS: Pool taking: 193.57.144.50 Jun 10 14:07:28 volumio-test ntpd[951]: DNS: Pool taking: 139.143.5.30 Jun 10 14:07:28 volumio-test ntpd[951]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 10 14:07:28 volumio-test sudo[1547]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 14:07:28 volumio-test volumio[1164]: info: No need to fix Spotify hosts Jun 10 14:07:28 volumio-test volumio[1164]: info: Discovery: this is already registered, 539b4ab7-0894-47c7-bad6-b00efcd39636 Jun 10 14:07:28 volumio-test volumio[1164]: info: Discovery: Found device Volumio-test Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=info msg="running go-librespot 0.2.0" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="app state loaded" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 10 14:07:28 volumio-test volumio[1164]: info: VolumeController:: Volume=100 Mute =false Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreStateMachine::pushState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioPushState Jun 10 14:07:28 volumio-test volumio[1164]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 10 14:07:28 volumio-test volumio[1164]: SPOTIFY: BQB1SN1C9bgWrFFNtdVgbsS1YsmmRPUD4OqJf6sb6Kjl2q9OU8g-q6829KQgD5ak0w1oLyrGjXCDaLoeIrmqqXBxmroa9ri2oGJ9NgpK3WWN8bGkLoLKF1wyOAjqN6Ps14D-5JNK9G_xX5NgEuejPKANqpPcXtsEX1Yk1xc3rGLHnioiTT35EVOUs2An04eBzQBmFkMiMvea2oQc_MUHR1hXTZfLahfhjUCmSqTEUdknkq1l1JI3asn9uTzzT6EtkHbNUdu1V4bDfhVYfAFMaAS3-lM Jun 10 14:07:28 volumio-test volumio[1164]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 10 14:07:28 volumio-test volumio[1164]: info: New Spotify access token = BQB1SN1C9bgWrFFNtdVgbsS1YsmmRPUD4OqJf6sb6Kjl2q9OU8g-q6829KQgD5ak0w1oLyrGjXCDaLoeIrmqqXBxmroa9ri2oGJ9NgpK3WWN8bGkLoLKF1wyOAjqN6Ps14D-5JNK9G_xX5NgEuejPKANqpPcXtsEX1Yk1xc3rGLHnioiTT35EVOUs2An04eBzQBmFkMiMvea2oQc_MUHR1hXTZfLahfhjUCmSqTEUdknkq1l1JI3asn9uTzzT6EtkHbNUdu1V4bDfhVYfAFMaAS3-lM Jun 10 14:07:28 volumio-test volumio[1164]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:28 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:28 volumio-test volumio[1164]: SPOTIFY: User informations: {"country":"GB","display_name":"Alastair Jones","email":"alastair.lovedayjones@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1118784979"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/1118784979","id":"1118784979","images":[{"height":300,"url":"https://scontent-bru2-1.xx.fbcdn.net/v/t39.30808-1/468731162_10162486182770452_6140593943874048250_n.jpg?_nc_cat=106&ccb=1-7&_nc_sid=79bf43&_nc_ohc=XC9biMsCz44Q7kNvwF_l8CC&_nc_oc=AdkVws9OcfNckqjTUtIr2m1vsllilEf0cTxn_LK9PsxULKKeDWyEnEWbdHBMdCIHFeubXqUvas57dYGHAyghotW_&_nc_zt=24&_nc_ht=scontent-bru2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=iLrP0tUJV0Rf_vPixpCbqA&oh=00_AfMIr1cpgCJVgEyd2PbwLGYbuOQxUF6GgsqKulOlrLA-9g&oe=684DD403","width":300},{"height":64,"url":"https://scontent-bru2-1.xx.fbcdn.net/v/t39.30808-1/468731162_10162486182770452_6140593943874048250_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=28885b&_nc_ohc=XC9biMsCz44Q7kNvwF_l8CC&_nc_oc=AdkVws9OcfNckqjTUtIr2m1vsllilEf0cTxn_LK9PsxULKKeDWyEnEWbdHBMdCIHFeubXqUvas57dYGHAyghotW_&_nc_zt=24&_nc_ht=scontent-bru2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=iLrP0tUJV0Rf_vPixpCbqA&oh=00_AfPy-QeYG8nHEaCMAe8fYKSJBcu1Qoz_EiazCfHFdB-UtQ&oe=684DD403","width":64}],"product":"premium","type":"user","uri":"spotify:user:1118784979"} Jun 10 14:07:28 volumio-test volumio[1164]: info: Spotify Successfully logged in Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 14:07:28 volumio-test volumio[1164]: info: [1749560848549] CoreMusicLibrary::Adding element Spotify Jun 10 14:07:28 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 14:07:28 volumio-test volumio[1164]: Cannot find translation for source Spotify Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=info msg="zeroconf server listening on port 44691" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="obtained new client token: AABVUIZdCHPPcDD8XMnBoQ1JaaCU/N2K188XhIR5d2xaxgbZuLdgnJyIUJD5DvJ2wrqpMYLbhhTY/naYli0Y4t2ruJzgd8XInVM/nSIFqNbcZ6NXV3TMdSHkqpSdS3beHk82XJ8/nJVMsDC3p6QffIyhfWcfCDQdSivBCeOLudCLKdTcy0zX+r2oAO45u1rYLTTwNE91UN8vUJpuP0jQkbLGBx54JdLdBjnHWoN89H/6B4QktLfm9na2IrlY" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="completed keyexchange" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="completed challenge" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=info msg="authenticated AP as 1118784979" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=info msg="authenticated Login5 as 1118784979" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="initializing zeroconf session, username: 1118784979" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="dealer connection opened" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=trace msg="starting accesspoint recv loop" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=trace msg="starting dealer recv loop" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=trace msg="received accesspoint ping" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="received connection id: ZGEwYmFlYWUtMGE0Yy00MWNmLWJiZTItMjNiYTZiNThiYjE2K2RlYWxlcit0Y3A6Ly8wYWNhNDIwMy5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMzJGNTlGQjJFQ0E5OEU1NzZDREEwRTU0RThFNkEwM0YwQzYyQjVBQjk3MDVCREVFMDRFODM3MjU0QUFCMkNFNA==" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=trace msg="received accesspoint pong ack" Jun 10 14:07:28 volumio-test go-librespot[1555]: time="2025-06-10T14:07:28+01:00" level=debug msg="put connect state because NEW_DEVICE" Jun 10 14:07:29 volumio-test ntpd[951]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 10 14:07:29 volumio-test ntpd[951]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 10 14:07:29 volumio-test ntpd[951]: DNS: Pool taking: 85.199.214.99 Jun 10 14:07:29 volumio-test ntpd[951]: DNS: Pool taking: 88.80.189.24 Jun 10 14:07:29 volumio-test ntpd[951]: DNS: Pool taking: 91.109.118.94 Jun 10 14:07:29 volumio-test ntpd[951]: DNS: Pool taking: 178.62.250.107 Jun 10 14:07:29 volumio-test ntpd[951]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 10 14:07:29 volumio-test mpd[1535]: 2025-06-10T14:07:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 14:07:29 volumio-test systemd[1]: Started mpd.service - Music Player Daemon. Jun 10 14:07:29 volumio-test sudo[1514]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:29 volumio-test sudo[1507]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:29 volumio-test volumio[1164]: info: Completed starting Core Plugins Jun 10 14:07:29 volumio-test volumio[1164]: info: ------------------------------------------- Jun 10 14:07:29 volumio-test volumio[1164]: info: ----- MyVolumio plugins startup ---- Jun 10 14:07:29 volumio-test volumio[1164]: info: ------------------------------------------- Jun 10 14:07:29 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 10 14:07:29 volumio-test volumio[1164]: error: MPD error: The expression evaluated to a falsy value: Jun 10 14:07:29 volumio-test volumio[1164]: assert.ok(self.idling) Jun 10 14:07:29 volumio-test volumio[1164]: error: The expression evaluated to a falsy value: Jun 10 14:07:29 volumio-test volumio[1164]: assert.ok(self.idling) Jun 10 14:07:29 volumio-test volumio[1164]: info: MPD running with PID1535 Jun 10 14:07:29 volumio-test volumio[1164]: ,establishing connection Jun 10 14:07:29 volumio-test volumio[1164]: error: updateQueue error: null Jun 10 14:07:29 volumio-test volumio[1164]: error: updateQueue error: null Jun 10 14:07:29 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:29] [connect] Successful connection Jun 10 14:07:29 volumio-test volumio[1164]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Jun 10 14:07:29 volumio-test volumio-remote-updater[747]: [2025-06-10 14:07:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1749560849 101 Jun 10 14:07:29 volumio-test dbus-daemon[732]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.16' (uid=0 pid=1429 comm="/usr/lib/chromium/chromium --show-component-extens") Jun 10 14:07:29 volumio-test systemd[1]: Starting bluetooth.service - Bluetooth service... Jun 10 14:07:29 volumio-test bluetoothd[1598]: Bluetooth daemon 5.72 Jun 10 14:07:29 volumio-test kernel: Bluetooth: Core ver 2.22 Jun 10 14:07:29 volumio-test kernel: NET: Registered PF_BLUETOOTH protocol family Jun 10 14:07:29 volumio-test kernel: Bluetooth: HCI device and connection manager initialized Jun 10 14:07:29 volumio-test kernel: Bluetooth: HCI socket layer initialized Jun 10 14:07:29 volumio-test kernel: Bluetooth: L2CAP socket layer initialized Jun 10 14:07:29 volumio-test kernel: Bluetooth: SCO socket layer initialized Jun 10 14:07:29 volumio-test dbus-daemon[732]: [system] Successfully activated service 'org.bluez' Jun 10 14:07:29 volumio-test systemd[1]: Started bluetooth.service - Bluetooth service. Jun 10 14:07:29 volumio-test bluetoothd[1598]: Starting SDP server Jun 10 14:07:29 volumio-test bluetoothd[1598]: Excluding (cli) wiimote Jun 10 14:07:29 volumio-test bluetoothd[1598]: Excluding (cli) sap Jun 10 14:07:29 volumio-test bluetoothd[1598]: Excluding (cli) midi Jun 10 14:07:29 volumio-test bluetoothd[1598]: Excluding (cli) battery Jun 10 14:07:29 volumio-test bluetoothd[1598]: Bluetooth management interface 1.23 initialized Jun 10 14:07:29 volumio-test dbus-daemon[732]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.17' (uid=0 pid=1598 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Jun 10 14:07:29 volumio-test kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 10 14:07:29 volumio-test kernel: Bluetooth: BNEP filters: protocol multicast Jun 10 14:07:29 volumio-test kernel: Bluetooth: BNEP socket layer initialized Jun 10 14:07:29 volumio-test systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jun 10 14:07:29 volumio-test dbus-daemon[732]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 10 14:07:29 volumio-test systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jun 10 14:07:30 volumio-test volumio[1164]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 10 14:07:31 volumio-test volumio[1164]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 14:07:31 volumio-test volumio[1164]: info: go-librespot daemon successfully initialized Jun 10 14:07:31 volumio-test dhcpcd[824]: wlan0: probing for an IPv4LL address Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:31 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 10 14:07:31 volumio-test volumio[1164]: info: Received Get System Info Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 14:07:31 volumio-test volumio[1164]: info: Discovery: Getting this device information Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:31 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:31 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:31 volumio-test volumio[1164]: info: Listing playlists Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 10 14:07:31 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 14:07:32 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:32 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:32 volumio-test ntpd[951]: CLOCK: time stepped by -0.197803 Jun 10 14:07:32 volumio-test ntpd[951]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jun 10 14:07:32 volumio-test systemd-journald[470]: Time jumped backwards, rotating. Jun 10 14:07:32 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 14:07:32 volumio-test volumio[1164]: info: Received Get System Info Jun 10 14:07:32 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 14:07:32 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 14:07:32 volumio-test volumio[1164]: info: Discovery: Getting this device information Jun 10 14:07:32 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:32 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:32 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 14:07:32 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 10 14:07:33 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 10 14:07:33 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 14:07:33 volumio-test volumio[1164]: info: Received Get System Info Jun 10 14:07:33 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 14:07:33 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 14:07:33 volumio-test volumio[1164]: info: Discovery: Getting this device information Jun 10 14:07:33 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:33 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:33 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 14:07:34 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 10 14:07:34 volumio-test volumio[1164]: info: Initializing connection to go-librespot Websocket Jun 10 14:07:34 volumio-test go-librespot[1555]: time="2025-06-10T14:07:34+01:00" level=debug msg="new websocket client" Jun 10 14:07:34 volumio-test volumio[1164]: info: Connection to go-librespot Websocket established Jun 10 14:07:34 volumio-test volumio[1164]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 10 14:07:34 volumio-test volumio[1164]: verbose: New Socket.io Connection to 172.16.0.202 from 192.168.0.249 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 10 14:07:34 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:34 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:35 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetQueue Jun 10 14:07:35 volumio-test volumio[1164]: info: CoreStateMachine::getQueue Jun 10 14:07:35 volumio-test volumio[1164]: info: CorePlayQueue::getQueue Jun 10 14:07:35 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 10 14:07:35 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jun 10 14:07:35 volumio-test volumio[1164]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jun 10 14:07:35 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jun 10 14:07:35 volumio-test volumio[1164]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jun 10 14:07:35 volumio-test volumio[1164]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jun 10 14:07:35 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jun 10 14:07:36 volumio-test dhcpcd[824]: wlan0: using IPv4LL address 169.254.175.119 Jun 10 14:07:36 volumio-test avahi-daemon[731]: Registering new address record for 169.254.175.119 on wlan0.IPv4. Jun 10 14:07:36 volumio-test dhcpcd[824]: wlan0: adding route to 169.254.0.0/16 Jun 10 14:07:36 volumio-test systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 10 14:07:36 volumio-test systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 10 14:07:36 volumio-test systemd[1]: welcome.service: Deactivated successfully. Jun 10 14:07:36 volumio-test systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 10 14:07:36 volumio-test systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 10 14:07:36 volumio-test systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 10 14:07:36 volumio-test welcome[1765]: Resolved ip:[3] 172.16.0.202 192.168.211.1 169.254.175.119 Jun 10 14:07:36 volumio-test systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 10 14:07:36 volumio-test systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 10 14:07:37 volumio-test volumio[1164]: info: Getting Spotify volume Jun 10 14:07:37 volumio-test volumio[1164]: info: Spotify volume: 100 Jun 10 14:07:37 volumio-test volumio[1164]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 10 14:07:37 volumio-test volumio[1164]: info: CoreCommandRouter::volumioGetState Jun 10 14:07:37 volumio-test volumio[1164]: info: CorePlayQueue::getTrack 0 Jun 10 14:07:37 volumio-test volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 10 14:07:37 volumio-test volumio[1164]: info: Adding plugin bluetooth to MyMusic Plugins Jun 10 14:07:37 volumio-test volumio[1164]: info: Adding plugin multiroom to MyMusic Plugins Jun 10 14:07:37 volumio-test volumio[1164]: info: Adding plugin metavolumio to MyMusic Plugins Jun 10 14:07:37 volumio-test volumio[1164]: info: Adding plugin cd_controller to MyMusic Plugins Jun 10 14:07:37 volumio-test volumio[1164]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 10 14:07:37 volumio-test volumio[1164]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 10 14:07:37 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 14:07:37 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 14:07:37 volumio-test volumio[1164]: info: Starting MyVolumio Remote Streaming Endpoints Jun 10 14:07:37 volumio-test volumio[1164]: info: MyVolumio login type: Token Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 10 14:07:37 volumio-test volumio[1164]: info: Streaming services startup Jun 10 14:07:37 volumio-test volumio[1164]: info: Starting Streaming Daemon Jun 10 14:07:37 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 10 14:07:37 volumio-test sudo[1789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 14:07:38 volumio-test sudo[1789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:38 volumio-test sudo[1789]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:38 volumio-test volumio[1164]: error: Cannot start Volumio Streaming Daemon Jun 10 14:07:38 volumio-test volumio[1164]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 10 14:07:38 volumio-test volumio[1164]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 10 14:07:38 volumio-test ntpd[951]: IO: Listen normally on 5 wlan0 169.254.175.119:123 Jun 10 14:07:38 volumio-test ntpd[951]: IO: new interface(s) found: waking up resolver Jun 10 14:07:38 volumio-test volumio[1164]: error: MyVolumio Custom Token format not valid, refreshing it Jun 10 14:07:38 volumio-test volumio[1164]: info: MyVolumio login type: Token Jun 10 14:07:39 volumio-test volumio[1164]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 10 14:07:39 volumio-test volumio[1164]: info: MyVolumio token set successfully Jun 10 14:07:39 volumio-test volumio[1164]: info: MYVOLUMIO: Adding device Jun 10 14:07:39 volumio-test volumio[1164]: info: MYVOLUMIO: Evaluating Server Jun 10 14:07:39 volumio-test volumio[1164]: info: MyVolumio Plan changed: premium Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 10 14:07:39 volumio-test volumio[1164]: info: Removing browser output: myVolumio user plan is not superstar Jun 10 14:07:39 volumio-test volumio[1164]: info: Removing audio output: Jun 10 14:07:39 volumio-test volumio[1164]: info: MYVOLUMIO: Adding device Jun 10 14:07:39 volumio-test volumio[1164]: info: MYVOLUMIO: Evaluating Server Jun 10 14:07:39 volumio-test volumio[1164]: info: Remote config written successfully Jun 10 14:07:39 volumio-test volumio[1164]: info: Starting Tunnel 1 Jun 10 14:07:39 volumio-test volumio[1164]: info: Starting Tunnel Connection Checker Jun 10 14:07:39 volumio-test volumio[1164]: info: Completed starting MyVolumio Plugin Jun 10 14:07:39 volumio-test volumio[1164]: info: MYVolumio Device enabled Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 10 14:07:39 volumio-test volumio[1164]: info: MyVolumio status changed Jun 10 14:07:39 volumio-test volumio[1164]: info: Streaming services startup Jun 10 14:07:39 volumio-test volumio[1164]: info: Starting Streaming Daemon Jun 10 14:07:39 volumio-test volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 10 14:07:39 volumio-test sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 14:07:39 volumio-test sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 10 14:07:39 volumio-test systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 10 14:07:39 volumio-test sudo[1828]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:39 volumio-test setdatetime-helper.sh[1831]: Time is not synchronized. Attempting to sync... Jun 10 14:07:39 volumio-test volumio[1164]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 10 14:07:39 volumio-test sudo[1840]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 10 Jun 2025 13:07:39 GMT#015' Jun 10 14:07:39 volumio-test sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 10 14:07:39 volumio-test setdatetime-helper.sh[1841]: Tue Jun 10 14:07:39 BST 2025 Jun 10 14:07:39 volumio-test sudo[1840]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:39 volumio-test systemd-journald[470]: Time jumped backwards, rotating. Jun 10 14:07:39 volumio-test setdatetime-helper.sh[1831]: Time synchronized successfully. Jun 10 14:07:39 volumio-test systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 10 14:07:39 volumio-test systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 10 14:07:39 volumio-test sudo[1845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 10 14:07:39 volumio-test sudo[1845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:39 volumio-test sudo[1845]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:39 volumio-test volumio[1164]: info: MRS: MultiRoom plugin initialized Jun 10 14:07:39 volumio-test volumio[1164]: info: MRS: STOPPING SNAPCLIENT Jun 10 14:07:39 volumio-test volumio[1164]: info: MRS: Snap server stop Jun 10 14:07:39 volumio-test volumio[1164]: info: MRS: STOPPING volumioStreaming Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 10 14:07:39 volumio-test sudo[1862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 10 14:07:39 volumio-test sudo[1862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:39 volumio-test sudo[1866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 10 14:07:39 volumio-test sudo[1868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 10 14:07:39 volumio-test sudo[1864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 10 14:07:39 volumio-test sudo[1868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:39 volumio-test sudo[1864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 10 14:07:39 volumio-test sudo[1866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 14:07:39 volumio-test sudo[1868]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 10 14:07:39 volumio-test sudo[1862]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 10 14:07:39 volumio-test sudo[1864]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:39 volumio-test sudo[1866]: pam_unix(sudo:session): session closed for user root Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 10 14:07:39 volumio-test volumio[1164]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 10 14:07:39 volumio-test volumio[1164]: info: Preparing to generate the ALSA configuration file Jun 10 14:07:39 volumio-test volumio[1164]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Jun 10 14:07:39 volumio-test volumio[1164]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 14:07:39 volumio-test volumio[1164]: Error: Unable to resolve or reject the same promise twice Jun 10 14:07:39 volumio-test volumio[1164]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 10 14:07:39 volumio-test volumio[1164]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 10 14:07:39 volumio-test volumio[1164]: at Socket.emit (node:events:514:28) Jun 10 14:07:39 volumio-test volumio[1164]: at addChunk (node:internal/streams/readable:343:12) Jun 10 14:07:39 volumio-test volumio[1164]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 10 14:07:39 volumio-test volumio[1164]: at Readable.push (node:internal/streams/readable:253:10) Jun 10 14:07:39 volumio-test volumio[1164]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 10 14:07:39 volumio-test volumio[1164]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 14:07:39 volumio-test sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-10 14:06' Jun 10 14:07:39 volumio-test sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="9c17cc34697ef689c9c9b64ea62ec0a8e386d7ce" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 7 03:41:32 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="3d7d070df9925b1237bac9fe8beec51e"