Oct 02 21:13:21 volumio kernel: Linux version 6.12.57-volumio (gkkpch@UbuntuVM24.04) (gcc (Ubuntu 13.3.0-6ubuntu2~24.04) 13.3.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 Oct 02 21:13:21 volumio kernel: Command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=ff9f55eb-0a9d-4451-8a94-4e45a1441048 bootpart=UUID=99F5-AE32 datapart=UUID=2cd2a181-641d-4576-a422-b6ec7752f6d5 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Oct 02 21:13:21 volumio kernel: KERNEL supported cpus: Oct 02 21:13:21 volumio kernel: Intel GenuineIntel Oct 02 21:13:21 volumio kernel: AMD AuthenticAMD Oct 02 21:13:21 volumio kernel: Hygon HygonGenuine Oct 02 21:13:21 volumio kernel: Centaur CentaurHauls Oct 02 21:13:21 volumio kernel: zhaoxin Shanghai Oct 02 21:13:21 volumio kernel: BIOS-provided physical RAM map: Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000057fff] usable Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x0000000000058000-0x0000000000058fff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x0000000000059000-0x000000000009dfff] usable Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x000000000009e000-0x00000000000fffff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000b81c3fff] usable Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000b81c4000-0x00000000b81c4fff] ACPI NVS Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000b81c5000-0x00000000b820efff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000b820f000-0x00000000bb5c2fff] usable Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000bb5c3000-0x00000000bc7cefff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000bc7cf000-0x00000000bc894fff] ACPI data Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000bc895000-0x00000000bcdcbfff] ACPI NVS Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000bcdcc000-0x00000000bd20efff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000bd20f000-0x00000000bd2fefff] type 20 Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000bd2ff000-0x00000000bd2fffff] usable Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000bd300000-0x00000000bfffffff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000fe000000-0x00000000fe010fff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved Oct 02 21:13:21 volumio kernel: BIOS-e820: [mem 0x0000000100000000-0x000000043dffffff] usable Oct 02 21:13:21 volumio kernel: NX (Execute Disable) protection: active Oct 02 21:13:21 volumio kernel: APIC: Static calls initialized Oct 02 21:13:21 volumio kernel: efi: EFI v2.4 by American Megatrends Oct 02 21:13:21 volumio kernel: efi: ESRT=0xbd154018 ACPI=0xbc7f6000 ACPI 2.0=0xbc7f6000 SMBIOS=0xbd150000 SMBIOS 3.0=0xbd14f000 MEMATTR=0xb9b97018 Oct 02 21:13:21 volumio kernel: efi: Remove mem34: MMIO range=[0xe0000000-0xefffffff] (256MB) from e820 map Oct 02 21:13:21 volumio kernel: e820: remove [mem 0xe0000000-0xefffffff] reserved Oct 02 21:13:21 volumio kernel: efi: Not removing mem35: MMIO range=[0xfe000000-0xfe010fff] (68KB) from e820 map Oct 02 21:13:21 volumio kernel: efi: Not removing mem36: MMIO range=[0xfec00000-0xfec00fff] (4KB) from e820 map Oct 02 21:13:21 volumio kernel: efi: Not removing mem37: MMIO range=[0xfee00000-0xfee00fff] (4KB) from e820 map Oct 02 21:13:21 volumio kernel: efi: Remove mem38: MMIO range=[0xff000000-0xffffffff] (16MB) from e820 map Oct 02 21:13:21 volumio kernel: e820: remove [mem 0xff000000-0xffffffff] reserved Oct 02 21:13:21 volumio kernel: SMBIOS 3.0.0 present. Oct 02 21:13:21 volumio kernel: DMI: LENOVO 10FRS01900/30D0, BIOS FWKTBFA 06/23/2022 Oct 02 21:13:21 volumio kernel: DMI: Memory slots populated: 2/2 Oct 02 21:13:21 volumio kernel: tsc: Detected 2800.000 MHz processor Oct 02 21:13:21 volumio kernel: tsc: Detected 2799.927 MHz TSC Oct 02 21:13:21 volumio kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 02 21:13:21 volumio kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 02 21:13:21 volumio kernel: last_pfn = 0x43e000 max_arch_pfn = 0x400000000 Oct 02 21:13:21 volumio kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 23), built from 10 variable MTRRs Oct 02 21:13:21 volumio kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 02 21:13:21 volumio kernel: last_pfn = 0xbd300 max_arch_pfn = 0x400000000 Oct 02 21:13:21 volumio kernel: esrt: Reserving ESRT space from 0x00000000bd154018 to 0x00000000bd154078. Oct 02 21:13:21 volumio kernel: Using GB pages for direct mapping Oct 02 21:13:21 volumio kernel: Secure boot could not be determined Oct 02 21:13:21 volumio kernel: RAMDISK: [mem 0x2e241000-0x33117fff] Oct 02 21:13:21 volumio kernel: ACPI: Early table checksum verification disabled Oct 02 21:13:21 volumio kernel: ACPI: RSDP 0x00000000BC7F6000 000024 (v02 LENOVO) Oct 02 21:13:21 volumio kernel: ACPI: XSDT 0x00000000BC7F60A8 0000CC (v01 LENOVO TC-FW 00001BF0 AMI 00010013) Oct 02 21:13:21 volumio kernel: ACPI: FACP 0x00000000BC821618 00010C (v05 LENOVO TC-FW 00001BF0 AMI 00010013) Oct 02 21:13:21 volumio kernel: ACPI: DSDT 0x00000000BC7F6200 02B412 (v02 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: FACS 0x00000000BCDCBD80 000040 Oct 02 21:13:21 volumio kernel: ACPI: APIC 0x00000000BC821728 0000BC (v03 LENOVO TC-FW 00001BF0 AMI 00010013) Oct 02 21:13:21 volumio kernel: ACPI: FPDT 0x00000000BC8217E8 000044 (v01 LENOVO TC-FW 00001BF0 AMI 00010013) Oct 02 21:13:21 volumio kernel: ACPI: FIDT 0x00000000BC821830 00009C (v01 LENOVO TC-FW 00001BF0 AMI 00010013) Oct 02 21:13:21 volumio kernel: ACPI: TCPA 0x00000000BC8218D0 000032 (v02 LENOVO TC-FW 00001BF0 MSFT 01000013) Oct 02 21:13:21 volumio kernel: ACPI: MCFG 0x00000000BC821908 00003C (v01 LENOVO TC-FW 00001BF0 MSFT 00000097) Oct 02 21:13:21 volumio kernel: ACPI: HPET 0x00000000BC821948 000038 (v01 LENOVO TC-FW 00001BF0 AMI. 0005000B) Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0x00000000BC821980 00036D (v01 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: LPIT 0x00000000BC821CF0 000094 (v01 LENOVO TC-FW 00001BF0 MSFT 0000005F) Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0x00000000BC821D88 000248 (v02 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0x00000000BC821FD0 002BAE (v02 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0x00000000BC824B80 000BE3 (v02 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: DBGP 0x00000000BC825768 000034 (v01 LENOVO TC-FW 00001BF0 MSFT 0000005F) Oct 02 21:13:21 volumio kernel: ACPI: DBG2 0x00000000BC8257A0 000054 (v00 LENOVO TC-FW 00001BF0 MSFT 0000005F) Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0x00000000BC8257F8 000A03 (v02 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: MSDM 0x00000000BC826200 000055 (v03 LENOVO TC-FW 00001BF0 AMI 00010013) Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0x00000000BC826258 0054A2 (v02 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: UEFI 0x00000000BC82B700 000042 (v01 LENOVO TC-FW 00001BF0 00000000) Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0x00000000BC82B748 000E73 (v02 LENOVO TC-FW 00001BF0 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: LUFT 0x00000000BC82C5C0 0569E2 (v01 LENOVO TC-FW 00001BF0 AMI 00010013) Oct 02 21:13:21 volumio kernel: ACPI: ASF! 0x00000000BC882FA8 0000A5 (v32 LENOVO TC-FW 00001BF0 TFSM 000F4240) Oct 02 21:13:21 volumio kernel: ACPI: Reserving FACP table memory at [mem 0xbc821618-0xbc821723] Oct 02 21:13:21 volumio kernel: ACPI: Reserving DSDT table memory at [mem 0xbc7f6200-0xbc821611] Oct 02 21:13:21 volumio kernel: ACPI: Reserving FACS table memory at [mem 0xbcdcbd80-0xbcdcbdbf] Oct 02 21:13:21 volumio kernel: ACPI: Reserving APIC table memory at [mem 0xbc821728-0xbc8217e3] Oct 02 21:13:21 volumio kernel: ACPI: Reserving FPDT table memory at [mem 0xbc8217e8-0xbc82182b] Oct 02 21:13:21 volumio kernel: ACPI: Reserving FIDT table memory at [mem 0xbc821830-0xbc8218cb] Oct 02 21:13:21 volumio kernel: ACPI: Reserving TCPA table memory at [mem 0xbc8218d0-0xbc821901] Oct 02 21:13:21 volumio kernel: ACPI: Reserving MCFG table memory at [mem 0xbc821908-0xbc821943] Oct 02 21:13:21 volumio kernel: ACPI: Reserving HPET table memory at [mem 0xbc821948-0xbc82197f] Oct 02 21:13:21 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xbc821980-0xbc821cec] Oct 02 21:13:21 volumio kernel: ACPI: Reserving LPIT table memory at [mem 0xbc821cf0-0xbc821d83] Oct 02 21:13:21 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xbc821d88-0xbc821fcf] Oct 02 21:13:21 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xbc821fd0-0xbc824b7d] Oct 02 21:13:21 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xbc824b80-0xbc825762] Oct 02 21:13:21 volumio kernel: ACPI: Reserving DBGP table memory at [mem 0xbc825768-0xbc82579b] Oct 02 21:13:21 volumio kernel: ACPI: Reserving DBG2 table memory at [mem 0xbc8257a0-0xbc8257f3] Oct 02 21:13:21 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xbc8257f8-0xbc8261fa] Oct 02 21:13:21 volumio kernel: ACPI: Reserving MSDM table memory at [mem 0xbc826200-0xbc826254] Oct 02 21:13:21 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xbc826258-0xbc82b6f9] Oct 02 21:13:21 volumio kernel: ACPI: Reserving UEFI table memory at [mem 0xbc82b700-0xbc82b741] Oct 02 21:13:21 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0xbc82b748-0xbc82c5ba] Oct 02 21:13:21 volumio kernel: ACPI: Reserving LUFT table memory at [mem 0xbc82c5c0-0xbc882fa1] Oct 02 21:13:21 volumio kernel: ACPI: Reserving ASF! table memory at [mem 0xbc882fa8-0xbc88304c] Oct 02 21:13:21 volumio kernel: Zone ranges: Oct 02 21:13:21 volumio kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 02 21:13:21 volumio kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 02 21:13:21 volumio kernel: Normal [mem 0x0000000100000000-0x000000043dffffff] Oct 02 21:13:21 volumio kernel: Movable zone start for each node Oct 02 21:13:21 volumio kernel: Early memory node ranges Oct 02 21:13:21 volumio kernel: node 0: [mem 0x0000000000001000-0x0000000000057fff] Oct 02 21:13:21 volumio kernel: node 0: [mem 0x0000000000059000-0x000000000009dfff] Oct 02 21:13:21 volumio kernel: node 0: [mem 0x0000000000100000-0x00000000b81c3fff] Oct 02 21:13:21 volumio kernel: node 0: [mem 0x00000000b820f000-0x00000000bb5c2fff] Oct 02 21:13:21 volumio kernel: node 0: [mem 0x00000000bd2ff000-0x00000000bd2fffff] Oct 02 21:13:21 volumio kernel: node 0: [mem 0x0000000100000000-0x000000043dffffff] Oct 02 21:13:21 volumio kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000043dffffff] Oct 02 21:13:21 volumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 02 21:13:21 volumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 02 21:13:21 volumio kernel: On node 0, zone DMA: 98 pages in unavailable ranges Oct 02 21:13:21 volumio kernel: On node 0, zone DMA32: 75 pages in unavailable ranges Oct 02 21:13:21 volumio kernel: On node 0, zone DMA32: 7484 pages in unavailable ranges Oct 02 21:13:21 volumio kernel: On node 0, zone Normal: 11520 pages in unavailable ranges Oct 02 21:13:21 volumio kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Oct 02 21:13:21 volumio kernel: Reserving Intel graphics memory at [mem 0xbe000000-0xbfffffff] Oct 02 21:13:21 volumio kernel: ACPI: PM-Timer IO Port: 0x1808 Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x05] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x06] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x07] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0x08] high edge lint[0x1]) Oct 02 21:13:21 volumio kernel: IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-119 Oct 02 21:13:21 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Oct 02 21:13:21 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 02 21:13:21 volumio kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 02 21:13:21 volumio kernel: ACPI: HPET id: 0x8086a701 base: 0xfed00000 Oct 02 21:13:21 volumio kernel: TSC deadline timer available Oct 02 21:13:21 volumio kernel: CPU topo: Max. logical packages: 1 Oct 02 21:13:21 volumio kernel: CPU topo: Max. logical dies: 1 Oct 02 21:13:21 volumio kernel: CPU topo: Max. dies per package: 1 Oct 02 21:13:21 volumio kernel: CPU topo: Max. threads per core: 2 Oct 02 21:13:21 volumio kernel: CPU topo: Num. cores per package: 4 Oct 02 21:13:21 volumio kernel: CPU topo: Num. threads per package: 8 Oct 02 21:13:21 volumio kernel: CPU topo: Allowing 8 present CPUs plus 0 hotplug CPUs Oct 02 21:13:21 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Oct 02 21:13:21 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00058000-0x00058fff] Oct 02 21:13:21 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x0009e000-0x000fffff] Oct 02 21:13:21 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xb81c4000-0xb820efff] Oct 02 21:13:21 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xbb5c3000-0xbd2fefff] Oct 02 21:13:21 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xbd300000-0xffffffff] Oct 02 21:13:21 volumio kernel: [mem 0xc0000000-0xfdffffff] available for PCI devices Oct 02 21:13:21 volumio kernel: Booting paravirtualized kernel on bare hardware Oct 02 21:13:21 volumio kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Oct 02 21:13:21 volumio kernel: setup_percpu: NR_CPUS:64 nr_cpumask_bits:8 nr_cpu_ids:8 nr_node_ids:1 Oct 02 21:13:21 volumio kernel: percpu: Embedded 59 pages/cpu s201368 r8192 d32104 u262144 Oct 02 21:13:21 volumio kernel: pcpu-alloc: s201368 r8192 d32104 u262144 alloc=1*2097152 Oct 02 21:13:21 volumio kernel: pcpu-alloc: [0] 0 1 2 3 4 5 6 7 Oct 02 21:13:21 volumio kernel: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=ff9f55eb-0a9d-4451-8a94-4e45a1441048 bootpart=UUID=99F5-AE32 datapart=UUID=2cd2a181-641d-4576-a422-b6ec7752f6d5 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Oct 02 21:13:21 volumio kernel: Unknown kernel command line parameters "splash bootdelay=5 biosdevname=0 imgpart=UUID=ff9f55eb-0a9d-4451-8a94-4e45a1441048 bootpart=UUID=99F5-AE32 datapart=UUID=2cd2a181-641d-4576-a422-b6ec7752f6d5 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Oct 02 21:13:21 volumio kernel: random: crng init done Oct 02 21:13:21 volumio kernel: Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear) Oct 02 21:13:21 volumio kernel: Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Oct 02 21:13:21 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 4166933 Oct 02 21:13:21 volumio kernel: mem auto-init: stack:all(pattern), heap alloc:off, heap free:off Oct 02 21:13:21 volumio kernel: software IO TLB: area num 8. Oct 02 21:13:21 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 Oct 02 21:13:21 volumio kernel: Kernel/User page tables isolation: enabled Oct 02 21:13:21 volumio kernel: ftrace: allocating 43851 entries in 172 pages Oct 02 21:13:21 volumio kernel: ftrace: allocated 172 pages with 4 groups Oct 02 21:13:21 volumio kernel: Dynamic Preempt: voluntary Oct 02 21:13:21 volumio kernel: rcu: Preemptible hierarchical RCU implementation. Oct 02 21:13:21 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=8. Oct 02 21:13:21 volumio kernel: Trampoline variant of Tasks RCU enabled. Oct 02 21:13:21 volumio kernel: Rude variant of Tasks RCU enabled. Oct 02 21:13:21 volumio kernel: Tracing variant of Tasks RCU enabled. Oct 02 21:13:21 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Oct 02 21:13:21 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8 Oct 02 21:13:21 volumio kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=8. Oct 02 21:13:21 volumio kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=8. Oct 02 21:13:21 volumio kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=8. Oct 02 21:13:21 volumio kernel: NR_IRQS: 4352, nr_irqs: 2048, preallocated irqs: 16 Oct 02 21:13:21 volumio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 02 21:13:21 volumio kernel: Console: colour dummy device 80x25 Oct 02 21:13:21 volumio kernel: printk: legacy console [tty0] enabled Oct 02 21:13:21 volumio kernel: ACPI: Core revision 20240827 Oct 02 21:13:21 volumio kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635855245 ns Oct 02 21:13:21 volumio kernel: APIC: Switch to symmetric I/O mode setup Oct 02 21:13:21 volumio kernel: x2apic: IRQ remapping doesn't support X2APIC mode Oct 02 21:13:21 volumio kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0 Oct 02 21:13:21 volumio kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x285bfbafad2, max_idle_ns: 440795215530 ns Oct 02 21:13:21 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5599.85 BogoMIPS (lpj=11199708) Oct 02 21:13:21 volumio kernel: x86/cpu: VMX (outside TXT) disabled by BIOS Oct 02 21:13:21 volumio kernel: CPU0: Thermal monitoring enabled (TM1) Oct 02 21:13:21 volumio kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 Oct 02 21:13:21 volumio kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 02 21:13:21 volumio kernel: process: using mwait in idle threads Oct 02 21:13:21 volumio kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 02 21:13:21 volumio kernel: Spectre V2 : Mitigation: IBRS Oct 02 21:13:21 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 02 21:13:21 volumio kernel: RETBleed: Mitigation: IBRS Oct 02 21:13:21 volumio kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Oct 02 21:13:21 volumio kernel: Spectre V2 : User space: Mitigation: STIBP via prctl Oct 02 21:13:21 volumio kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Oct 02 21:13:21 volumio kernel: MDS: Mitigation: Clear CPU buffers Oct 02 21:13:21 volumio kernel: TAA: Mitigation: TSX disabled Oct 02 21:13:21 volumio kernel: MMIO Stale Data: Mitigation: Clear CPU buffers Oct 02 21:13:21 volumio kernel: SRBDS: Mitigation: Microcode Oct 02 21:13:21 volumio kernel: GDS: Vulnerable: No microcode Oct 02 21:13:21 volumio kernel: VMSCAPE: Mitigation: IBPB before exit to userspace Oct 02 21:13:21 volumio kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 02 21:13:21 volumio kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 02 21:13:21 volumio kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 02 21:13:21 volumio kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' Oct 02 21:13:21 volumio kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' Oct 02 21:13:21 volumio kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 02 21:13:21 volumio kernel: x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 Oct 02 21:13:21 volumio kernel: x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 Oct 02 21:13:21 volumio kernel: x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format. Oct 02 21:13:21 volumio kernel: Freeing SMP alternatives memory: 36K Oct 02 21:13:21 volumio kernel: pid_max: default: 32768 minimum: 301 Oct 02 21:13:21 volumio kernel: LSM: initializing lsm=capability,yama Oct 02 21:13:21 volumio kernel: Yama: becoming mindful. Oct 02 21:13:21 volumio kernel: Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 02 21:13:21 volumio kernel: Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 02 21:13:21 volumio kernel: smpboot: CPU0: Intel(R) Core(TM) i7-6700T CPU @ 2.80GHz (family: 0x6, model: 0x5e, stepping: 0x3) Oct 02 21:13:21 volumio kernel: Performance Events: PEBS fmt3+, Skylake events, 32-deep LBR, full-width counters, Intel PMU driver. Oct 02 21:13:21 volumio kernel: ... version: 4 Oct 02 21:13:21 volumio kernel: ... bit width: 48 Oct 02 21:13:21 volumio kernel: ... generic registers: 4 Oct 02 21:13:21 volumio kernel: ... value mask: 0000ffffffffffff Oct 02 21:13:21 volumio kernel: ... max period: 00007fffffffffff Oct 02 21:13:21 volumio kernel: ... fixed-purpose events: 3 Oct 02 21:13:21 volumio kernel: ... event mask: 000000070000000f Oct 02 21:13:21 volumio kernel: signal: max sigframe size: 2032 Oct 02 21:13:21 volumio kernel: Estimated ratio of average max frequency by base frequency (times 1024): 1243 Oct 02 21:13:21 volumio kernel: rcu: Hierarchical SRCU implementation. Oct 02 21:13:21 volumio kernel: rcu: Max phase no-delay instances is 1000. Oct 02 21:13:21 volumio kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 02 21:13:21 volumio kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. Oct 02 21:13:21 volumio kernel: smp: Bringing up secondary CPUs ... Oct 02 21:13:21 volumio kernel: smpboot: x86: Booting SMP configuration: Oct 02 21:13:21 volumio kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 #6 #7 Oct 02 21:13:21 volumio kernel: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. Oct 02 21:13:21 volumio kernel: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details. Oct 02 21:13:21 volumio kernel: VMSCAPE: SMT on, STIBP is required for full protection. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/vmscape.html for more details. Oct 02 21:13:21 volumio kernel: smp: Brought up 1 node, 8 CPUs Oct 02 21:13:21 volumio kernel: smpboot: Total of 8 processors activated (44798.83 BogoMIPS) Oct 02 21:13:21 volumio kernel: Memory: 16190224K/16667732K available (16384K kernel code, 2032K rwdata, 5320K rodata, 3128K init, 840K bss, 465012K reserved, 0K cma-reserved) Oct 02 21:13:21 volumio kernel: devtmpfs: initialized Oct 02 21:13:21 volumio kernel: x86/mm: Memory block size: 128MB Oct 02 21:13:21 volumio kernel: ACPI: PM: Registering ACPI NVS region [mem 0xb81c4000-0xb81c4fff] (4096 bytes) Oct 02 21:13:21 volumio kernel: ACPI: PM: Registering ACPI NVS region [mem 0xbc895000-0xbcdcbfff] (5468160 bytes) Oct 02 21:13:21 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Oct 02 21:13:21 volumio kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 02 21:13:21 volumio kernel: pinctrl core: initialized pinctrl subsystem Oct 02 21:13:21 volumio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 02 21:13:21 volumio kernel: audit: initializing netlink subsys (disabled) Oct 02 21:13:21 volumio kernel: audit: type=2000 audit(1790968387.000:1): state=initialized audit_enabled=0 res=1 Oct 02 21:13:21 volumio kernel: thermal_sys: Registered thermal governor 'fair_share' Oct 02 21:13:21 volumio kernel: thermal_sys: Registered thermal governor 'bang_bang' Oct 02 21:13:21 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 02 21:13:21 volumio kernel: thermal_sys: Registered thermal governor 'user_space' Oct 02 21:13:21 volumio kernel: cpuidle: using governor ladder Oct 02 21:13:21 volumio kernel: cpuidle: using governor menu Oct 02 21:13:21 volumio kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 02 21:13:21 volumio kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff] Oct 02 21:13:21 volumio kernel: PCI: Using configuration type 1 for base access Oct 02 21:13:21 volumio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 02 21:13:21 volumio kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 02 21:13:21 volumio kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 02 21:13:21 volumio kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 02 21:13:21 volumio kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 02 21:13:21 volumio kernel: ACPI: Added _OSI(Module Device) Oct 02 21:13:21 volumio kernel: ACPI: Added _OSI(Processor Device) Oct 02 21:13:21 volumio kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 02 21:13:21 volumio kernel: ACPI: 8 ACPI AML tables successfully acquired and loaded Oct 02 21:13:21 volumio kernel: ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored Oct 02 21:13:21 volumio kernel: ACPI: Dynamic OEM Table Load: Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0xFFFF888100E10000 00037F (v02 PmRef Cpu0Cst 00003001 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: Dynamic OEM Table Load: Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0xFFFF888100E09000 00061E (v02 PmRef Cpu0Ist 00003000 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: Dynamic OEM Table Load: Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0xFFFF88810120F240 00008E (v02 PmRef Cpu0Hwp 00003000 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: Dynamic OEM Table Load: Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0xFFFF888101240000 000130 (v02 PmRef HwpLvt 00003000 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: Dynamic OEM Table Load: Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0xFFFF888100E0A000 0005AA (v02 PmRef ApIst 00003000 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: Dynamic OEM Table Load: Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0xFFFF888101240200 000119 (v02 PmRef ApHwp 00003000 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: Dynamic OEM Table Load: Oct 02 21:13:21 volumio kernel: ACPI: SSDT 0xFFFF888101240400 000119 (v02 PmRef ApCst 00003000 INTL 20120913) Oct 02 21:13:21 volumio kernel: ACPI: Interpreter enabled Oct 02 21:13:21 volumio kernel: ACPI: PM: (supports S0 S3 S4 S5) Oct 02 21:13:21 volumio kernel: ACPI: Using IOAPIC for interrupt routing Oct 02 21:13:21 volumio kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 02 21:13:21 volumio kernel: PCI: Using E820 reservations for host bridge windows Oct 02 21:13:21 volumio kernel: ACPI: Enabled 10 GPEs in block 00 to 7F Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.PEG0.PG00: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.PEG1.PG01: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.PEG2.PG02: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP09.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP10.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP11.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP12.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP13.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP01.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP02.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP03.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP04.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP05.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP06.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP07.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP08.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP17.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP18.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP19.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP20.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP14.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP15.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PCI0.RP16.PXSX.WRST: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_TZ_.FN00: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_TZ_.FN01: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_TZ_.FN02: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_TZ_.FN03: New power resource Oct 02 21:13:21 volumio kernel: ACPI: \_TZ_.FN04: New power resource Oct 02 21:13:21 volumio kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-fe]) Oct 02 21:13:21 volumio kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 02 21:13:21 volumio kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR] Oct 02 21:13:21 volumio kernel: PCI host bridge to bus 0000:00 Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000effff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xdfffffff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: root bus resource [mem 0xfd000000-0xfe7fffff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: root bus resource [bus 00-fe] Oct 02 21:13:21 volumio kernel: pci 0000:00:00.0: [8086:191f] type 00 class 0x060000 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: [8086:1912] type 00 class 0x030000 PCIe Root Complex Integrated Endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: BAR 0 [mem 0xde000000-0xdeffffff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: BAR 2 [mem 0xc0000000-0xcfffffff 64bit pref] Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: BAR 4 [io 0xf000-0xf03f] Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 02 21:13:21 volumio kernel: pci 0000:00:14.0: [8086:a12f] type 00 class 0x0c0330 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:14.0: BAR 0 [mem 0xdf130000-0xdf13ffff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:00:14.0: PME# supported from D3hot D3cold Oct 02 21:13:21 volumio kernel: pci 0000:00:16.0: [8086:a13a] type 00 class 0x078000 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:16.0: BAR 0 [mem 0xdf14e000-0xdf14efff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:00:16.0: PME# supported from D3hot Oct 02 21:13:21 volumio kernel: pci 0000:00:16.3: [8086:a13d] type 00 class 0x070002 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:16.3: BAR 0 [io 0xf0a0-0xf0a7] Oct 02 21:13:21 volumio kernel: pci 0000:00:16.3: BAR 1 [mem 0xdf14d000-0xdf14dfff] Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: [8086:a102] type 00 class 0x010601 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: BAR 0 [mem 0xdf148000-0xdf149fff] Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: BAR 1 [mem 0xdf14c000-0xdf14c0ff] Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: BAR 2 [io 0xf090-0xf097] Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: BAR 3 [io 0xf080-0xf083] Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: BAR 4 [io 0xf060-0xf07f] Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: BAR 5 [mem 0xdf14b000-0xdf14b7ff] Oct 02 21:13:21 volumio kernel: pci 0000:00:17.0: PME# supported from D3hot Oct 02 21:13:21 volumio kernel: pci 0000:00:1b.0: [8086:a167] type 01 class 0x060400 PCIe Root Port Oct 02 21:13:21 volumio kernel: pci 0000:00:1b.0: PCI bridge to [bus 01] Oct 02 21:13:21 volumio kernel: pci 0000:00:1b.0: bridge window [mem 0xdf000000-0xdf0fffff] Oct 02 21:13:21 volumio kernel: pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.0: [8086:a146] type 00 class 0x060100 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.2: [8086:a121] type 00 class 0x058000 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.2: BAR 0 [mem 0xdf144000-0xdf147fff] Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.3: [8086:a170] type 00 class 0x040300 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.3: BAR 0 [mem 0xdf140000-0xdf143fff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.3: BAR 4 [mem 0xdf120000-0xdf12ffff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.3: PME# supported from D3hot D3cold Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.4: [8086:a123] type 00 class 0x0c0500 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.4: BAR 0 [mem 0xdf14a000-0xdf14a0ff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.4: BAR 4 [io 0xf040-0xf05f] Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.6: [8086:15b7] type 00 class 0x020000 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.6: BAR 0 [mem 0xdf100000-0xdf11ffff] Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.6: PME# supported from D0 D3hot D3cold Oct 02 21:13:21 volumio kernel: pci 0000:01:00.0: [15b7:5009] type 00 class 0x010802 PCIe Endpoint Oct 02 21:13:21 volumio kernel: pci 0000:01:00.0: BAR 0 [mem 0xdf000000-0xdf003fff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:01:00.0: BAR 4 [mem 0xdf004000-0xdf0040ff 64bit] Oct 02 21:13:21 volumio kernel: pci 0000:00:1b.0: PCI bridge to [bus 01] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: on NUMA node 0 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 11 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 11 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 11 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Oct 02 21:13:21 volumio kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Oct 02 21:13:21 volumio kernel: ACPI: \_SB_.PEPD: Duplicate LPS0 _DSM functions (mask: 0x1) Oct 02 21:13:21 volumio kernel: iommu: Default domain type: Translated Oct 02 21:13:21 volumio kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 02 21:13:21 volumio kernel: SCSI subsystem initialized Oct 02 21:13:21 volumio kernel: libata version 3.00 loaded. Oct 02 21:13:21 volumio kernel: ACPI: bus type USB registered Oct 02 21:13:21 volumio kernel: usbcore: registered new interface driver usbfs Oct 02 21:13:21 volumio kernel: usbcore: registered new interface driver hub Oct 02 21:13:21 volumio kernel: usbcore: registered new device driver usb Oct 02 21:13:21 volumio kernel: efivars: Registered efivars operations Oct 02 21:13:21 volumio kernel: PCI: Using ACPI for IRQ routing Oct 02 21:13:21 volumio kernel: PCI: pci_cache_line_size set to 64 bytes Oct 02 21:13:21 volumio kernel: e820: reserve RAM buffer [mem 0x00058000-0x0005ffff] Oct 02 21:13:21 volumio kernel: e820: reserve RAM buffer [mem 0x0009e000-0x0009ffff] Oct 02 21:13:21 volumio kernel: e820: reserve RAM buffer [mem 0xb81c4000-0xbbffffff] Oct 02 21:13:21 volumio kernel: e820: reserve RAM buffer [mem 0xbb5c3000-0xbbffffff] Oct 02 21:13:21 volumio kernel: e820: reserve RAM buffer [mem 0xbd300000-0xbfffffff] Oct 02 21:13:21 volumio kernel: e820: reserve RAM buffer [mem 0x43e000000-0x43fffffff] Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: vgaarb: bridge control possible Oct 02 21:13:21 volumio kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 02 21:13:21 volumio kernel: vgaarb: loaded Oct 02 21:13:21 volumio kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0 Oct 02 21:13:21 volumio kernel: hpet0: 8 comparators, 64-bit 24.000000 MHz counter Oct 02 21:13:21 volumio kernel: clocksource: Switched to clocksource tsc-early Oct 02 21:13:21 volumio kernel: VFS: Disk quotas dquot_6.6.0 Oct 02 21:13:21 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 02 21:13:21 volumio kernel: pnp: PnP ACPI init Oct 02 21:13:21 volumio kernel: system 00:00: [io 0x0a00-0x0a0f] has been reserved Oct 02 21:13:21 volumio kernel: system 00:00: [io 0x0a10-0x0a1f] has been reserved Oct 02 21:13:21 volumio kernel: system 00:00: [io 0x0a20-0x0a2f] has been reserved Oct 02 21:13:21 volumio kernel: system 00:01: [io 0x0680-0x069f] has been reserved Oct 02 21:13:21 volumio kernel: system 00:01: [io 0xffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:01: [io 0xffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:01: [io 0xffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:01: [io 0x1800-0x18fe] could not be reserved Oct 02 21:13:21 volumio kernel: system 00:01: [io 0x164e-0x164f] has been reserved Oct 02 21:13:21 volumio kernel: system 00:02: [io 0x0800-0x087f] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xfed10000-0xfed17fff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xfed18000-0xfed18fff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xfed19000-0xfed19fff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xe0000000-0xefffffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xfed20000-0xfed3ffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xfed90000-0xfed93fff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xfed45000-0xfed8ffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xff000000-0xffffffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xfee00000-0xfeefffff] could not be reserved Oct 02 21:13:21 volumio kernel: system 00:04: [mem 0xdffc0000-0xdffdffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:05: [mem 0xfd000000-0xfdabffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:05: [mem 0xfdad0000-0xfdadffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:05: [mem 0xfdb00000-0xfdffffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:05: [mem 0xfe000000-0xfe01ffff] could not be reserved Oct 02 21:13:21 volumio kernel: system 00:05: [mem 0xfe036000-0xfe03bfff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:05: [mem 0xfe03d000-0xfe3fffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:05: [mem 0xfe410000-0xfe7fffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:06: [io 0xff00-0xfffe] has been reserved Oct 02 21:13:21 volumio kernel: system 00:07: [mem 0xfdaf0000-0xfdafffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:07: [mem 0xfdae0000-0xfdaeffff] has been reserved Oct 02 21:13:21 volumio kernel: system 00:07: [mem 0xfdac0000-0xfdacffff] has been reserved Oct 02 21:13:21 volumio kernel: pnp: PnP ACPI: found 9 devices Oct 02 21:13:21 volumio kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 02 21:13:21 volumio kernel: NET: Registered PF_INET protocol family Oct 02 21:13:21 volumio kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 02 21:13:21 volumio kernel: tcp_listen_portaddr_hash hash table entries: 8192 (order: 5, 131072 bytes, linear) Oct 02 21:13:21 volumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 02 21:13:21 volumio kernel: TCP established hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 02 21:13:21 volumio kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 02 21:13:21 volumio kernel: TCP: Hash tables configured (established 131072 bind 65536) Oct 02 21:13:21 volumio kernel: UDP hash table entries: 8192 (order: 6, 262144 bytes, linear) Oct 02 21:13:21 volumio kernel: UDP-Lite hash table entries: 8192 (order: 6, 262144 bytes, linear) Oct 02 21:13:21 volumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 02 21:13:21 volumio kernel: pci 0000:00:1b.0: PCI bridge to [bus 01] Oct 02 21:13:21 volumio kernel: pci 0000:00:1b.0: bridge window [mem 0xdf000000-0xdf0fffff] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000effff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xdfffffff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:00: resource 8 [mem 0xfd000000-0xfe7fffff window] Oct 02 21:13:21 volumio kernel: pci_bus 0000:01: resource 1 [mem 0xdf000000-0xdf0fffff] Oct 02 21:13:21 volumio kernel: PCI: CLS 0 bytes, default 64 Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.1: [8086:a120] type 00 class 0x058000 conventional PCI endpoint Oct 02 21:13:21 volumio kernel: pci 0000:00:1f.1: BAR 0 [mem 0xfd000000-0xfdffffff 64bit] Oct 02 21:13:21 volumio kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 02 21:13:21 volumio kernel: software IO TLB: mapped [mem 0x00000000b331a000-0x00000000b731a000] (64MB) Oct 02 21:13:21 volumio kernel: Trying to unpack rootfs image as initramfs... Oct 02 21:13:21 volumio kernel: RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer Oct 02 21:13:21 volumio kernel: RAPL PMU: hw unit of domain pp0-core 2^-14 Joules Oct 02 21:13:21 volumio kernel: RAPL PMU: hw unit of domain package 2^-14 Joules Oct 02 21:13:21 volumio kernel: RAPL PMU: hw unit of domain dram 2^-14 Joules Oct 02 21:13:21 volumio kernel: RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules Oct 02 21:13:21 volumio kernel: Initialise system trusted keyrings Oct 02 21:13:21 volumio kernel: workingset: timestamp_bits=46 max_order=22 bucket_order=0 Oct 02 21:13:21 volumio kernel: Key type asymmetric registered Oct 02 21:13:21 volumio kernel: Asymmetric key parser 'x509' registered Oct 02 21:13:21 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 02 21:13:21 volumio kernel: io scheduler mq-deadline registered Oct 02 21:13:21 volumio kernel: io scheduler kyber registered Oct 02 21:13:21 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 02 21:13:21 volumio kernel: pcieport 0000:00:1b.0: PME: Signaling with IRQ 120 Oct 02 21:13:21 volumio kernel: pcieport 0000:00:1b.0: AER: enabled with IRQ 120 Oct 02 21:13:21 volumio kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Oct 02 21:13:21 volumio kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input0 Oct 02 21:13:21 volumio kernel: ACPI: button: Sleep Button [SLPB] Oct 02 21:13:21 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 Oct 02 21:13:21 volumio kernel: ACPI: button: Power Button [PWRB] Oct 02 21:13:21 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Oct 02 21:13:21 volumio kernel: ACPI: button: Power Button [PWRF] Oct 02 21:13:21 volumio kernel: thermal LNXTHERM:00: registered as thermal_zone0 Oct 02 21:13:21 volumio kernel: ACPI: thermal: Thermal Zone [TZ00] (28 C) Oct 02 21:13:21 volumio kernel: thermal LNXTHERM:01: registered as thermal_zone1 Oct 02 21:13:21 volumio kernel: ACPI: thermal: Thermal Zone [TZ01] (30 C) Oct 02 21:13:21 volumio kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Oct 02 21:13:21 volumio kernel: serial 0000:00:16.3: enabling device (0000 -> 0003) Oct 02 21:13:21 volumio kernel: 0000:00:16.3: ttyS4 at I/O 0xf0a0 (irq = 19, base_baud = 115200) is a 16550A Oct 02 21:13:21 volumio kernel: Linux agpgart interface v0.103 Oct 02 21:13:21 volumio kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Oct 02 21:13:21 volumio kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 Oct 02 21:13:21 volumio kernel: xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x0000000001109810 Oct 02 21:13:21 volumio kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Oct 02 21:13:21 volumio kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2 Oct 02 21:13:21 volumio kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.0 SuperSpeed Oct 02 21:13:21 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Oct 02 21:13:21 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 02 21:13:21 volumio kernel: usb usb1: Product: xHCI Host Controller Oct 02 21:13:21 volumio kernel: usb usb1: Manufacturer: Linux 6.12.57-volumio xhci-hcd Oct 02 21:13:21 volumio kernel: usb usb1: SerialNumber: 0000:00:14.0 Oct 02 21:13:21 volumio kernel: hub 1-0:1.0: USB hub found Oct 02 21:13:21 volumio kernel: hub 1-0:1.0: 16 ports detected Oct 02 21:13:21 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Oct 02 21:13:21 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 02 21:13:21 volumio kernel: usb usb2: Product: xHCI Host Controller Oct 02 21:13:21 volumio kernel: usb usb2: Manufacturer: Linux 6.12.57-volumio xhci-hcd Oct 02 21:13:21 volumio kernel: usb usb2: SerialNumber: 0000:00:14.0 Oct 02 21:13:21 volumio kernel: hub 2-0:1.0: USB hub found Oct 02 21:13:21 volumio kernel: hub 2-0:1.0: 10 ports detected Oct 02 21:13:21 volumio kernel: usbcore: registered new interface driver usb-storage Oct 02 21:13:21 volumio kernel: i8042: PNP: No PS/2 controller found. Oct 02 21:13:21 volumio kernel: mousedev: PS/2 mouse device common for all mice Oct 02 21:13:21 volumio kernel: rtc_cmos 00:03: RTC can wake from S4 Oct 02 21:13:21 volumio kernel: rtc_cmos 00:03: registered as rtc0 Oct 02 21:13:21 volumio kernel: rtc_cmos 00:03: setting system clock to 2026-10-02T19:13:07 UTC (1790968387) Oct 02 21:13:21 volumio kernel: rtc_cmos 00:03: alarms up to one month, y3k, 242 bytes nvram Oct 02 21:13:21 volumio kernel: i2c_dev: i2c /dev entries driver Oct 02 21:13:21 volumio kernel: intel_pstate: Intel P-state driver initializing Oct 02 21:13:21 volumio kernel: intel_pstate: HWP enabled Oct 02 21:13:21 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Oct 02 21:13:21 volumio kernel: NET: Registered PF_INET6 protocol family Oct 02 21:13:21 volumio kernel: Segment Routing with IPv6 Oct 02 21:13:21 volumio kernel: In-situ OAM (IOAM) with IPv6 Oct 02 21:13:21 volumio kernel: mip6: Mobile IPv6 Oct 02 21:13:21 volumio kernel: NET: Registered PF_PACKET protocol family Oct 02 21:13:21 volumio kernel: mpls_gso: MPLS GSO support Oct 02 21:13:21 volumio kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance' Oct 02 21:13:21 volumio kernel: microcode: Current revision: 0x000000f0 Oct 02 21:13:21 volumio kernel: IPI shorthand broadcast: enabled Oct 02 21:13:21 volumio kernel: sched_clock: Marking stable (318119007, 609299)->(321261418, -2533112) Oct 02 21:13:21 volumio kernel: Loading compiled-in X.509 certificates Oct 02 21:13:21 volumio kernel: clk: Disabling unused clocks Oct 02 21:13:21 volumio kernel: PM: genpd: Disabling unused power domains Oct 02 21:13:21 volumio kernel: usb 1-2: new high-speed USB device number 2 using xhci_hcd Oct 02 21:13:21 volumio kernel: Freeing initrd memory: 80732K Oct 02 21:13:21 volumio kernel: Freeing unused kernel image (initmem) memory: 3128K Oct 02 21:13:21 volumio kernel: Write protecting the kernel read-only data: 22528k Oct 02 21:13:21 volumio kernel: Freeing unused kernel image (rodata/data gap) memory: 824K Oct 02 21:13:21 volumio kernel: Run /init as init process Oct 02 21:13:21 volumio kernel: with arguments: Oct 02 21:13:21 volumio kernel: /init Oct 02 21:13:21 volumio kernel: splash Oct 02 21:13:21 volumio kernel: with environment: Oct 02 21:13:21 volumio kernel: HOME=/ Oct 02 21:13:21 volumio kernel: TERM=linux Oct 02 21:13:21 volumio kernel: bootdelay=5 Oct 02 21:13:21 volumio kernel: biosdevname=0 Oct 02 21:13:21 volumio kernel: imgpart=UUID=ff9f55eb-0a9d-4451-8a94-4e45a1441048 Oct 02 21:13:21 volumio kernel: bootpart=UUID=99F5-AE32 Oct 02 21:13:21 volumio kernel: datapart=UUID=2cd2a181-641d-4576-a422-b6ec7752f6d5 Oct 02 21:13:21 volumio kernel: hwdevice=x86 Oct 02 21:13:21 volumio kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Oct 02 21:13:21 volumio kernel: imgfile=/volumio_current.sqsh Oct 02 21:13:21 volumio kernel: use_kmsg=no Oct 02 21:13:21 volumio kernel: usb 1-2: New USB device found, idVendor=16d0, idProduct=0a23, bcdDevice= 1.9b Oct 02 21:13:21 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 02 21:13:21 volumio kernel: usb 1-2: Product: Combo384 Amanero Oct 02 21:13:21 volumio kernel: usb 1-2: Manufacturer: Amanero Technologies Oct 02 21:13:21 volumio kernel: usb 1-2: SerialNumber: 414-001 Oct 02 21:13:21 volumio kernel: ahci 0000:00:17.0: version 3.0 Oct 02 21:13:21 volumio kernel: ahci 0000:00:17.0: AHCI vers 0001.0301, 32 command slots, 6 Gbps, SATA mode Oct 02 21:13:21 volumio kernel: ahci 0000:00:17.0: 4/4 ports implemented (port mask 0xf) Oct 02 21:13:21 volumio kernel: ahci 0000:00:17.0: flags: 64bit ncq sntf pm led clo only pio slum part ems deso sadm sds apst Oct 02 21:13:21 volumio kernel: usb 1-5: new high-speed USB device number 3 using xhci_hcd Oct 02 21:13:21 volumio kernel: ACPI: bus type drm_connector registered Oct 02 21:13:21 volumio kernel: e1000e: Intel(R) PRO/1000 Network Driver Oct 02 21:13:21 volumio kernel: e1000e: Copyright(c) 1999 - 2015 Intel Corporation. Oct 02 21:13:21 volumio kernel: e1000e 0000:00:1f.6: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode Oct 02 21:13:21 volumio kernel: nvme nvme0: pci function 0000:01:00.0 Oct 02 21:13:21 volumio kernel: nvme nvme0: allocated 32 MiB host memory buffer. Oct 02 21:13:21 volumio kernel: scsi host0: ahci Oct 02 21:13:21 volumio kernel: scsi host1: ahci Oct 02 21:13:21 volumio kernel: scsi host2: ahci Oct 02 21:13:21 volumio kernel: scsi host3: ahci Oct 02 21:13:21 volumio kernel: ata1: SATA max UDMA/133 abar m2048@0xdf14b000 port 0xdf14b100 irq 122 lpm-pol 3 Oct 02 21:13:21 volumio kernel: ata2: SATA max UDMA/133 abar m2048@0xdf14b000 port 0xdf14b180 irq 122 lpm-pol 3 Oct 02 21:13:21 volumio kernel: ata3: SATA max UDMA/133 abar m2048@0xdf14b000 port 0xdf14b200 irq 122 lpm-pol 3 Oct 02 21:13:21 volumio kernel: ata4: SATA max UDMA/133 abar m2048@0xdf14b000 port 0xdf14b280 irq 122 lpm-pol 3 Oct 02 21:13:21 volumio kernel: nvme nvme0: 8/0/0 default/read/poll queues Oct 02 21:13:21 volumio kernel: nvme0n1: p1 p2 p3 Oct 02 21:13:21 volumio kernel: usb 1-5: New USB device found, idVendor=22d9, idProduct=0436, bcdDevice= 1.01 Oct 02 21:13:21 volumio kernel: usb 1-5: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 02 21:13:21 volumio kernel: usb 1-5: Product: BRZHIFI Oct 02 21:13:21 volumio kernel: usb 1-5: Manufacturer: BRZHIFI Oct 02 21:13:21 volumio kernel: usb 1-5: SerialNumber: 0 Oct 02 21:13:21 volumio kernel: e1000e 0000:00:1f.6 eth0: (PCI Express:2.5GT/s:Width x1) 00:23:24:b3:13:18 Oct 02 21:13:21 volumio kernel: e1000e 0000:00:1f.6 eth0: Intel(R) PRO/1000 Network Connection Oct 02 21:13:21 volumio kernel: e1000e 0000:00:1f.6 eth0: MAC: 12, PHY: 12, PBA No: FFFFFF-0FF Oct 02 21:13:21 volumio kernel: usbcore: registered new interface driver usbhid Oct 02 21:13:21 volumio kernel: usbhid: USB HID core driver Oct 02 21:13:21 volumio kernel: ata2: SATA link down (SStatus 4 SControl 300) Oct 02 21:13:21 volumio kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Oct 02 21:13:21 volumio kernel: ata3: SATA link down (SStatus 4 SControl 300) Oct 02 21:13:21 volumio kernel: ata4: SATA link down (SStatus 4 SControl 300) Oct 02 21:13:21 volumio kernel: ata1.00: ATAPI: TSSTcorp CDDVDW SH-224BB, SB00, max UDMA/100 Oct 02 21:13:21 volumio kernel: ata1.00: configured for UDMA/100 Oct 02 21:13:21 volumio kernel: scsi 0:0:0:0: CD-ROM TSSTcorp CDDVDW SH-224BB SB00 PQ: 0 ANSI: 5 Oct 02 21:13:21 volumio kernel: i801_smbus 0000:00:1f.4: enabling device (0001 -> 0003) Oct 02 21:13:21 volumio kernel: i801_smbus 0000:00:1f.4: SPD Write Disable is set Oct 02 21:13:21 volumio kernel: i801_smbus 0000:00:1f.4: SMBus using PCI interrupt Oct 02 21:13:21 volumio kernel: hid-generic 0003:22D9:0436.0001: hiddev0,hidraw0: USB HID v1.10 Device [BRZHIFI BRZHIFI] on usb-0000:00:14.0-5/input3 Oct 02 21:13:21 volumio kernel: i2c i2c-0: Successfully instantiated SPD at 0x50 Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] scsi3-mmc drive: 32x/40x writer dvd-ram cd/rw xa/form2 cdda tray Oct 02 21:13:21 volumio kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 02 21:13:21 volumio kernel: snd_hda_intel 0000:00:1f.3: enabling device (0000 -> 0002) Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: Attached scsi CD-ROM sr0 Oct 02 21:13:21 volumio kernel: tsc: Refined TSC clocksource calibration: 2808.042 MHz Oct 02 21:13:21 volumio kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2879ed89721, max_idle_ns: 440795251415 ns Oct 02 21:13:21 volumio kernel: clocksource: Switched to clocksource tsc Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: [drm] Found SKYLAKE (device ID 1912) display version 9.00 stepping G0 Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: vgaarb: deactivate vga console Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: [drm] Finished loading DMC firmware i915/skl_dmc_ver1_27.bin (v1.27) Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: [drm] [ENCODER:98:DDI A/PHY A] failed to retrieve link info, disabling eDP Oct 02 21:13:21 volumio kernel: [drm] Initialized i915 1.6.0 for 0000:00:02.0 on minor 0 Oct 02 21:13:21 volumio kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Oct 02 21:13:21 volumio kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input3 Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Oct 02 21:13:21 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 02 21:13:21 volumio kernel: snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops __SCT__tp_func_intel_frontbuffer_flush [i915]) Oct 02 21:13:21 volumio kernel: fuse: init (API version 7.41) Oct 02 21:13:21 volumio kernel: snd_hda_codec_generic hdaudioC0D1: autoconfig for Generic: line_outs=1 (0x1b/0x0/0x0/0x0/0x0) type:line Oct 02 21:13:21 volumio kernel: snd_hda_codec_generic hdaudioC0D1: speaker_outs=1 (0x14/0x0/0x0/0x0/0x0) Oct 02 21:13:21 volumio kernel: snd_hda_codec_generic hdaudioC0D1: hp_outs=1 (0x21/0x0/0x0/0x0/0x0) Oct 02 21:13:21 volumio kernel: snd_hda_codec_generic hdaudioC0D1: mono: mono_out=0x0 Oct 02 21:13:21 volumio kernel: snd_hda_codec_generic hdaudioC0D1: inputs: Oct 02 21:13:21 volumio kernel: snd_hda_codec_generic hdaudioC0D1: Mic=0x19 Oct 02 21:13:21 volumio kernel: snd_hda_codec_generic hdaudioC0D1: Mic=0x1a Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Oct 02 21:13:21 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Oct 02 21:13:21 volumio kernel: sdhci: Copyright(c) Pierre Ossman Oct 02 21:13:21 volumio kernel: input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input4 Oct 02 21:13:21 volumio kernel: input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input5 Oct 02 21:13:21 volumio kernel: input: HDA Intel PCH Line Out as /devices/pci0000:00/0000:00:1f.3/sound/card0/input6 Oct 02 21:13:21 volumio kernel: input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input7 Oct 02 21:13:21 volumio kernel: input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input8 Oct 02 21:13:21 volumio kernel: input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input9 Oct 02 21:13:21 volumio kernel: input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input10 Oct 02 21:13:21 volumio kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Oct 02 21:13:21 volumio kernel: usbcore: registered new interface driver uas Oct 02 21:13:21 volumio kernel: [drm] radeon kernel modesetting enabled. Oct 02 21:13:21 volumio kernel: [drm] amdgpu kernel modesetting enabled. Oct 02 21:13:21 volumio kernel: scsi host4: pata_legacy Oct 02 21:13:21 volumio kernel: ata5: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 lpm-pol 0 Oct 02 21:13:21 volumio kernel: scsi host4: pata_legacy Oct 02 21:13:21 volumio kernel: ata5: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 lpm-pol 0 Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#3 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#3 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#3 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#3 CDB: Read(10) 28 00 00 05 17 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 1334272 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#4 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#4 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#4 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#4 CDB: Read(10) 28 00 00 05 17 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 1334272 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: Buffer I/O error on dev sr0, logical block 166784, async page read Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#5 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#5 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#5 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#5 CDB: Read(10) 28 00 00 00 00 00 00 00 04 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 0 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#6 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#6 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#6 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#6 CDB: Read(10) 28 00 00 00 00 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: Buffer I/O error on dev sr0, logical block 0, async page read Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#7 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#7 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#7 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#7 CDB: Read(10) 28 00 00 05 17 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 1334272 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#8 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#8 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#8 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#8 CDB: Read(10) 28 00 00 05 17 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 1334272 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: Buffer I/O error on dev sr0, logical block 166784, async page read Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#9 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#9 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#9 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#9 CDB: Read(10) 28 00 00 00 00 00 00 00 04 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 0 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 2 Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#10 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#10 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#10 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#10 CDB: Read(10) 28 00 00 00 00 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: Buffer I/O error on dev sr0, logical block 0, async page read Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#23 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#23 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#23 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#23 CDB: Read(10) 28 00 00 05 17 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 1334272 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#31 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#31 Sense Key : Illegal Request [current] Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#31 Add. Sense: Illegal mode for this track Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: [sr0] tag#31 CDB: Read(10) 28 00 00 05 17 00 00 00 02 00 Oct 02 21:13:21 volumio kernel: I/O error, dev sr0, sector 1334272 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Oct 02 21:13:21 volumio kernel: Buffer I/O error on dev sr0, logical block 166784, async page read Oct 02 21:13:21 volumio kernel: Buffer I/O error on dev sr0, logical block 0, async page read Oct 02 21:13:21 volumio kernel: FAT-fs (nvme0n1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 02 21:13:21 volumio kernel: FAT-fs (nvme0n1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 02 21:13:21 volumio kernel: EXT4-fs (nvme0n1p2): recovery complete Oct 02 21:13:21 volumio kernel: EXT4-fs (nvme0n1p2): mounted filesystem ff9f55eb-0a9d-4451-8a94-4e45a1441048 r/w with ordered data mode. Quota mode: none. Oct 02 21:13:21 volumio kernel: loop: module loaded Oct 02 21:13:21 volumio kernel: block device autoloading is deprecated and will be removed. Oct 02 21:13:21 volumio kernel: loop0: detected capacity change from 0 to 2331568 Oct 02 21:13:21 volumio kernel: EXT4-fs (nvme0n1p3): 2 orphan inodes deleted Oct 02 21:13:21 volumio kernel: EXT4-fs (nvme0n1p3): recovery complete Oct 02 21:13:21 volumio kernel: EXT4-fs (nvme0n1p3): mounted filesystem 2cd2a181-641d-4576-a422-b6ec7752f6d5 r/w with ordered data mode. Quota mode: none. Oct 02 21:13:21 volumio systemd[1]: Inserted module 'autofs4' Oct 02 21:13:21 volumio systemd[1]: systemd 252.39-1~deb12u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 02 21:13:21 volumio systemd[1]: Detected architecture x86-64. Oct 02 21:13:21 volumio systemd[1]: Hostname set to . Oct 02 21:13:21 volumio systemd-gpt-auto-generator[565]: EFI loader partition unknown, exiting. Oct 02 21:13:21 volumio systemd-gpt-auto-generator[565]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Oct 02 21:13:21 volumio systemd[1]: Queued start job for default target graphical.target. Oct 02 21:13:21 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 02 21:13:21 volumio systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 02 21:13:21 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 02 21:13:21 volumio systemd[1]: Created slice user.slice - User and Session Slice. Oct 02 21:13:21 volumio systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 02 21:13:21 volumio systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 02 21:13:21 volumio systemd[1]: Expecting device dev-disk-by\x2duuid-99F5\x2dAE32.device - /dev/disk/by-uuid/99F5-AE32... Oct 02 21:13:21 volumio systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 02 21:13:21 volumio systemd[1]: Reached target slices.target - Slice Units. Oct 02 21:13:21 volumio systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 02 21:13:21 volumio systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Oct 02 21:13:21 volumio systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Oct 02 21:13:21 volumio systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Oct 02 21:13:21 volumio systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Oct 02 21:13:21 volumio systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 02 21:13:21 volumio systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 02 21:13:21 volumio systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 02 21:13:21 volumio systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 02 21:13:21 volumio systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 02 21:13:21 volumio systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 02 21:13:21 volumio systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 02 21:13:21 volumio systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 02 21:13:21 volumio systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Oct 02 21:13:21 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 02 21:13:21 volumio systemd[1]: Reached target swap.target - Swaps. Oct 02 21:13:21 volumio systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Oct 02 21:13:21 volumio systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 02 21:13:21 volumio systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 02 21:13:21 volumio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 02 21:13:21 volumio systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 02 21:13:21 volumio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 02 21:13:21 volumio systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 02 21:13:21 volumio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 02 21:13:21 volumio systemd[1]: Starting systemd-journald.service - Journal Service... Oct 02 21:13:21 volumio kernel: pstore: Using crash dump compression: deflate Oct 02 21:13:21 volumio systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 02 21:13:21 volumio systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 02 21:13:21 volumio systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 02 21:13:21 volumio systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 02 21:13:21 volumio systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 02 21:13:21 volumio systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 02 21:13:21 volumio systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 02 21:13:21 volumio systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 02 21:13:21 volumio systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 02 21:13:21 volumio systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 02 21:13:21 volumio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 02 21:13:21 volumio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 02 21:13:21 volumio systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 02 21:13:21 volumio systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 02 21:13:21 volumio systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Oct 02 21:13:21 volumio systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 02 21:13:21 volumio systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 02 21:13:21 volumio systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 02 21:13:21 volumio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 02 21:13:21 volumio systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 02 21:13:21 volumio systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 02 21:13:21 volumio kernel: pstore: Registered efi_pstore as persistent store backend Oct 02 21:13:21 volumio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 02 21:13:21 volumio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 02 21:13:21 volumio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 02 21:13:21 volumio systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 02 21:13:21 volumio systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 02 21:13:21 volumio systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 02 21:13:21 volumio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 02 21:13:21 volumio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 02 21:13:21 volumio systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Oct 02 21:13:21 volumio systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 02 21:13:21 volumio systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 02 21:13:21 volumio systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 02 21:13:21 volumio systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Oct 02 21:13:21 volumio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 02 21:13:21 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 02 21:13:21 volumio systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 02 21:13:21 volumio systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 02 21:13:21 volumio systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 02 21:13:21 volumio systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 02 21:13:21 volumio systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 02 21:13:21 volumio systemd[1]: Set up automount mnt-raid.automount. Oct 02 21:13:21 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 02 21:13:21 volumio systemd[1]: Mounting tmp.mount - /tmp... Oct 02 21:13:21 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 02 21:13:21 volumio systemd[1]: Mounting var-log.mount - /var/log... Oct 02 21:13:21 volumio systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Oct 02 21:13:21 volumio systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 02 21:13:21 volumio systemd[1]: Mounted tmp.mount - /tmp. Oct 02 21:13:21 volumio systemd[1]: Mounted var-log.mount - /var/log. Oct 02 21:13:21 volumio systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Oct 02 21:13:21 volumio systemd-journald[592]: Journal started Oct 02 21:13:21 volumio systemd-journald[592]: Runtime Journal (/run/log/journal/30175a60cf76629f74f78d9769c2cd99) is 3.7M, max 30.0M, 26.2M free. Oct 02 21:13:21 volumio fake-hwclock[583]: Current system time: 2026-10-02 19:13:21 Oct 02 21:13:21 volumio fake-hwclock[583]: fake-hwclock saved clock information is in the past: 2026-09-21 19:57:28 Oct 02 21:13:21 volumio fake-hwclock[583]: To set system time to this saved clock anyway, use "force" Oct 02 21:13:21 volumio systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 02 21:13:21 volumio systemd[1]: Started systemd-journald.service - Journal Service. Oct 02 21:13:21 volumio systemd-journald[592]: Runtime Journal (/run/log/journal/30175a60cf76629f74f78d9769c2cd99) is 3.7M, max 30.0M, 26.2M free. Oct 02 21:13:21 volumio systemd-journald[592]: Received client request to flush runtime journal. Oct 02 21:13:21 volumio systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 02 21:13:21 volumio systemd-udevd[618]: Using default interface naming scheme 'v252'. Oct 02 21:13:21 volumio systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 02 21:13:21 volumio systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 02 21:13:21 volumio systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Oct 02 21:13:21 volumio systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Oct 02 21:13:21 volumio (udev-worker)[647]: Network interface NamePolicy= disabled on kernel command line. Oct 02 21:13:21 volumio kernel: sr 0:0:0:0: Attached scsi generic sg0 type 5 Oct 02 21:13:21 volumio systemd[1]: Found device dev-disk-by\x2duuid-99F5\x2dAE32.device - WDC PC SN530 SDBPNPZ-256G-1002 boot. Oct 02 21:13:21 volumio systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-99F5\x2dAE32.service - File System Check on /dev/disk/by-uuid/99F5-AE32... Oct 02 21:13:21 volumio systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Oct 02 21:13:21 volumio systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Oct 02 21:13:21 volumio kernel: intel_pmc_core INT33A1:00: initialized Oct 02 21:13:21 volumio systemd-fsck[671]: fsck.fat 4.2 (2021-01-31) Oct 02 21:13:21 volumio systemd-fsck[671]: /dev/nvme0n1p1: 20 files, 23297/93500 clusters Oct 02 21:13:21 volumio systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-99F5\x2dAE32.service - File System Check on /dev/disk/by-uuid/99F5-AE32. Oct 02 21:13:21 volumio systemd[1]: Found device sys-subsystem-net-devices-eth0.device - Ethernet Connection (2) I219-LM. Oct 02 21:13:21 volumio kernel: tpm_tis 00:08: 1.2 TPM (device-id 0xFE, rev-id 2) Oct 02 21:13:21 volumio kernel: mei_me 0000:00:16.0: enabling device (0000 -> 0002) Oct 02 21:13:21 volumio kernel: cryptd: max_cpu_qlen set to 1000 Oct 02 21:13:22 volumio kernel: AES CTR mode by8 optimization enabled Oct 02 21:13:22 volumio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 02 21:13:22 volumio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 02 21:13:22 volumio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 02 21:13:22 volumio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 02 21:13:22 volumio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 02 21:13:22 volumio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 02 21:13:22 volumio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 02 21:13:22 volumio systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 02 21:13:22 volumio systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 02 21:13:22 volumio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 02 21:13:22 volumio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 02 21:13:22 volumio kernel: usb 1-2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 02 21:13:22 volumio kernel: usb 1-2: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Oct 02 21:13:22 volumio kernel: usb 1-2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 02 21:13:22 volumio kernel: usb 1-2: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Oct 02 21:13:22 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 02 21:13:22 volumio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 02 21:13:22 volumio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 02 21:13:22 volumio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 02 21:13:22 volumio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 02 21:13:22 volumio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 02 21:13:22 volumio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 02 21:13:22 volumio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 02 21:13:22 volumio systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 02 21:13:22 volumio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 02 21:13:22 volumio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 02 21:13:22 volumio (udev-worker)[649]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Oct 02 21:13:22 volumio systemd[1]: Mounting boot.mount - /boot... Oct 02 21:13:22 volumio systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Oct 02 21:13:22 volumio systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Oct 02 21:13:22 volumio kernel: FAT-fs (nvme0n1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 02 21:13:22 volumio systemd[1]: Mounted boot.mount - /boot. Oct 02 21:13:22 volumio systemd[1]: Reached target local-fs.target - Local File Systems. Oct 02 21:13:22 volumio systemd[1]: Started ifup@eth0.service - ifup for eth0. Oct 02 21:13:22 volumio systemd[1]: Starting networking.service - Raise network interfaces... Oct 02 21:13:22 volumio systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Oct 02 21:13:22 volumio systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Oct 02 21:13:22 volumio systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Oct 02 21:13:22 volumio systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Oct 02 21:13:22 volumio systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 02 21:13:22 volumio systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 785 (systemd-binfmt) Oct 02 21:13:22 volumio systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Oct 02 21:13:22 volumio systemd[1]: Received SIGRTMIN+20 from PID 455 (plymouthd). Oct 02 21:13:22 volumio systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Oct 02 21:13:22 volumio systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Oct 02 21:13:22 volumio systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Oct 02 21:13:22 volumio systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Oct 02 21:13:22 volumio systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Oct 02 21:13:22 volumio systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 02 21:13:22 volumio systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Oct 02 21:13:22 volumio systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Oct 02 21:13:22 volumio systemd[1]: Starting rpcbind.service - RPC bind portmap service... Oct 02 21:13:22 volumio systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 02 21:13:22 volumio systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Oct 02 21:13:22 volumio systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Oct 02 21:13:22 volumio systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Oct 02 21:13:22 volumio systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 02 21:13:22 volumio systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 02 21:13:22 volumio systemd[1]: Reached target sysinit.target - System Initialization. Oct 02 21:13:22 volumio systemd[1]: Started acpid.path - ACPI Events Check. Oct 02 21:13:22 volumio systemd[1]: Started apt-daily.timer - Daily apt download activities. Oct 02 21:13:22 volumio systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Oct 02 21:13:22 volumio systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Oct 02 21:13:22 volumio systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Oct 02 21:13:22 volumio systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Oct 02 21:13:22 volumio systemd[1]: Started man-db.timer - Daily man-db regeneration. Oct 02 21:13:22 volumio systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Oct 02 21:13:22 volumio systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Oct 02 21:13:22 volumio systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 02 21:13:22 volumio systemd[1]: Reached target paths.target - Path Units. Oct 02 21:13:22 volumio systemd[1]: Reached target timers.target - Timer Units. Oct 02 21:13:22 volumio systemd[1]: Listening on acpid.socket - ACPID Listen Socket. Oct 02 21:13:22 volumio systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Oct 02 21:13:22 volumio systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 02 21:13:22 volumio systemd[1]: Reached target sockets.target - Socket Units. Oct 02 21:13:22 volumio systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Oct 02 21:13:22 volumio systemd[1]: Reached target basic.target - Basic System. Oct 02 21:13:22 volumio systemd[1]: Started acpid.service - ACPI event daemon. Oct 02 21:13:22 volumio systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Oct 02 21:13:22 volumio systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Oct 02 21:13:22 volumio acpid[815]: starting up with netlink and the input layer Oct 02 21:13:22 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 02 21:13:22 volumio systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 02 21:13:22 volumio systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Oct 02 21:13:22 volumio acpid[815]: 3 rules loaded Oct 02 21:13:22 volumio acpid[815]: waiting for events: event logging is off Oct 02 21:13:22 volumio systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Oct 02 21:13:22 volumio systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Oct 02 21:13:22 volumio systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Oct 02 21:13:22 volumio systemd[1]: Started iptables.service - Volumio Iptables Module. Oct 02 21:13:22 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 02 21:13:22 volumio systemd[1]: Starting systemd-logind.service - User Login Management... Oct 02 21:13:22 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Oct 02 21:13:22 volumio avahi-daemon[817]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 02 21:13:22 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 21:13:22 volumio avahi-daemon[817]: Successfully dropped root privileges. Oct 02 21:13:22 volumio avahi-daemon[817]: avahi-daemon 0.8 starting up. Oct 02 21:13:22 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 02 21:13:22 volumio systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Oct 02 21:13:22 volumio systemd[1]: Starting wireless.service - Wireless Services... Oct 02 21:13:22 volumio systemd[1]: Started rpcbind.service - RPC bind portmap service. Oct 02 21:13:22 volumio systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Oct 02 21:13:22 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 02 21:13:22 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 02 21:13:22 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Oct 02 21:13:22 volumio thd[827]: Unable to parse trigger line: Oct 02 21:13:22 volumio thd[827]: Unable to parse trigger line: Oct 02 21:13:22 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Oct 02 21:13:22 volumio systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Oct 02 21:13:22 volumio systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Oct 02 21:13:22 volumio systemd[1]: Reached target sound.target - Sound Card. Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event0 to thd using --add... Oct 02 21:13:22 volumio sh[798]: command failed: No such device (-19) Oct 02 21:13:22 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 02 21:13:22 volumio kernel: RPC: Registered named UNIX socket transport module. Oct 02 21:13:22 volumio kernel: RPC: Registered udp transport module. Oct 02 21:13:22 volumio kernel: RPC: Registered tcp transport module. Oct 02 21:13:22 volumio kernel: RPC: Registered tcp-with-tls transport module. Oct 02 21:13:22 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 02 21:13:22 volumio ifup[797]: command failed: No such device (-19) Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event1 to thd using --add... Oct 02 21:13:22 volumio systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event10 to thd using --add... Oct 02 21:13:22 volumio systemd[1]: Reached target rpc_pipefs.target. Oct 02 21:13:22 volumio systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Oct 02 21:13:22 volumio systemd[1]: Reached target nfs-client.target - NFS client services. Oct 02 21:13:22 volumio systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 02 21:13:22 volumio systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event2 to thd using --add... Oct 02 21:13:22 volumio systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Oct 02 21:13:22 volumio systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event3 to thd using --add... Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event4 to thd using --add... Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event5 to thd using --add... Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event6 to thd using --add... Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event7 to thd using --add... Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event8 to thd using --add... Oct 02 21:13:22 volumio th-udev-rebind.sh[842]: Rebinding /dev/input/event9 to thd using --add... Oct 02 21:13:22 volumio systemd[1]: th-udev-rebind.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Oct 02 21:13:22 volumio systemd[1]: e2scrub_reap.service: Deactivated successfully. Oct 02 21:13:22 volumio systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Oct 02 21:13:22 volumio haveged[812]: haveged: command socket is listening at fd 3 Oct 02 21:13:22 volumio volumio-remote-updater[830]: Volumio OTA Client: 1.7.0 Oct 02 21:13:22 volumio dhcpcd[820]: dhcpcd-9.4.1 starting Oct 02 21:13:22 volumio sh[878]: dhcpcd-9.4.1 starting Oct 02 21:13:22 volumio dhcpcd[878]: dhcpcd-9.4.1 starting Oct 02 21:13:22 volumio systemd-logind[824]: New seat seat0. Oct 02 21:13:22 volumio ifup[889]: command failed: No such device (-19) Oct 02 21:13:22 volumio volumio-remote-updater[830]: Error: No active session Oct 02 21:13:22 volumio volumio-remote-updater[830]: [2026-10-02 21:13:22] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 02 21:13:22 volumio volumio-remote-updater[830]: [2026-10-02 21:13:22] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 02 21:13:22 volumio volumio-remote-updater[830]: [2026-10-02 21:13:22] [error] handle_connect error: Connection refused Oct 02 21:13:22 volumio systemd-logind[824]: Watching system buttons on /dev/input/event2 (Power Button) Oct 02 21:13:22 volumio systemd-logind[824]: Watching system buttons on /dev/input/event1 (Power Button) Oct 02 21:13:22 volumio systemd-logind[824]: Watching system buttons on /dev/input/event0 (Sleep Button) Oct 02 21:13:22 volumio dhcpcd[899]: DUID 00:04:f6:98:33:cc:27:3e:11:e6:ab:07:74:9b:31:0f:13:00 Oct 02 21:13:22 volumio sh[878]: DUID 00:04:f6:98:33:cc:27:3e:11:e6:ab:07:74:9b:31:0f:13:00 Oct 02 21:13:22 volumio dhcpcd[896]: dev: loaded udev Oct 02 21:13:22 volumio dhcpcd[896]: DUID 00:04:f6:98:33:cc:27:3e:11:e6:ab:07:74:9b:31:0f:13:00 Oct 02 21:13:22 volumio loadcpufreq[853]: Loading cpufreq kernel modules... Oct 02 21:13:22 volumio loadcpufreq[923]: libkmod: ERROR ../libkmod/libkmod-module.c:1998 kmod_module_get_holders: could not open '/sys/module/nf_tables/holders': No such file or directory Oct 02 21:13:22 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 02 21:13:22 volumio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Oct 02 21:13:22 volumio upmpdcli[828]: Could not open config: /tmp/upmpdcli.conf Oct 02 21:13:22 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 21:13:22 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 21:13:22 volumio systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 02 21:13:23 volumio avahi-daemon[817]: Successfully called chroot(). Oct 02 21:13:23 volumio avahi-daemon[817]: Successfully dropped remaining capabilities. Oct 02 21:13:23 volumio avahi-daemon[817]: No service file found in /etc/avahi/services. Oct 02 21:13:23 volumio avahi-daemon[817]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 02 21:13:23 volumio avahi-daemon[817]: New relevant interface lo.IPv4 for mDNS. Oct 02 21:13:23 volumio avahi-daemon[817]: Network interface enumeration completed. Oct 02 21:13:23 volumio avahi-daemon[817]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 02 21:13:23 volumio loadcpufreq[853]: done (acpi-cpufreq). Oct 02 21:13:23 volumio systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Oct 02 21:13:23 volumio systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Oct 02 21:13:23 volumio systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Oct 02 21:13:23 volumio dbus-daemon[818]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 02 21:13:23 volumio systemd[1]: Started systemd-logind.service - User Login Management. Oct 02 21:13:23 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 02 21:13:23 volumio systemd[1]: iptables.service: Deactivated successfully. Oct 02 21:13:23 volumio cpufrequtils[980]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Oct 02 21:13:23 volumio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Oct 02 21:13:23 volumio systemd[1]: Finished networking.service - Raise network interfaces. Oct 02 21:13:23 volumio systemd[1]: Started wpa_supplicant.service - WPA supplicant. Oct 02 21:13:23 volumio wpa_supplicant[938]: Successfully initialized wpa_supplicant Oct 02 21:13:23 volumio systemd[1]: Reached target network.target - Network. Oct 02 21:13:23 volumio systemd[1]: Reached target network-online.target - Network is Online. Oct 02 21:13:23 volumio haveged[812]: haveged: ver: 1.9.14; arch: x86; vend: GenuineIntel; build: (gcc 12.2.0 ITV); collect: 128K Oct 02 21:13:23 volumio haveged[812]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 32010/53875 Oct 02 21:13:23 volumio haveged[812]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99826 Oct 02 21:13:23 volumio haveged[812]: haveged: fills: 0, generated: 0 Oct 02 21:13:23 volumio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Oct 02 21:13:23 volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... Oct 02 21:13:23 volumio systemd[1]: Starting ntpsec.service - Network Time Service... Oct 02 21:13:23 volumio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Oct 02 21:13:23 volumio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Oct 02 21:13:23 volumio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Oct 02 21:13:23 volumio sm-notify[1025]: Version 2.6.2 starting Oct 02 21:13:23 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 02 21:13:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:23 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Oct 02 21:13:23 volumio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 02 21:13:23 volumio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Oct 02 21:13:23 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 21:13:23 volumio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Oct 02 21:13:23 volumio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Oct 02 21:13:23 volumio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 02 21:13:23 volumio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Oct 02 21:13:23 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Oct 02 21:13:23 volumio ntpd[1033]: INIT: ntpd ntpsec-1.2.2: Starting Oct 02 21:13:23 volumio ntpd[1033]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 02 21:13:23 volumio ntp-systemd-wrapper[1033]: 2026-10-02T21:13:23 ntpd[1033]: INIT: ntpd ntpsec-1.2.2: Starting Oct 02 21:13:23 volumio ntp-systemd-wrapper[1033]: 2026-10-02T21:13:23 ntpd[1033]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 02 21:13:23 volumio systemd[1]: Started ntpsec.service - Network Time Service. Oct 02 21:13:23 volumio systemd[1]: Received SIGRTMIN+21 from PID 455 (plymouthd). Oct 02 21:13:23 volumio ntpd[1052]: INIT: precision = 0.134 usec (-23) Oct 02 21:13:23 volumio systemd[1]: Received SIGRTMIN+21 from PID 455 (plymouthd). Oct 02 21:13:23 volumio ifplugd[1019]: Network Interface Plugging Daemon... Oct 02 21:13:23 volumio ifplugd[1055]: error: unexpectedly disconnected from boot status daemon Oct 02 21:13:23 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Oct 02 21:13:23 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 02 21:13:23 volumio ntpd[1052]: INIT: successfully locked into RAM Oct 02 21:13:23 volumio ntpd[1052]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Oct 02 21:13:23 volumio ifplugd(eth0)[1066]: ifplugd 0.28 initializing. Oct 02 21:13:23 volumio ntpd[1052]: CONFIG: restrict nopeer ignored Oct 02 21:13:23 volumio ntpd[1052]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 02 21:13:23 volumio ntpd[1052]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Oct 02 21:13:23 volumio ntpd[1052]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 97 days ago Oct 02 21:13:23 volumio ntpd[1052]: INIT: Using SO_TIMESTAMPNS(ns) Oct 02 21:13:23 volumio ntpd[1052]: IO: Listen and drop on 0 v6wildcard [::]:123 Oct 02 21:13:23 volumio ntpd[1052]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 02 21:13:23 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 02 21:13:23 volumio systemd[1]: Reached target getty.target - Login Prompts. Oct 02 21:13:23 volumio dhcpcd[896]: eth0: waiting for carrier Oct 02 21:13:23 volumio sh[878]: eth0: waiting for carrier Oct 02 21:13:23 volumio dhcpcd[899]: eth0: waiting for carrier Oct 02 21:13:23 volumio ifplugd(eth0)[1066]: Using interface eth0/00:23:24:B3:13:18 with driver (version: 6.12.57-volumio) Oct 02 21:13:23 volumio ifplugd(eth0)[1066]: Using detection mode: SIOCETHTOOL Oct 02 21:13:23 volumio ifplugd(eth0)[1066]: Initialization complete, link beat not detected. Oct 02 21:13:23 volumio ntpd[1052]: IO: Listen normally on 2 lo 127.0.0.1:123 Oct 02 21:13:23 volumio ntpd[1052]: IO: Listening on routing socket on fd #19 for interface updates Oct 02 21:13:23 volumio welcome[1039]: Resolved ip:[0] Oct 02 21:13:23 volumio ntpd[1052]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Oct 02 21:13:23 volumio ntpd[1052]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Oct 02 21:13:23 volumio ntpd[1052]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120 Oct 02 21:13:23 volumio ntpd[1052]: NTSc: Using system default root certificates. Oct 02 21:13:23 volumio sshd[1081]: Server listening on 0.0.0.0 port 22. Oct 02 21:13:23 volumio sshd[1081]: Server listening on :: port 22. Oct 02 21:13:23 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Oct 02 21:13:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 21:13:23 volumio ifplugd[1019]: start eth0...done. Oct 02 21:13:23 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Oct 02 21:13:23 volumio ntpd[1052]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Oct 02 21:13:23 volumio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Oct 02 21:13:23 volumio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Oct 02 21:13:23 volumio wireless.js[831]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Oct 02 21:13:23 volumio wireless.js[831]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Oct 02 21:13:23 volumio wireless.js[831]: WIRELESS.JS - INFO: ERROR: wlan0 does not exist, exiting... Oct 02 21:13:23 volumio systemd[1]: wireless.service: Main process exited, code=exited, status=1/FAILURE Oct 02 21:13:23 volumio systemd[1]: wireless.service: Killing process 1112 (sh) with signal SIGKILL. Oct 02 21:13:23 volumio systemd[1]: wireless.service: Killing process 1113 (pkill) with signal SIGKILL. Oct 02 21:13:23 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Oct 02 21:13:23 volumio systemd[1]: Failed to start wireless.service - Wireless Services. Oct 02 21:13:23 volumio nmbd[1103]: [2026/10/02 21:13:23.610535, 0] ../../source3/nmbd/nmbd.c:901(main) Oct 02 21:13:23 volumio nmbd[1103]: nmbd version 4.17.12-Debian started. Oct 02 21:13:23 volumio nmbd[1103]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 02 21:13:23 volumio nmbd[1103]: [2026/10/02 21:13:23.612430, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 02 21:13:23 volumio nmbd[1103]: started asyncdns process 1115 Oct 02 21:13:23 volumio nmbd[1103]: [2026/10/02 21:13:23.612778, 0] ../../lib/util/become_daemon.c:150(daemon_status) Oct 02 21:13:23 volumio nmbd[1103]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 02 21:13:23 volumio nmbd[1103]: [2026/10/02 21:13:23.612798, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Oct 02 21:13:23 volumio nmbd[1103]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 02 21:13:23 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 02 21:13:23 volumio systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Oct 02 21:13:23 volumio systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Oct 02 21:13:23 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Oct 02 21:13:23 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 02 21:13:23 volumio volumio-cpu-tweak[1126]: Setting RT Priority for mpd Oct 02 21:13:23 volumio volumio-cpu-tweak[1160]: pid 35's current scheduling policy: SCHED_OTHER Oct 02 21:13:23 volumio volumio-cpu-tweak[1160]: pid 35's current scheduling priority: 0 Oct 02 21:13:23 volumio volumio-cpu-tweak[1126]: Setting MPD Affinity Oct 02 21:13:23 volumio volumio-cpu-tweak[1163]: pid 3's current affinity mask: ff Oct 02 21:13:23 volumio volumio-cpu-tweak[1126]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 02 21:13:23 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 02 21:13:23 volumio startx[1192]: X.Org X Server 1.21.1.7 Oct 02 21:13:23 volumio startx[1192]: X Protocol Version 11, Revision 0 Oct 02 21:13:23 volumio startx[1192]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Oct 02 21:13:23 volumio startx[1192]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=ff9f55eb-0a9d-4451-8a94-4e45a1441048 bootpart=UUID=99F5-AE32 datapart=UUID=2cd2a181-641d-4576-a422-b6ec7752f6d5 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no Oct 02 21:13:23 volumio startx[1192]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support) Oct 02 21:13:23 volumio startx[1192]: Current version of pixman: 0.42.2 Oct 02 21:13:23 volumio startx[1192]: Before reporting problems, check http://wiki.x.org Oct 02 21:13:23 volumio startx[1192]: to make sure that you have the latest version. Oct 02 21:13:23 volumio startx[1192]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 02 21:13:23 volumio startx[1192]: (++) from command line, (!!) notice, (II) informational, Oct 02 21:13:23 volumio startx[1192]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 02 21:13:23 volumio startx[1192]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Oct 2 21:13:23 2026 Oct 02 21:13:23 volumio startx[1192]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 02 21:13:23 volumio soundcard-init.sh[1198]: Card 0 Chip Realtek Generic Name HDA Intel PCH Oct 02 21:13:23 volumio soundcard-init.sh[1210]: Simple mixer control 'Master',0 Oct 02 21:13:23 volumio soundcard-init.sh[1210]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Oct 02 21:13:23 volumio soundcard-init.sh[1210]: Playback channels: Mono Oct 02 21:13:23 volumio soundcard-init.sh[1210]: Limits: Playback 0 - 87 Oct 02 21:13:23 volumio soundcard-init.sh[1210]: Mono: Playback 65 [75%] [-16.50dB] [on] Oct 02 21:13:23 volumio soundcard-init.sh[1198]: Card 1 Chip USB Mixer Name BRZHIFI Oct 02 21:13:23 volumio soundcard-init.sh[1198]: Card 5 Chip USB Mixer Name Combo384 Amanero Oct 02 21:13:23 volumio systemd[1]: soundcard-init.service: Deactivated successfully. Oct 02 21:13:23 volumio avahi-daemon[817]: Server startup complete. Host name is volumio.local. Local service cookie is 2914888626. Oct 02 21:13:24 volumio ntpd[1052]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:24 volumio ntpd[1052]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 02 21:13:24 volumio ntpd[1052]: DNS: dns_check: DNS error: -11, System error Oct 02 21:13:24 volumio ntpd[1052]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Oct 02 21:13:24 volumio volumio[1114]: info: ------------------------------------------- Oct 02 21:13:24 volumio volumio[1114]: info: ----- Volumio3 ---- Oct 02 21:13:24 volumio volumio[1114]: info: ------------------------------------------- Oct 02 21:13:24 volumio volumio[1114]: info: ----- System startup ---- Oct 02 21:13:24 volumio volumio[1114]: info: ------------------------------------------- Oct 02 21:13:24 volumio volumio[1114]: info: MYVOLUMIO Environment detected Oct 02 21:13:24 volumio volumio[1114]: info: Plugin folders cleanup Oct 02 21:13:24 volumio volumio[1114]: info: Scanning into folder /volumio/app/plugins/ Oct 02 21:13:24 volumio volumio[1114]: info: Scanning category audio_interface Oct 02 21:13:24 volumio volumio[1114]: info: Scanning category miscellanea Oct 02 21:13:24 volumio volumio[1114]: info: Scanning category music_service Oct 02 21:13:24 volumio volumio[1114]: info: Scanning category plugins.json Oct 02 21:13:24 volumio volumio[1114]: info: Scanning category system_controller Oct 02 21:13:24 volumio volumio[1114]: info: Scanning category user_interface Oct 02 21:13:24 volumio volumio[1114]: info: Scanning into folder /data/plugins/ Oct 02 21:13:24 volumio volumio[1114]: info: Scanning category music_service Oct 02 21:13:24 volumio volumio[1114]: info: Plugin folders cleanup completed Oct 02 21:13:24 volumio volumio[1114]: info: ------------------------------------------- Oct 02 21:13:24 volumio volumio[1114]: info: ----- Core plugins startup ---- Oct 02 21:13:24 volumio volumio[1114]: info: ------------------------------------------- Oct 02 21:13:24 volumio volumio[1114]: info: Loading plugins from folder /volumio/app/plugins/ Oct 02 21:13:24 volumio volumio[1114]: info: Adding plugin upnp to MyMusic Plugins Oct 02 21:13:24 volumio volumio[1114]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 02 21:13:24 volumio volumio[1114]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 02 21:13:24 volumio volumio[1114]: info: Loading plugins from folder /data/plugins/ Oct 02 21:13:24 volumio volumio[1114]: info: Loading plugin "system"... Oct 02 21:13:24 volumio volumio[1114]: info: Loading plugin "appearance"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "network"... Oct 02 21:13:25 volumio volumio[1114]: info: Refreshing Cached IP Addresses Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "services"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "volumio5onboarding"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "alsa_controller"... Oct 02 21:13:25 volumio volumio[1114]: warn: Unable to locate the audio output device Combo384 Amanero. Please configure a valid output device. Oct 02 21:13:25 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "wizard"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "networkfs"... Oct 02 21:13:25 volumio sudo[1337]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 21:13:25 volumio sudo[1344]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 02 21:13:25 volumio sudo[1335]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 21:13:25 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:25 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:25 volumio sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:25 volumio sudo[1335]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:25 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:25 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:25 volumio volumio[1114]: info: Starting Udev Watcher for removable devices Oct 02 21:13:25 volumio sudo[1371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.0.202/dsd /mnt/NAS/dsd Oct 02 21:13:25 volumio sudo[1371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:25 volumio sudo[1373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.202/Jozene /mnt/NAS/Jozene Oct 02 21:13:25 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:25 volumio sudo[1375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.0.202/Lossless /mnt/NAS/Losless Oct 02 21:13:25 volumio sudo[1375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:25 volumio volumio[1114]: info: Ignoring mount for partition: boot Oct 02 21:13:25 volumio volumio[1114]: info: Ignoring mount for partition: volumio Oct 02 21:13:25 volumio volumio[1114]: info: Ignoring mount for partition: volumio_data Oct 02 21:13:25 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "volumio_command_line_client"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "upnp"... Oct 02 21:13:25 volumio volumio[1114]: info: [1790968405228] Starting Upmpd Daemon Oct 02 21:13:25 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "my_music"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "mpd"... Oct 02 21:13:25 volumio kernel: netfs: FS-Cache loaded Oct 02 21:13:25 volumio kernel: Key type dns_resolver registered Oct 02 21:13:25 volumio ntpd[1052]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:25 volumio ntpd[1052]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 02 21:13:25 volumio ntpd[1052]: DNS: dns_check: DNS error: -11, System error Oct 02 21:13:25 volumio ntpd[1052]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Oct 02 21:13:25 volumio sudo[1371]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:25 volumio sudo[1373]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:25 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:25 volumio kernel: Key type cifs.spnego registered Oct 02 21:13:25 volumio kernel: Key type cifs.idmap registered Oct 02 21:13:25 volumio kernel: CIFS: Attempting to mount //192.168.0.202/dsd Oct 02 21:13:25 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 02 21:13:25 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 02 21:13:25 volumio kernel: CIFS: Attempting to mount //192.168.0.202/Lossless Oct 02 21:13:25 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 02 21:13:25 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 02 21:13:25 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 02 21:13:25 volumio kernel: CIFS: Attempting to mount //192.168.0.202/Jozene Oct 02 21:13:25 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 02 21:13:25 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "upnp_browser"... Oct 02 21:13:25 volumio volumio[1114]: info: Starting UPNP Browser Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "alarm-clock"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "airplay_emulation"... Oct 02 21:13:25 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "last_100"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "webradio"... Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "i2s_dacs"... Oct 02 21:13:25 volumio volumio[1114]: info: I2S DAC not set, start Auto-detection Oct 02 21:13:25 volumio volumio[1114]: info: Loading plugin "volumiodiscovery"... Oct 02 21:13:25 volumio volumio[1114]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 21:13:25 volumio volumio[1114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 21:13:25 volumio volumio[1114]: *** WARNING *** For more information see Oct 02 21:13:25 volumio volumio[1114]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 21:13:25 volumio volumio[1114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 21:13:25 volumio volumio[1114]: *** WARNING *** For more information see Oct 02 21:13:25 volumio node[1114]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 21:13:25 volumio node[1114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 21:13:25 volumio node[1114]: *** WARNING *** For more information see Oct 02 21:13:25 volumio node[1114]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 21:13:25 volumio node[1114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 21:13:25 volumio node[1114]: *** WARNING *** For more information see Oct 02 21:13:25 volumio volumio[1114]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 02 21:13:25 volumio volumio[1114]: info: Discovery: Started advertising with name: Volumio Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 21:13:26 volumio volumio[1114]: info: Loading plugin "spop"... Oct 02 21:13:26 volumio volumio[1114]: info: Loading plugin "outputs"... Oct 02 21:13:26 volumio volumio[1114]: info: Loading plugin "albumart"... Oct 02 21:13:26 volumio volumio[1114]: info: Plugin example_plugin is not enabled Oct 02 21:13:26 volumio volumio[1114]: info: Loading plugin "inputs"... Oct 02 21:13:26 volumio volumio[1114]: info: Loading plugin "updater_comm"... Oct 02 21:13:26 volumio volumio[1114]: info: Plugin mpdemulation is not enabled Oct 02 21:13:26 volumio volumio[1114]: info: Loading plugin "rest_api"... Oct 02 21:13:26 volumio volumio[1455]: Forking 7 albumart workers Oct 02 21:13:26 volumio volumio[1114]: info: Loading plugin "websocket"... Oct 02 21:13:26 volumio volumio[1114]: info: Starting Socket.io Server version 1.7.4 Oct 02 21:13:26 volumio volumio[1114]: info: Loading i18n strings for locale hu Oct 02 21:13:26 volumio volumio[1114]: Updating browse sources language Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:26 volumio ntpd[1052]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:26 volumio ntpd[1052]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 02 21:13:26 volumio ntpd[1052]: DNS: dns_check: DNS error: -11, System error Oct 02 21:13:26 volumio ntpd[1052]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Oct 02 21:13:26 volumio volumio[1467]: Starting albumart workers Oct 02 21:13:26 volumio volumio[1484]: Starting albumart workers Oct 02 21:13:26 volumio volumio[1465]: Starting albumart workers Oct 02 21:13:26 volumio volumio[1466]: Starting albumart workers Oct 02 21:13:26 volumio volumio[1469]: Starting albumart workers Oct 02 21:13:26 volumio dbus-daemon[818]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.7' (uid=0 pid=1317 comm="/usr/lib/chromium/chromium --show-component-extens") Oct 02 21:13:26 volumio volumio[1475]: Starting albumart workers Oct 02 21:13:26 volumio volumio[1468]: Starting albumart workers Oct 02 21:13:26 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::initPlayerControls Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: Express server listening on port 3000 Oct 02 21:13:26 volumio volumio[1114]: [Metrics] WebUI: 2s 458.54ms Oct 02 21:13:26 volumio volumio[1114]: info: Setting Device type: x86 Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::resetVolumioState Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::getcurrentVolume Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:26 volumio kernel: Bluetooth: Core ver 2.22 Oct 02 21:13:26 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Oct 02 21:13:26 volumio kernel: Bluetooth: HCI device and connection manager initialized Oct 02 21:13:26 volumio kernel: Bluetooth: HCI socket layer initialized Oct 02 21:13:26 volumio kernel: Bluetooth: L2CAP socket layer initialized Oct 02 21:13:26 volumio kernel: Bluetooth: SCO socket layer initialized Oct 02 21:13:26 volumio volumio[1114]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 02 21:13:26 volumio volumio[1114]: info: Completed loading Core Plugins Oct 02 21:13:26 volumio volumio[1114]: info: Preparing to generate the ALSA configuration file Oct 02 21:13:26 volumio bluetoothd[1542]: Bluetooth daemon 5.83 Oct 02 21:13:26 volumio dbus-daemon[818]: [system] Successfully activated service 'org.bluez' Oct 02 21:13:26 volumio systemd[1]: Started bluetooth.service - Bluetooth service. Oct 02 21:13:26 volumio bluetoothd[1542]: Starting SDP server Oct 02 21:13:26 volumio bluetoothd[1542]: Excluding (cli) sap Oct 02 21:13:26 volumio bluetoothd[1542]: Excluding (cli) scanparam Oct 02 21:13:26 volumio bluetoothd[1542]: Excluding (cli) midi Oct 02 21:13:26 volumio bluetoothd[1542]: Excluding (cli) battery Oct 02 21:13:26 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 02 21:13:26 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 02 21:13:26 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 02 21:13:26 volumio dbus-daemon[818]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=1542 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 02 21:13:26 volumio bluetoothd[1542]: Bluetooth management interface 1.23 initialized Oct 02 21:13:26 volumio volumio[1114]: info: Volumio Network Manager: Network status updated: 0 Oct 02 21:13:26 volumio volumio[1114]: info: Cannot mount NAS Losless at system boot, trial number 1 ,retrying in 5 seconds Oct 02 21:13:26 volumio volumio[1114]: info: Cannot mount NAS Jozene at system boot, trial number 1 ,retrying in 5 seconds Oct 02 21:13:26 volumio volumio[1114]: info: Cannot mount NAS dsd at system boot, trial number 1 ,retrying in 5 seconds Oct 02 21:13:26 volumio volumio[1114]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 02 21:13:26 volumio volumio[1114]: wlan0 Interface doesn't support scanning. Oct 02 21:13:26 volumio volumio[1114]: info: Cannot use regular scanning, forcing with ap-force Oct 02 21:13:26 volumio sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 02 21:13:26 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:26 volumio volumio[1114]: command failed: No such device (-19) Oct 02 21:13:26 volumio volumio[1114]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 02 21:13:26 volumio volumio[1114]: command failed: No such device (-19) Oct 02 21:13:26 volumio volumio[1114]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 02 21:13:26 volumio volumio[1114]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 02 21:13:26 volumio volumio[1114]: info: Reloading queue from file Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::setRepeat null single undefined Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::pushState Oct 02 21:13:26 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::setRandom null Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::pushState Oct 02 21:13:26 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Oct 02 21:13:26 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 02 21:13:26 volumio volumio[1114]: info: VolumeController:: Volume=undefined Mute =false Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::pushState Oct 02 21:13:26 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Oct 02 21:13:26 volumio volumio[1114]: info: CoreStateMachine::updateTrackBlock Oct 02 21:13:26 volumio volumio[1114]: info: CorePlayQueue::getTrackBlock Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 21:13:26 volumio volumio[1114]: info: Asound.conf file written Oct 02 21:13:26 volumio sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:13:26 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:26 volumio volumio[1114]: info: Output device has changed, restarting MPD Oct 02 21:13:26 volumio volumio[1114]: info: Output device has changed, restarting Shairport Sync Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:26 volumio sh[878]: eth0: carrier acquired Oct 02 21:13:26 volumio kernel: e1000e 0000:00:1f.6 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx Oct 02 21:13:26 volumio dhcpcd[899]: eth0: carrier acquired Oct 02 21:13:26 volumio dhcpcd[896]: eth0: carrier acquired Oct 02 21:13:26 volumio sudo[1685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:13:26 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:26 volumio sudo[1687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:13:26 volumio sudo[1687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:13:26 volumio volumio[1114]: info: ___________ START PLUGINS ___________ Oct 02 21:13:26 volumio sh[878]: eth0: IAID 24:b3:13:18 Oct 02 21:13:26 volumio dhcpcd[899]: eth0: IAID 24:b3:13:18 Oct 02 21:13:26 volumio dhcpcd[896]: eth0: IAID 24:b3:13:18 Oct 02 21:13:26 volumio sh[878]: eth0: adding address fe80::3402:7e31:f454:1146 Oct 02 21:13:26 volumio dhcpcd[899]: eth0: adding address fe80::3402:7e31:f454:1146 Oct 02 21:13:26 volumio sh[878]: ipv6_addaddr1: Permission denied Oct 02 21:13:26 volumio dhcpcd[899]: ipv6_addaddr1: Permission denied Oct 02 21:13:26 volumio dhcpcd[896]: eth0: adding address fe80::3402:7e31:f454:1146 Oct 02 21:13:26 volumio dhcpcd[896]: ipv6_addaddr1: Permission denied Oct 02 21:13:26 volumio volumio[1114]: info: ControllerMpd::onStart: Initializing MPD Oct 02 21:13:26 volumio volumio[1114]: info: Creating MPD Configuration file Oct 02 21:13:26 volumio dbus-daemon[818]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 02 21:13:26 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 21:13:26 volumio sudo[1699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 02 21:13:26 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio volumio[1114]: info: [1790968406772] CoreMusicLibrary::Adding element Médiaszerverek Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:26 volumio volumio[1114]: info: UPNP Browser: Client initialized successfully Oct 02 21:13:26 volumio sudo[1701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:13:26 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:26 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:26 volumio sudo[1703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:13:26 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 21:13:26 volumio volumio[1114]: info: [1790968406792] CoreMusicLibrary::Adding element Last_100 Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 21:13:26 volumio volumio[1114]: info: [1790968406793] CoreMusicLibrary::Adding element Webradio Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:13:26 volumio volumio[1114]: info: Initializing BBC Radios Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:13:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:13:26 volumio volumio[1114]: info: Creating Spotify config file Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: Volumio Calling Home Oct 02 21:13:26 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 21:13:26 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:26 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:13:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:13:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:13:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:13:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:13:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:13:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:13:26 volumio sudo[1735]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:13:26 volumio sudo[1735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:13:26 volumio sudo[1743]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 02 21:13:26 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:26 volumio volumio[1114]: Unhandled rejection Error: No sockets available, cannot start. Oct 02 21:13:26 volumio volumio[1114]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 02 21:13:26 volumio volumio[1114]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 02 21:13:26 volumio volumio[1114]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 02 21:13:26 volumio volumio[1114]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 02 21:13:26 volumio volumio[1114]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 02 21:13:26 volumio volumio[1114]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 02 21:13:26 volumio volumio[1114]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 02 21:13:26 volumio volumio[1114]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 02 21:13:26 volumio volumio[1114]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Oct 02 21:13:26 volumio volumio[1114]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 02 21:13:26 volumio volumio[1114]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 02 21:13:26 volumio volumio[1114]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 02 21:13:26 volumio volumio[1114]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 02 21:13:26 volumio volumio[1114]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 02 21:13:26 volumio volumio[1114]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 02 21:13:26 volumio volumio[1114]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 02 21:13:26 volumio volumio[1114]: info: MPD Permissions set Oct 02 21:13:26 volumio volumio[1114]: info: MPD Permissions set Oct 02 21:13:26 volumio volumio[1114]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 02 21:13:26 volumio volumio[1114]: info: Spotify config file written Oct 02 21:13:26 volumio sudo[1755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 02 21:13:26 volumio sudo[1755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:26 volumio volumio[1114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio 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. Oct 02 21:13:26 volumio 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. Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:13:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 21:13:26 volumio go-librespot[1763]: go-librespot daemon starting... Oct 02 21:13:26 volumio sudo[1755]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:13:26 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:26 volumio volumio[1114]: info: No need to fix Spotify hosts Oct 02 21:13:26 volumio go-librespot[1765]: time="2026-10-02T21:13:26+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 21:13:26 volumio go-librespot[1765]: time="2026-10-02T21:13:26+02:00" level=debug msg="app state loaded" Oct 02 21:13:26 volumio go-librespot[1765]: time="2026-10-02T21:13:26+02:00" level=debug msg="stored credentials not found" Oct 02 21:13:27 volumio volumio[1114]: info: VolumeController:: Volume=undefined Mute =false Oct 02 21:13:27 volumio volumio[1114]: info: CoreStateMachine::pushState Oct 02 21:13:27 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Oct 02 21:13:27 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:13:27 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:13:27 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:13:27 volumio go-librespot[1765]: time="2026-10-02T21:13:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 21:13:27 volumio go-librespot[1765]: time="2026-10-02T21:13:27+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 02 21:13:27 volumio volumio[1114]: info: Discovery: adding a7a94d0c-fb28-4f1e-b590-af0237022916 Oct 02 21:13:27 volumio volumio[1114]: info: Discovery: Found device Volumio Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:27 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 21:13:27 volumio sudo[1789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:13:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 21:13:27 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:27 volumio sudo[1785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:13:27 volumio sudo[1785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:27 volumio sudo[1787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:13:27 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:27 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:27 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.033+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 02 21:13:27 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:13:27 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:27 volumio systemd[1]: shairport-sync.service: Consumed 1.744s CPU time. Oct 02 21:13:27 volumio volumio[1114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 02 21:13:27 volumio volumio[1114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.051+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.051+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.051+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.052+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.052+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.052+02:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Oct 02 21:13:27 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:27 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:27 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.055+02:00 level=INFO msg="system info for 3a79d105ba25075006d85a6a88dee0e4" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.056+02:00 level=INFO msg="bootstrapping state" hasInternet=false Oct 02 21:13:27 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:27.058+02:00 level=INFO msg="enabling local network discovery" Oct 02 21:13:27 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:27 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:27 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:27 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:27 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 21:13:27 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 21:13:27 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:13:27 volumio sudo[1785]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:27 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:27 volumio volumio[1114]: Error adding Membership: Error: addMembership EINVAL Oct 02 21:13:27 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:13:27 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:13:27 volumio dhcpcd[899]: eth0: soliciting a DHCP lease Oct 02 21:13:27 volumio sh[878]: eth0: soliciting a DHCP lease Oct 02 21:13:27 volumio dhcpcd[899]: eth0: offered 192.168.0.204 from 192.168.0.1 Oct 02 21:13:27 volumio sh[878]: eth0: offered 192.168.0.204 from 192.168.0.1 Oct 02 21:13:27 volumio dhcpcd[899]: eth0: probing address 192.168.0.204/24 Oct 02 21:13:27 volumio sh[878]: eth0: probing address 192.168.0.204/24 Oct 02 21:13:27 volumio ifplugd(eth0)[1066]: Link beat detected. Oct 02 21:13:27 volumio ntpd[1052]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:27 volumio ntpd[1052]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 02 21:13:27 volumio ntpd[1052]: DNS: dns_check: DNS error: -11, System error Oct 02 21:13:27 volumio ntpd[1052]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Oct 02 21:13:27 volumio dhcpcd[896]: eth0: soliciting a DHCP lease Oct 02 21:13:27 volumio dhcpcd[896]: eth0: offered 192.168.0.204 from 192.168.0.1 Oct 02 21:13:27 volumio dhcpcd[896]: eth0: probing address 192.168.0.204/24 Oct 02 21:13:27 volumio ifplugd(eth0)[1066]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 02 21:13:27 volumio dhcpcd[896]: eth0: soliciting an IPv6 router Oct 02 21:13:27 volumio ifplugd(eth0)[1066]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 02 21:13:27 volumio sh[878]: eth0: soliciting an IPv6 router Oct 02 21:13:27 volumio dhcpcd[899]: eth0: soliciting an IPv6 router Oct 02 21:13:27 volumio volumio-remote-updater[830]: [2026-10-02 21:13:27] [connect] Successful connection Oct 02 21:13:27 volumio volumio-remote-updater[830]: [2026-10-02 21:13:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1790968407 101 Oct 02 21:13:27 volumio volumio[1114]: 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: 3 Oct 02 21:13:27 volumio volumio[1114]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 02 21:13:28 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:28.030+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 21:13:28 volumio mpd[1744]: 2026-10-02T21:13:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 21:13:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 21:13:28 volumio sudo[1687]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:28 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:28 volumio volumio[1114]: info: Completed starting Core Plugins Oct 02 21:13:28 volumio volumio[1114]: info: ------------------------------------------- Oct 02 21:13:28 volumio volumio[1114]: info: ----- MyVolumio plugins startup ---- Oct 02 21:13:28 volumio volumio[1114]: info: ------------------------------------------- Oct 02 21:13:28 volumio volumio[1114]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 02 21:13:28 volumio volumio[1114]: error: MPD error: The expression evaluated to a falsy value: Oct 02 21:13:28 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:13:28 volumio volumio[1114]: error: The expression evaluated to a falsy value: Oct 02 21:13:28 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:13:28 volumio volumio[1114]: error: updateQueue error: null Oct 02 21:13:28 volumio volumio[1114]: info: MPD running with PID1744 Oct 02 21:13:28 volumio volumio[1114]: ,establishing connection Oct 02 21:13:28 volumio volumio[1114]: error: updateQueue error: null Oct 02 21:13:28 volumio volumio[1114]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:28 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:28 volumio volumio[1114]: info: Listing playlists Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 21:13:28 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 21:13:28 volumio bash[1032]: setdatetime-helper: all HTTPS Date fallbacks failed Oct 02 21:13:28 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 21:13:28 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 21:13:29 volumio volumio[1114]: info: VolumeController::SetAlsaVolume0 Oct 02 21:13:29 volumio volumio[1114]: info: CoreStateMachine::pushState Oct 02 21:13:29 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:29 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:13:29 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Oct 02 21:13:29 volumio volumio[1114]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 02 21:13:30 volumio volumio[1114]: info: go-librespot daemon successfully initialized Oct 02 21:13:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 02 21:13:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 21:13:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 21:13:30 volumio go-librespot[1922]: go-librespot daemon starting... Oct 02 21:13:30 volumio go-librespot[1923]: time="2026-10-02T21:13:30+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 21:13:30 volumio go-librespot[1923]: time="2026-10-02T21:13:30+02:00" level=debug msg="app state loaded" Oct 02 21:13:30 volumio go-librespot[1923]: time="2026-10-02T21:13:30+02:00" level=debug msg="stored credentials not found" Oct 02 21:13:30 volumio go-librespot[1923]: time="2026-10-02T21:13:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 21:13:30 volumio go-librespot[1923]: time="2026-10-02T21:13:30+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 02 21:13:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 21:13:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 21:13:30 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 21:13:30 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:30 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:30 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:30 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:30 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:30 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:30 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:13:31 volumio sudo[1935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.0.202/Lossless /mnt/NAS/Losless Oct 02 21:13:31 volumio sudo[1935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:31 volumio sudo[1937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.202/Jozene /mnt/NAS/Jozene Oct 02 21:13:31 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:31 volumio sudo[1939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.0.202/dsd /mnt/NAS/dsd Oct 02 21:13:31 volumio sudo[1935]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:31 volumio sudo[1939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:31 volumio kernel: CIFS: Attempting to mount //192.168.0.202/Lossless Oct 02 21:13:31 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 02 21:13:31 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 02 21:13:31 volumio volumio[1114]: info: Cannot mount NAS Losless at system boot, trial number 2 ,retrying in 5 seconds Oct 02 21:13:31 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:31 volumio volumio[1114]: info: Cannot mount NAS Jozene at system boot, trial number 2 ,retrying in 5 seconds Oct 02 21:13:31 volumio sudo[1939]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:31 volumio kernel: CIFS: Attempting to mount //192.168.0.202/Jozene Oct 02 21:13:31 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 02 21:13:31 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 02 21:13:31 volumio kernel: CIFS: Attempting to mount //192.168.0.202/dsd Oct 02 21:13:31 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 02 21:13:31 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 02 21:13:31 volumio volumio[1114]: info: Cannot mount NAS dsd at system boot, trial number 2 ,retrying in 5 seconds Oct 02 21:13:32 volumio dhcpcd[899]: eth0: leased 192.168.0.204 for 43200 seconds Oct 02 21:13:32 volumio sh[878]: eth0: leased 192.168.0.204 for 43200 seconds Oct 02 21:13:32 volumio sh[878]: eth0: adding route to 192.168.0.0/24 Oct 02 21:13:32 volumio avahi-daemon[817]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.204. Oct 02 21:13:32 volumio sh[878]: eth0: adding default route via 192.168.0.1 Oct 02 21:13:32 volumio avahi-daemon[817]: New relevant interface eth0.IPv4 for mDNS. Oct 02 21:13:32 volumio avahi-daemon[817]: Registering new address record for 192.168.0.204 on eth0.IPv4. Oct 02 21:13:32 volumio dhcpcd[899]: eth0: adding route to 192.168.0.0/24 Oct 02 21:13:32 volumio dhcpcd[899]: eth0: adding default route via 192.168.0.1 Oct 02 21:13:32 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 02 21:13:32 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 21:13:32 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 21:13:32 volumio sh[878]: forked to background, child pid 895 Oct 02 21:13:32 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 21:13:32 volumio welcome[1963]: Resolved ip:[1] 192.168.0.204 Oct 02 21:13:32 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 21:13:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 21:13:32 volumio ifplugd(eth0)[1066]: client: ifup: interface eth0 already configured Oct 02 21:13:32 volumio sh[2003]: eth0=eth0 Oct 02 21:13:32 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Oct 02 21:13:32 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Oct 02 21:13:32 volumio dhcpcd[896]: eth0: leased 192.168.0.204 for 43200 seconds Oct 02 21:13:32 volumio dhcpcd[896]: eth0: adding route to 192.168.0.0/24 Oct 02 21:13:32 volumio dhcpcd[896]: eth0: adding default route via 192.168.0.1 Oct 02 21:13:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 21:13:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 02 21:13:32 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 02 21:13:32 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 21:13:32 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 21:13:32 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 21:13:32 volumio welcome[2020]: Resolved ip:[1] 192.168.0.204 Oct 02 21:13:32 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 21:13:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 21:13:32 volumio ifplugd(eth0)[1066]: Program executed successfully. Oct 02 21:13:32 volumio winbindd[2032]: [2026/10/02 21:13:32.208915, 0] ../../source3/winbindd/winbindd.c:1440(main) Oct 02 21:13:32 volumio winbindd[2032]: winbindd version 4.17.12-Debian started. Oct 02 21:13:32 volumio winbindd[2032]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 02 21:13:32 volumio winbindd[2032]: [2026/10/02 21:13:32.212494, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Oct 02 21:13:32 volumio winbindd[2032]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 02 21:13:32 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Oct 02 21:13:32 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Oct 02 21:13:32 volumio smbd[2048]: [2026/10/02 21:13:32.335401, 0] ../../source3/smbd/server.c:1741(main) Oct 02 21:13:32 volumio smbd[2048]: smbd version 4.17.12-Debian started. Oct 02 21:13:32 volumio smbd[2048]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 02 21:13:32 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 02 21:13:32 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Oct 02 21:13:32 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Oct 02 21:13:32 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 02 21:13:32 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 02 21:13:32 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 02 21:13:32 volumio systemd[1]: Startup finished in 13.092s (firmware) + 3.355s (loader) + 14.176s (kernel) + 11.082s (userspace) = 41.706s. Oct 02 21:13:32 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:32 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:32 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:32 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:32 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:32 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:32 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:13:32 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 21:13:32 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 21:13:32 volumio volumio[1114]: info: Discovery: this is already registered, a7a94d0c-fb28-4f1e-b590-af0237022916 Oct 02 21:13:32 volumio volumio[1114]: info: Discovery: Found device Volumio Oct 02 21:13:32 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:32 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:33 volumio volumio[1114]: info: Initializing connection to go-librespot Websocket Oct 02 21:13:33 volumio volumio[1114]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 21:13:33 volumio volumio[1114]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 02 21:13:33 volumio ntpd[1052]: IO: Listen normally on 3 eth0 192.168.0.204:123 Oct 02 21:13:33 volumio ntpd[1052]: IO: new interface(s) found: waking up resolver Oct 02 21:13:33 volumio ntpd[1052]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 02 21:13:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 21:13:33 volumio ntpd[1052]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 02 21:13:33 volumio ntpd[1052]: DNS: Pool taking: 193.41.47.85 Oct 02 21:13:33 volumio ntpd[1052]: DNS: Pool taking: 162.159.200.1 Oct 02 21:13:33 volumio ntpd[1052]: DNS: Pool taking: 82.141.152.3 Oct 02 21:13:33 volumio ntpd[1052]: DNS: Pool taking: 188.156.114.7 Oct 02 21:13:33 volumio ntpd[1052]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 02 21:13:33 volumio volumio5-onboarding[1732]: time=2026-10-02T21:13:33.406+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 21:13:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 21:13:33 volumio go-librespot[2063]: go-librespot daemon starting... Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=debug msg="app state loaded" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=debug msg="stored credentials not found" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=info msg="zeroconf server listening on port 40441" Oct 02 21:13:33 volumio go-librespot[2064]: time="2026-10-02T21:13:33+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 21:13:34 volumio ntpd[1052]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:34 volumio ntpd[1052]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool skipping: 82.141.152.3 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool taking: 193.41.47.84 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool taking: 195.228.75.149 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool taking: 193.227.197.2 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool taking: 2a01:270:9939::1 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool taking: 2606:4700:f1::1 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool taking: 2001:ac8:26:62:0:1:0:14 Oct 02 21:13:34 volumio ntpd[1052]: DNS: Pool taking: 2a05:5502:12:11::123 Oct 02 21:13:34 volumio ntpd[1052]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 02 21:13:35 volumio volumio[1114]: info: Volumio Network Manager: Network status updated: 1 Oct 02 21:13:35 volumio sudo[2094]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 21:13:35 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:35 volumio sudo[2096]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 21:13:35 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:35 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:35 volumio sudo[2098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 02 21:13:35 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:35 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:35 volumio ntpd[1052]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:35 volumio ntpd[1052]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 02 21:13:35 volumio ntpd[1052]: DNS: Pool taking: 37.221.209.57 Oct 02 21:13:35 volumio ntpd[1052]: DNS: Pool skipping: 193.41.47.84 Oct 02 21:13:35 volumio ntpd[1052]: DNS: Pool taking: 37.221.209.24 Oct 02 21:13:35 volumio ntpd[1052]: DNS: Pool taking: 162.159.200.123 Oct 02 21:13:35 volumio ntpd[1052]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 02 21:13:35 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 21:13:35 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:35 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:35 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:35 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:35 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:35 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:35 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:13:36 volumio volumio[1114]: info: Initializing connection to go-librespot Websocket Oct 02 21:13:36 volumio go-librespot[2064]: time="2026-10-02T21:13:36+02:00" level=debug msg="new websocket client" Oct 02 21:13:36 volumio volumio[1114]: info: Connection to go-librespot Websocket established Oct 02 21:13:36 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 02 21:13:36 volumio volumio[1114]: info: Adding plugin bluetooth to MyMusic Plugins Oct 02 21:13:36 volumio volumio[1114]: info: Adding plugin multiroom to MyMusic Plugins Oct 02 21:13:36 volumio volumio[1114]: info: Adding plugin metavolumio to MyMusic Plugins Oct 02 21:13:36 volumio volumio[1114]: info: Adding plugin cd_controller to MyMusic Plugins Oct 02 21:13:36 volumio volumio[1114]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 02 21:13:36 volumio volumio[1114]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 02 21:13:36 volumio volumio[1114]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 02 21:13:36 volumio ntpd[1052]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 21:13:36 volumio ntpd[1052]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 02 21:13:36 volumio ntpd[1052]: DNS: Pool taking: 195.111.92.55 Oct 02 21:13:36 volumio ntpd[1052]: DNS: Pool taking: 81.0.124.200 Oct 02 21:13:36 volumio ntpd[1052]: DNS: Pool taking: 92.249.148.253 Oct 02 21:13:36 volumio ntpd[1052]: DNS: Pool skipping: 162.159.200.123 Oct 02 21:13:36 volumio ntpd[1052]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 02 21:13:36 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:36 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:36 volumio volumio[1114]: info: Starting MyVolumio Remote Streaming Endpoints Oct 02 21:13:36 volumio volumio[1114]: info: MyVolumio login type: Token Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 02 21:13:36 volumio volumio[1114]: info: Streaming services startup Oct 02 21:13:36 volumio volumio[1114]: info: Starting Streaming Daemon Oct 02 21:13:36 volumio volumio[1114]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 02 21:13:36 volumio sudo[2109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 21:13:36 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:36 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:36 volumio sudo[2116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.0.202/Lossless /mnt/NAS/Losless Oct 02 21:13:36 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:36 volumio sudo[2118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.202/Jozene /mnt/NAS/Jozene Oct 02 21:13:36 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:36 volumio volumio[1114]: error: Cannot start Volumio Streaming Daemon Oct 02 21:13:36 volumio sudo[2120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hori,password=x,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.0.202/dsd /mnt/NAS/dsd Oct 02 21:13:36 volumio volumio[1114]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 21:13:36 volumio volumio[1114]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 21:13:36 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:36 volumio kernel: CIFS: Attempting to mount //192.168.0.202/Lossless Oct 02 21:13:36 volumio volumio[1114]: error: MyVolumio Custom Token format not valid, refreshing it Oct 02 21:13:36 volumio volumio[1114]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.119&uuid=3a79d105ba25075006d85a6a88dee0e4" http://updates.volumio.org/downloader-v1/track-device Oct 02 21:13:36 volumio volumio[1114]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 02 21:13:36 volumio volumio[1114]: Dload Upload Total Spent Left Speed Oct 02 21:13:36 volumio volumio[1114]: [132B blob data] Oct 02 21:13:36 volumio volumio[1114]: retrying in 5 seconds, trial 0 Oct 02 21:13:36 volumio volumio[1114]: info: Volumio Calling Home Oct 02 21:13:36 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:36 volumio kernel: CIFS: Attempting to mount //192.168.0.202/Jozene Oct 02 21:13:37 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:37 volumio kernel: CIFS: Attempting to mount //192.168.0.202/dsd Oct 02 21:13:37 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:37 volumio volumio[1114]: info: Volumio called home Oct 02 21:13:37 volumio volumio[1114]: info: MyVolumio login type: Token Oct 02 21:13:37 volumio volumio[1114]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 02 21:13:38 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 02 21:13:38 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 21:13:38 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 21:13:38 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio volumio[1114]: info: Upmpdcli Daemon Started Oct 02 21:13:38 volumio volumio[1114]: info: MyVolumio token set successfully Oct 02 21:13:38 volumio volumio[1114]: info: MYVOLUMIO: Adding device Oct 02 21:13:38 volumio volumio[1114]: info: MYVOLUMIO: Evaluating Server Oct 02 21:13:38 volumio volumio[1114]: info: MyVolumio Plan changed: premium Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 02 21:13:38 volumio volumio[1114]: info: Removing browser output: myVolumio user plan is not superstar Oct 02 21:13:38 volumio volumio[1114]: info: Removing audio output: Oct 02 21:13:38 volumio volumio[1114]: info: MYVOLUMIO: Adding device Oct 02 21:13:38 volumio volumio[1114]: info: MYVOLUMIO: Evaluating Server Oct 02 21:13:38 volumio volumio[1114]: info: Remote config written successfully Oct 02 21:13:38 volumio volumio[1114]: info: Starting Tunnel 1 Oct 02 21:13:38 volumio volumio[1114]: info: Starting Tunnel Connection Checker Oct 02 21:13:38 volumio volumio[1114]: info: Completed starting MyVolumio Plugin Oct 02 21:13:38 volumio volumio[1114]: info: MYVolumio Device enabled Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 02 21:13:38 volumio volumio[1114]: info: MyVolumio status changed Oct 02 21:13:38 volumio volumio[1114]: info: Streaming services startup Oct 02 21:13:38 volumio volumio[1114]: info: Starting Streaming Daemon Oct 02 21:13:38 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 02 21:13:38 volumio sudo[2205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 21:13:38 volumio sudo[2205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 02 21:13:38 volumio sudo[2205]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 02 21:13:38 volumio volumio[1114]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 02 21:13:38 volumio sudo[2211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 02 21:13:38 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio volumio[1114]: info: MRS: MultiRoom plugin initialized Oct 02 21:13:38 volumio volumio[1114]: info: MRS: STOPPING SNAPCLIENT Oct 02 21:13:38 volumio volumio[1114]: info: MRS: Snap server stop Oct 02 21:13:38 volumio volumio[1114]: info: MRS: STOPPING volumioStreaming Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 02 21:13:38 volumio sudo[2228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 02 21:13:38 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 02 21:13:38 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 02 21:13:38 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 02 21:13:38 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 02 21:13:38 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 02 21:13:38 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 02 21:13:38 volumio volumio[1114]: info: Preparing to generate the ALSA configuration file Oct 02 21:13:38 volumio volumio[1114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:13:38 volumio volumio[1114]: info: Reading ALSA contributions from plugins. Oct 02 21:13:38 volumio volumio[1114]: info: MRS: Removed streaming files Oct 02 21:13:38 volumio volumio[1114]: info: MRS: volumioStreaming STOPPED Oct 02 21:13:38 volumio volumio[1114]: info: MRS: SNAPSERVER STOPPED Oct 02 21:13:38 volumio volumio[1114]: info: MRS: SNAPCLIENT STOPPED Oct 02 21:13:38 volumio volumio[1114]: error: Cannot start Volumio Streaming Daemon Oct 02 21:13:38 volumio volumio[1114]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 21:13:38 volumio volumio[1114]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 21:13:38 volumio volumio[1114]: info: Asound.conf file written Oct 02 21:13:38 volumio sudo[2242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:13:38 volumio sudo[2242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2242]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio volumio[1114]: info: Output device has changed, restarting MPD Oct 02 21:13:38 volumio volumio[1114]: info: Output device has changed, restarting Shairport Sync Oct 02 21:13:38 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:38 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:38 volumio sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:13:38 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:13:38 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:38 volumio sudo[2250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:13:38 volumio volumio[1114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:13:38 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 02 21:13:38 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 02 21:13:38 volumio volumio[1114]: info: MRS: Pushing multiroomSync output for this device Oct 02 21:13:38 volumio volumio[1114]: info: MRS: Pushing multiroomSync output Oct 02 21:13:38 volumio volumio[1114]: info: Adding audio output: Oct 02 21:13:38 volumio volumio[1114]: info: Adding audio output: Oct 02 21:13:38 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 21:13:38 volumio volumio[1114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:13:38 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 02 21:13:38 volumio volumio[1114]: info: Adding METAVOLUMIO REST API Endpoints Oct 02 21:13:38 volumio volumio[1114]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 02 21:13:38 volumio volumio[1114]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 02 21:13:38 volumio volumio[1114]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 02 21:13:38 volumio volumio[1114]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 02 21:13:38 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 02 21:13:38 volumio volumio[1114]: info: Preparing CD Folders Oct 02 21:13:38 volumio volumio[1114]: info: Adding CD REST API Endpoints Oct 02 21:13:38 volumio volumio[1114]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 02 21:13:38 volumio volumio[1114]: info: Starting UDEV Watcher for CD Oct 02 21:13:38 volumio volumio[1114]: info: Detecting CD presence with UDEV Oct 02 21:13:38 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 02 21:13:38 volumio sudo[2264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr0 Oct 02 21:13:38 volumio sudo[2264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:38 volumio sudo[2264]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio sudo[2268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr1 Oct 02 21:13:39 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio sudo[2268]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio volumio[1114]: /bin/chmod: cannot access '/dev/sr1': No such file or directory Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 21:13:39 volumio volumio[1114]: info: [1790968419007] CoreMusicLibrary::Adding element Audio CD Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:39 volumio volumio[1114]: Cannot find translation for source Audio CD Oct 02 21:13:39 volumio volumio[1114]: info: [cd-plugin] Set CD speed to 1X Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 02 21:13:39 volumio volumio[1114]: error: Hi Res Audio Failed Login: Missing Login Data Oct 02 21:13:39 volumio volumio[1114]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 02 21:13:39 volumio volumio[1114]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 02 21:13:39 volumio volumio[1114]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Oct 02 21:13:39 volumio volumio[1114]: info: Adding TIDAL REST API Endpoints Oct 02 21:13:39 volumio volumio[1114]: info: Stopping AccessToken refresher cron for QOBUZ Oct 02 21:13:39 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:13:39 volumio systemd[1]: mpd.service: Consumed 1.077s CPU time. Oct 02 21:13:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:13:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:13:39 volumio volumio[1114]: info: AccessToken refresher cron started for QOBUZ Oct 02 21:13:39 volumio volumio[1114]: info: Adding QOBUZ REST API Endpoints Oct 02 21:13:39 volumio volumio[1114]: info: Getting Spotify volume Oct 02 21:13:39 volumio volumio[1114]: info: MPD Permissions set Oct 02 21:13:39 volumio volumio[1114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:13:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:13:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:13:39 volumio volumio[1114]: info: MRS: Found cast device: S95F-f84b46fbaa599ad39399eb929f5a9ee1 Oct 02 21:13:39 volumio volumio[1114]: info: Adding audio output: Oct 02 21:13:39 volumio volumio[1114]: info: MRS: Found cast device: MiTV-AESP0-cd40d4d42473fbf7d7888c5eecca80fa Oct 02 21:13:39 volumio volumio[1114]: info: Adding audio output: Oct 02 21:13:39 volumio sudo[2349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:13:39 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio volumio[1114]: info: Preparing to generate the ALSA configuration file Oct 02 21:13:39 volumio volumio[1114]: [Metrics] CommandRouter: 14s 715.82ms Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::Close All Modals sent Oct 02 21:13:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::Close All Modals sent Oct 02 21:13:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:39 volumio systemd[1]: shairport-sync.service: Consumed 1.581s CPU time. Oct 02 21:13:39 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:39 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio volumio[1114]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 02 21:13:39 volumio volumio[1114]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for Amanero_1 Oct 02 21:13:39 volumio volumio[1114]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 02 21:13:39 volumio volumio[1114]: aplay: main:831: audio open error: No such device Oct 02 21:13:39 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:13:39 volumio sudo[2321]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:13:39 volumio sudo[2321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:39 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:39 volumio sudo[2321]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio volumio[1114]: info: Asound.conf file written Oct 02 21:13:39 volumio sudo[2375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:13:39 volumio sudo[2375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio sudo[2375]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio volumio[1114]: info: Output device has changed, restarting MPD Oct 02 21:13:39 volumio volumio[1114]: info: Output device has changed, restarting Shairport Sync Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:13:39 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio sudo[2381]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio sudo[2383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:13:39 volumio sudo[2383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:13:39 volumio volumio[1114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: Preparing to generate the ALSA configuration file Oct 02 21:13:39 volumio volumio[1114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:13:39 volumio volumio[1114]: info: Reading ALSA contributions from plugins. Oct 02 21:13:39 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:13:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:13:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:13:39 volumio volumio[1114]: info: MPD Permissions set Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:13:39 volumio volumio[1114]: info: Asound.conf file written Oct 02 21:13:39 volumio sudo[2402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:13:39 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio sudo[2405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:13:39 volumio sudo[2405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio sudo[2405]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:13:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:13:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:13:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:39 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:39 volumio volumio[1114]: info: Output device has changed, restarting MPD Oct 02 21:13:39 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio volumio[1114]: info: Output device has changed, restarting Shairport Sync Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:13:39 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio sudo[2416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:13:39 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:13:39 volumio volumio[1114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: MPD Permissions set Oct 02 21:13:39 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:13:39 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:13:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:13:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:13:39 volumio sudo[2450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:13:39 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:13:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:13:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:39 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:13:39 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:13:39 volumio sudo[2442]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:13:39 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:13:39 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio ntpd[1052]: CLOCK: time stepped by -0.271396 Oct 02 21:13:39 volumio ntpd[1052]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Oct 02 21:13:39 volumio systemd-journald[592]: Time jumped backwards, rotating. Oct 02 21:13:39 volumio mpd[2467]: 2026-10-02T21:13:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 21:13:39 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 21:13:39 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio sudo[2383]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio sudo[2250]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:39 volumio volumio[1114]: error: MPD error: The expression evaluated to a falsy value: Oct 02 21:13:39 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:13:39 volumio volumio[1114]: error: The expression evaluated to a falsy value: Oct 02 21:13:39 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:13:39 volumio volumio[1114]: error: MPD error: The expression evaluated to a falsy value: Oct 02 21:13:39 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:13:39 volumio volumio[1114]: error: The expression evaluated to a falsy value: Oct 02 21:13:39 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:13:39 volumio volumio[1114]: error: updateQueue error: null Oct 02 21:13:39 volumio volumio[1114]: info: Setting Geolocation for MyVolumio to eu11 Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: Setting Geolocation for MyVolumio to eu4 Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:39 volumio upmpdcli[2472]: writing RSA key Oct 02 21:13:39 volumio volumio[1114]: info: Successfully Added MyVolumio device Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:13:39 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 21:13:40 volumio volumio[1114]: info: Successfully Added MyVolumio device Oct 02 21:13:40 volumio volumio[1114]: info: Updating MyVolumio device info Oct 02 21:13:40 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:40 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:40 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:40 volumio volumio[1114]: info: Updating MyVolumio device info Oct 02 21:13:40 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:40 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:40 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:40 volumio volumio[1114]: info: Successfully Updated MyVolumio device Oct 02 21:13:40 volumio volumio[1114]: info: Successfully Updated MyVolumio device Oct 02 21:13:42 volumio volumio[1114]: info: MRS: Found cast device: MiTV-AESP0-cd40d4d42473fbf7d7888c5eecca80fa Oct 02 21:13:43 volumio sudo[3003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 02 21:13:43 volumio sudo[3003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:13:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 21:13:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 21:13:43 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Oct 02 21:13:43 volumio sudo[3003]: pam_unix(sudo:session): session closed for user root Oct 02 21:13:43 volumio volumio[1114]: info: Remote SSH Started Oct 02 21:13:43 volumio autossh[3086]: port set to 0, monitoring disabled Oct 02 21:13:43 volumio autossh[3086]: starting ssh (count 1) Oct 02 21:13:43 volumio autossh[3086]: ssh child pid is 3098 Oct 02 21:13:43 volumio volumio[1114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 02 21:13:43 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:43 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:43 volumio volumio[1114]: info: CDDB Entry Already exists Oct 02 21:13:43 volumio volumio[1114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 21:13:43 volumio volumio[1114]: info: [1790968423214] CoreMusicLibrary::Adding element Audio CD Oct 02 21:13:43 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:43 volumio volumio[1114]: Cannot find translation for source Audio CD Oct 02 21:13:43 volumio volumiossh-tunnel[3098]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Oct 02 21:13:43 volumio volumio[1114]: info: MRS: Getting audio outputs on start Oct 02 21:13:43 volumio volumio[1114]: info: MRS: Requesting all other devices output Oct 02 21:13:43 volumio volumio[1114]: info: [cd-plugin] Set CD speed to 1X Oct 02 21:13:45 volumio volumio[1114]: info: BOOT COMPLETED Oct 02 21:13:46 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 21:13:46 volumio volumio[1114]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 21:13:46 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 21:13:46 volumio volumio[1114]: info: Received Get System Version Oct 02 21:13:46 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:13:46 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:46 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:46 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:46 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:46 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:46 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:46 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:13:51 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 21:13:51 volumio volumio[1114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 21:13:51 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:51 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:51 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 02 21:13:54 volumio volumio[1114]: info: MRS: Found cast device: MiTV-AESP0-cd40d4d42473fbf7d7888c5eecca80fa Oct 02 21:13:55 volumio volumio[1114]: verbose: New Socket.io Connection to 192.168.0.204 from 192.168.0.146 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 21:13:55 volumio volumio[1114]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:55 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 21:13:55 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 21:13:56 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Oct 02 21:13:56 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 02 21:13:56 volumio volumio-remote-updater[830]: Test mode disabled Oct 02 21:13:56 volumio volumio-remote-updater[830]: Alpha mode disabled Oct 02 21:13:56 volumio volumio-remote-updater[830]: Alpha legacy test mode disabled Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 02 21:13:56 volumio volumio[1114]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 21:13:56 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 21:13:57 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Oct 02 21:13:57 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Oct 02 21:13:58 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 21:13:58 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:58 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:58 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:58 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:58 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:58 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:58 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:13:59 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 21:13:59 volumio volumio[1114]: info: Received Get System Info Oct 02 21:13:59 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:13:59 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:13:59 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:13:59 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:13:59 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:13:59 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:14:07 volumio volumio[1114]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 02 21:14:07 volumio volumio[1114]: info: Preparing to save Alsa Options, stopping services first Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:14:07 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::volumioPause Oct 02 21:14:07 volumio volumio[1114]: info: CoreStateMachine::pause Oct 02 21:14:07 volumio volumio[1114]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"BRZHIFI"}} Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:14:07 volumio volumio[1114]: info: Device 1 does not have any Mixer Control Available Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 02 21:14:07 volumio volumio[1114]: info: Updating Volume Controller Parameters: Device: 1 Name: BRZHIFI Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 02 21:14:07 volumio volumio[1114]: info: Disabling external Volume Control Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 21:14:07 volumio volumio[1114]: info: CoreStateMachine::pushState Oct 02 21:14:07 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Oct 02 21:14:07 volumio volumio[1114]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:14:07 volumio volumio[1114]: info: MRS: Pushing multiroomSync output Oct 02 21:14:07 volumio volumio[1114]: info: Preparing to generate the ALSA configuration file Oct 02 21:14:07 volumio volumio[1114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:14:07 volumio volumio[1114]: info: Reading ALSA contributions from plugins. Oct 02 21:14:07 volumio volumio[1114]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 21:14:07 volumio volumio[1114]: info: Asound.conf file written Oct 02 21:14:07 volumio sudo[3402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:14:07 volumio sudo[3402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:14:07 volumio sudo[3402]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:07 volumio volumio[1114]: info: Output device has changed, restarting MPD Oct 02 21:14:07 volumio volumio[1114]: info: Output device has changed, restarting Shairport Sync Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:14:07 volumio sudo[3408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:14:07 volumio sudo[3408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:14:07 volumio sudo[3408]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:07 volumio sudo[3410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:14:07 volumio sudo[3410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:14:07 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:14:07 volumio volumio[1114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: Output device has changed, restarting MPD Oct 02 21:14:07 volumio volumio[1114]: info: Output device has changed, restarting Shairport Sync Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:14:07 volumio sudo[3421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:14:07 volumio sudo[3421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:14:07 volumio volumio[1114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:14:07 volumio volumio[1114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio sudo[3423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:14:07 volumio sudo[3421]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:07 volumio sudo[3423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:14:07 volumio volumio[1114]: info: MPD Permissions set Oct 02 21:14:07 volumio volumio[1114]: info: MPD Permissions set Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:14:07 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:14:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 21:14:07 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:14:07 volumio volumio[1114]: info: Starting Shairport Sync Oct 02 21:14:07 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:14:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:14:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:14:07 volumio sudo[3456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:14:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:14:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:14:07 volumio sudo[3456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:14:07 volumio sudo[3459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:14:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:14:07 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:14:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:14:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:14:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:14:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:14:07 volumio systemd[1]: shairport-sync.service: Consumed 1.703s CPU time. Oct 02 21:14:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:14:07 volumio sudo[3456]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:07 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:07 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:14:07 volumio volumio[1114]: info: Shairport-Sync Started Oct 02 21:14:07 volumio sudo[3467]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:14:07 volumio sudo[3467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:14:07 volumio sudo[3467]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:08 volumio mpd[3486]: 2026-10-02T21:14:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 21:14:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 21:14:08 volumio sudo[3423]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:08 volumio sudo[3410]: pam_unix(sudo:session): session closed for user root Oct 02 21:14:08 volumio volumio[1114]: error: MPD error: The expression evaluated to a falsy value: Oct 02 21:14:08 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:14:08 volumio volumio[1114]: error: The expression evaluated to a falsy value: Oct 02 21:14:08 volumio volumio[1114]: assert.ok(self.idling) Oct 02 21:14:08 volumio volumio[1114]: error: updateQueue error: null Oct 02 21:14:11 volumio volumio[1114]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 02 21:14:11 volumio volumio[1114]: info: CoreStateMachine::getcurrentVolume Oct 02 21:14:11 volumio volumio[1114]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 21:14:11 volumio volumio[1114]: info: CoreStateMachine::pushState Oct 02 21:14:11 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:14:11 volumio volumio[1114]: info: CoreCommandRouter::volumioPushState Oct 02 21:14:11 volumio volumio[1114]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:14:11 volumio volumio[1114]: info: MRS: Pushing multiroomSync output Oct 02 21:14:13 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 21:14:13 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 21:14:13 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 21:14:13 volumio volumio[1114]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 21:14:13 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 21:14:13 volumio volumio[1114]: info: Received Get System Version Oct 02 21:14:13 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:14:13 volumio volumio[1114]: info: Received Get System Info Oct 02 21:14:13 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:14:13 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:14:13 volumio volumio[1114]: info: Discovery: Getting this device information Oct 02 21:14:13 volumio volumio[1114]: info: CoreCommandRouter::volumioGetState Oct 02 21:14:13 volumio volumio[1114]: info: CorePlayQueue::getTrack 0 Oct 02 21:14:13 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:14:18 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 21:14:18 volumio volumio[1114]: info: CURURI: music-library Oct 02 21:14:18 volumio volumio[1114]: info: CoreCommandRouter::volumioGetQueue Oct 02 21:14:18 volumio volumio[1114]: info: CoreStateMachine::getQueue Oct 02 21:14:18 volumio volumio[1114]: info: CorePlayQueue::getQueue Oct 02 21:14:18 volumio volumio[1114]: info: Listing playlists Oct 02 21:14:18 volumio volumio[1114]: info: Preload queue cleared Oct 02 21:14:19 volumio volumio[1114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 21:14:19 volumio volumio[1114]: info: CURURI: music-library/INTERNAL Oct 02 21:14:19 volumio volumio[1114]: info: Preload queue cleared Oct 02 21:14:24 volumio volumio[1114]: info: Preload queue cleared Oct 02 21:14:24 volumio volumio[1114]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 21:14:24 volumio volumio[1114]: info: CoreStateMachine::ClearQueue Oct 02 21:14:24 volumio volumio[1114]: info: CoreStateMachine::stop Oct 02 21:14:24 volumio volumio[1114]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:14:24 volumio volumio[1114]: info: CorePlayQueue::clearPlayQueue Oct 02 21:14:24 volumio volumio[1114]: info: CorePlayQueue::saveQueue Oct 02 21:14:24 volumio volumio[1114]: info: CoreCommandRouter::volumioPushQueue Oct 02 21:14:24 volumio volumio[1114]: info: CoreStateMachine::addQueueItems Oct 02 21:14:24 volumio volumio[1114]: info: CorePlayQueue::addQueueItems Oct 02 21:14:24 volumio volumio[1114]: info: Preload queue cleared Oct 02 21:14:24 volumio volumio[1114]: info: Adding Item to queue: music-library/INTERNAL/Big Daddy Wilson & Doc Fozz - Doin' It Right (2007) Oct 02 21:14:24 volumio volumio[1114]: info: Exploding uri music-library/INTERNAL/Big Daddy Wilson & Doc Fozz - Doin' It Right (2007) in service mpd Oct 02 21:14:24 volumio volumio[1114]: info: ALBUMART /albumart?cacheid=522&web=Big%20Daddy%20Wilson%20%26%20Doc%20Fozz/Doin'%20It%20Right/extralarge&path=%2Fmnt%2FINTERNAL%2FBig%20Daddy%20Wilson%20%26%20Doc%20Fozz%20-%20Doin'%20It%20Right%20(2007)%2FBig%20Daddy%20Wilson%20%26%20Doc%20Fozz%20-%20Doin'%20It%20Right.cue&metadata=false Oct 02 21:14:24 volumio volumio[1114]: info: URI /mnt/INTERNAL/Big Daddy Wilson & Doc Fozz - Doin' It Right (2007)/Big Daddy Wilson & Doc Fozz - Doin' It Right.cue Oct 02 21:14:24 volumio volumio[1114]: info: ALBUMART /albumart?cacheid=522&web=Big%20Daddy%20Wilson%20%26%20Doc%20Fozz/Doin'%20It%20Right/extralarge&path=%2Fmnt%2FINTERNAL%2FBig%20Daddy%20Wilson%20%26%20Doc%20Fozz%20-%20Doin'%20It%20Right%20(2007)%2FBig%20Daddy%20Wilson%20%26%20Doc%20Fozz%20-%20Doin'%20It%20Right.cue&metadata=false Oct 02 21:14:24 volumio volumio[1114]: info: URI /mnt/INTERNAL/Big Daddy Wilson & Doc Fozz - Doin' It Right (2007)/Big Daddy Wilson & Doc Fozz - Doin' It Right.cue Oct 02 21:14:24 volumio volumio[1114]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 21:14:24 volumio volumio[1114]: Error: Unable to resolve or reject the same promise twice Oct 02 21:14:24 volumio volumio[1114]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 02 21:14:24 volumio volumio[1114]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 02 21:14:24 volumio volumio[1114]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 02 21:14:24 volumio volumio[1114]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 02 21:14:24 volumio volumio[1114]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 02 21:14:24 volumio volumio[1114]: at Socket.emit (node:events:514:28) Oct 02 21:14:24 volumio volumio[1114]: at addChunk (node:internal/streams/readable:343:12) Oct 02 21:14:24 volumio volumio[1114]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 02 21:14:24 volumio volumio[1114]: at Readable.push (node:internal/streams/readable:253:10) Oct 02 21:14:24 volumio volumio[1114]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 02 21:14:24 volumio volumio[1114]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 21:14:24 volumio sudo[3545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 21:13' Oct 02 21:14:24 volumio sudo[3545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"