-- Logs begin at Thu 2025-05-29 15:00:54 CEST, end at Thu 2025-05-29 15:01:14 CEST. -- May 29 15:00:54 volumio-hc kernel: Linux version 6.6.32-volumio (gkkpch@UbuntuVM24.04) (gcc (Ubuntu 13.2.0-23ubuntu4) 13.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 May 29 15:00:54 volumio-hc kernel: Command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=61cb7b6c-d4cb-431d-9399-36325980667c bootpart=UUID=5D14-3AFB datapart=UUID=77451eee-ccc4-4fda-b095-beb6b9a2c81d hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no May 29 15:00:54 volumio-hc kernel: KERNEL supported cpus: May 29 15:00:54 volumio-hc kernel: Intel GenuineIntel May 29 15:00:54 volumio-hc kernel: AMD AuthenticAMD May 29 15:00:54 volumio-hc kernel: Hygon HygonGenuine May 29 15:00:54 volumio-hc kernel: Centaur CentaurHauls May 29 15:00:54 volumio-hc kernel: zhaoxin Shanghai May 29 15:00:54 volumio-hc kernel: x86/split lock detection: #AC: crashing the kernel on kernel split_locks and warning on user-space split_locks May 29 15:00:54 volumio-hc kernel: BIOS-provided physical RAM map: May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009dfff] usable May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x000000000009e000-0x000000000009efff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] usable May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000000a0000-0x00000000000fffff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007264afff] usable May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x000000007264b000-0x000000007574afff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x000000007574b000-0x000000007581afff] ACPI data May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x000000007581b000-0x00000000758dafff] ACPI NVS May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000758db000-0x0000000075f65fff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x0000000075f66000-0x0000000075ffefff] type 20 May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x0000000075fff000-0x0000000075ffffff] usable May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x0000000076000000-0x0000000079ffffff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x000000007aa00000-0x000000007abfffff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x000000007b000000-0x00000000803fffff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000c0000000-0x00000000cfffffff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000fe000000-0x00000000fe010fff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000fed00000-0x00000000fed00fff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000fed20000-0x00000000fed7ffff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved May 29 15:00:54 volumio-hc kernel: BIOS-e820: [mem 0x0000000100000000-0x000000047fbfffff] usable May 29 15:00:54 volumio-hc kernel: NX (Execute Disable) protection: active May 29 15:00:54 volumio-hc kernel: APIC: Static calls initialized May 29 15:00:54 volumio-hc kernel: efi: EFI v2.8 by American Megatrends May 29 15:00:54 volumio-hc kernel: efi: ACPI=0x7581a000 ACPI 2.0=0x7581a014 TPMFinalLog=0x75888000 SMBIOS=0x75cc9000 SMBIOS 3.0=0x75cc8000 MEMATTR=0x6eab7018 ESRT=0x6eb9d498 May 29 15:00:54 volumio-hc kernel: efi: Remove mem66: MMIO range=[0xc0000000-0xcfffffff] (256MB) from e820 map May 29 15:00:54 volumio-hc kernel: e820: remove [mem 0xc0000000-0xcfffffff] reserved May 29 15:00:54 volumio-hc kernel: efi: Not removing mem67: MMIO range=[0xfe000000-0xfe010fff] (68KB) from e820 map May 29 15:00:54 volumio-hc kernel: efi: Not removing mem68: MMIO range=[0xfec00000-0xfec00fff] (4KB) from e820 map May 29 15:00:54 volumio-hc kernel: efi: Not removing mem69: MMIO range=[0xfed00000-0xfed00fff] (4KB) from e820 map May 29 15:00:54 volumio-hc kernel: efi: Not removing mem71: MMIO range=[0xfee00000-0xfee00fff] (4KB) from e820 map May 29 15:00:54 volumio-hc kernel: efi: Remove mem72: MMIO range=[0xff000000-0xffffffff] (16MB) from e820 map May 29 15:00:54 volumio-hc kernel: e820: remove [mem 0xff000000-0xffffffff] reserved May 29 15:00:54 volumio-hc kernel: SMBIOS 3.5.0 present. May 29 15:00:54 volumio-hc kernel: DMI: Default string Default string/Default string, BIOS 5.27 06/05/2023 May 29 15:00:54 volumio-hc kernel: tsc: Detected 1700.000 MHz processor May 29 15:00:54 volumio-hc kernel: tsc: Detected 1689.600 MHz TSC May 29 15:00:54 volumio-hc kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 29 15:00:54 volumio-hc kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 29 15:00:54 volumio-hc kernel: last_pfn = 0x47fc00 max_arch_pfn = 0x400000000 May 29 15:00:54 volumio-hc kernel: MTRR map: 8 entries (3 fixed + 5 variable; max 23), built from 10 variable MTRRs May 29 15:00:54 volumio-hc kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 29 15:00:54 volumio-hc kernel: e820: update [mem 0x7c000000-0xffffffff] usable ==> reserved May 29 15:00:54 volumio-hc kernel: e820: update [mem 0x47fc00000-0x483bfffff] usable ==> reserved May 29 15:00:54 volumio-hc kernel: last_pfn = 0x76000 max_arch_pfn = 0x400000000 May 29 15:00:54 volumio-hc kernel: esrt: Reserving ESRT space from 0x000000006eb9d498 to 0x000000006eb9d548. May 29 15:00:54 volumio-hc kernel: e820: update [mem 0x6eb9d000-0x6eb9dfff] usable ==> reserved May 29 15:00:54 volumio-hc kernel: Using GB pages for direct mapping May 29 15:00:54 volumio-hc kernel: Incomplete global flushes, disabling PCID May 29 15:00:54 volumio-hc kernel: Secure boot could not be determined May 29 15:00:54 volumio-hc kernel: RAMDISK: [mem 0x2fae9000-0x33d6bfff] May 29 15:00:54 volumio-hc kernel: ACPI: Early table checksum verification disabled May 29 15:00:54 volumio-hc kernel: ACPI: RSDP 0x000000007581A014 000024 (v02 ALASKA) May 29 15:00:54 volumio-hc kernel: ACPI: XSDT 0x0000000075819728 0000FC (v01 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: FACP 0x0000000075818000 000114 (v06 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: DSDT 0x00000000757A3000 07459A (v02 ALASKA A M I 01072009 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: FACS 0x00000000758DA000 000040 May 29 15:00:54 volumio-hc kernel: ACPI: FIDT 0x00000000757A2000 00009C (v01 ALASKA A M I 01072009 AMI 00010013) May 29 15:00:54 volumio-hc kernel: ACPI: MSDM 0x00000000757A1000 000055 (v03 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x00000000757A0000 00038C (v02 PmaxDv Pmax_Dev 00000001 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x000000007579A000 005D0B (v02 CpuRef CpuSsdt 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x0000000075797000 002935 (v02 SaSsdt SaSsdt 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x0000000075793000 0033D3 (v02 INTEL IgfxSsdt 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: HPET 0x0000000075792000 000038 (v01 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: APIC 0x0000000075791000 0001DC (v05 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: MCFG 0x0000000075790000 00003C (v01 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x000000007578E000 001F1A (v02 ALASKA Ther_Rvp 00001000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: UEFI 0x000000007586F000 000048 (v01 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: NHLT 0x000000007578D000 00002D (v00 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: LPIT 0x000000007578C000 0000CC (v01 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x0000000075788000 002A83 (v02 ALASKA PtidDevc 00001000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x0000000075785000 002357 (v02 ALASKA TbtTypeC 00000000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: DBGP 0x0000000075784000 000034 (v01 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: DBG2 0x0000000075783000 000054 (v00 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: DMAR 0x0000000075782000 000088 (v02 INTEL EDK2 00000002 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: FPDT 0x0000000075781000 000044 (v01 ALASKA A M I 01072009 AMI 01000013) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x0000000075780000 000686 (v02 INTEL xh_adl_N 00000000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x000000007577C000 003AEA (v02 SocGpe SocGpe 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x0000000075778000 0039DA (v02 SocCmn SocCmn 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0x0000000075777000 000144 (v02 Intel ADebTabl 00001000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: TPM2 0x0000000075776000 00004C (v04 ALASKA A M I 00000001 AMI 00000000) May 29 15:00:54 volumio-hc kernel: ACPI: PHAT 0x0000000075775000 0005F1 (v01 ALASKA A M I 00000005 MSFT 0100000D) May 29 15:00:54 volumio-hc kernel: ACPI: WSMT 0x000000007578B000 000028 (v01 ALASKA A M I 01072009 AMI 00010013) May 29 15:00:54 volumio-hc kernel: ACPI: Reserving FACP table memory at [mem 0x75818000-0x75818113] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving DSDT table memory at [mem 0x757a3000-0x75817599] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving FACS table memory at [mem 0x758da000-0x758da03f] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving FIDT table memory at [mem 0x757a2000-0x757a209b] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving MSDM table memory at [mem 0x757a1000-0x757a1054] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x757a0000-0x757a038b] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x7579a000-0x7579fd0a] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x75797000-0x75799934] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x75793000-0x757963d2] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving HPET table memory at [mem 0x75792000-0x75792037] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving APIC table memory at [mem 0x75791000-0x757911db] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving MCFG table memory at [mem 0x75790000-0x7579003b] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x7578e000-0x7578ff19] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving UEFI table memory at [mem 0x7586f000-0x7586f047] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving NHLT table memory at [mem 0x7578d000-0x7578d02c] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving LPIT table memory at [mem 0x7578c000-0x7578c0cb] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x75788000-0x7578aa82] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x75785000-0x75787356] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving DBGP table memory at [mem 0x75784000-0x75784033] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving DBG2 table memory at [mem 0x75783000-0x75783053] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving DMAR table memory at [mem 0x75782000-0x75782087] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving FPDT table memory at [mem 0x75781000-0x75781043] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x75780000-0x75780685] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x7577c000-0x7577fae9] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x75778000-0x7577b9d9] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving SSDT table memory at [mem 0x75777000-0x75777143] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving TPM2 table memory at [mem 0x75776000-0x7577604b] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving PHAT table memory at [mem 0x75775000-0x757755f0] May 29 15:00:54 volumio-hc kernel: ACPI: Reserving WSMT table memory at [mem 0x7578b000-0x7578b027] May 29 15:00:54 volumio-hc kernel: Zone ranges: May 29 15:00:54 volumio-hc kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 29 15:00:54 volumio-hc kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 29 15:00:54 volumio-hc kernel: Normal [mem 0x0000000100000000-0x000000047fbfffff] May 29 15:00:54 volumio-hc kernel: Movable zone start for each node May 29 15:00:54 volumio-hc kernel: Early memory node ranges May 29 15:00:54 volumio-hc kernel: node 0: [mem 0x0000000000001000-0x000000000009dfff] May 29 15:00:54 volumio-hc kernel: node 0: [mem 0x000000000009f000-0x000000000009ffff] May 29 15:00:54 volumio-hc kernel: node 0: [mem 0x0000000000100000-0x000000007264afff] May 29 15:00:54 volumio-hc kernel: node 0: [mem 0x0000000075fff000-0x0000000075ffffff] May 29 15:00:54 volumio-hc kernel: node 0: [mem 0x0000000100000000-0x000000047fbfffff] May 29 15:00:54 volumio-hc kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000047fbfffff] May 29 15:00:54 volumio-hc kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 29 15:00:54 volumio-hc kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 29 15:00:54 volumio-hc kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 29 15:00:54 volumio-hc kernel: On node 0, zone DMA32: 14772 pages in unavailable ranges May 29 15:00:54 volumio-hc kernel: On node 0, zone Normal: 8192 pages in unavailable ranges May 29 15:00:54 volumio-hc kernel: On node 0, zone Normal: 1024 pages in unavailable ranges May 29 15:00:54 volumio-hc kernel: Reserving Intel graphics memory at [mem 0x7c800000-0x803fffff] May 29 15:00:54 volumio-hc kernel: ACPI: PM-Timer IO Port: 0x1808 May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x05] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x06] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x07] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x08] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x09] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x0a] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x0b] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x0c] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x0d] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x0e] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x0f] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x10] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x11] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x12] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x13] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x14] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x15] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x16] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x17] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1]) May 29 15:00:54 volumio-hc kernel: IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-119 May 29 15:00:54 volumio-hc kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 29 15:00:54 volumio-hc kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 29 15:00:54 volumio-hc kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 29 15:00:54 volumio-hc kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 29 15:00:54 volumio-hc kernel: TSC deadline timer available May 29 15:00:54 volumio-hc kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x0009e000-0x0009efff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000fffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x6eb9d000-0x6eb9dfff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x7264b000-0x7574afff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x7574b000-0x7581afff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x7581b000-0x758dafff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x758db000-0x75f65fff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x75f66000-0x75ffefff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x76000000-0x79ffffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x7a000000-0x7a9fffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x7aa00000-0x7abfffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x7ac00000-0x7affffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x7b000000-0x803fffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0x80400000-0xfdffffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfe000000-0xfe010fff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfe011000-0xfebfffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfec00000-0xfec00fff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfec01000-0xfecfffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfed00000-0xfed00fff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfed01000-0xfed1ffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfed20000-0xfed7ffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfed80000-0xfedfffff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfee00000-0xfee00fff] May 29 15:00:54 volumio-hc kernel: PM: hibernation: Registered nosave memory: [mem 0xfee01000-0xffffffff] May 29 15:00:54 volumio-hc kernel: [mem 0x80400000-0xfdffffff] available for PCI devices May 29 15:00:54 volumio-hc kernel: Booting paravirtualized kernel on bare hardware May 29 15:00:54 volumio-hc kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns May 29 15:00:54 volumio-hc kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 29 15:00:54 volumio-hc kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u524288 May 29 15:00:54 volumio-hc kernel: pcpu-alloc: s191400 r8192 d29784 u524288 alloc=1*2097152 May 29 15:00:54 volumio-hc kernel: pcpu-alloc: [0] 0 1 2 3 May 29 15:00:54 volumio-hc kernel: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=61cb7b6c-d4cb-431d-9399-36325980667c bootpart=UUID=5D14-3AFB datapart=UUID=77451eee-ccc4-4fda-b095-beb6b9a2c81d hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no May 29 15:00:54 volumio-hc kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=/vmlinuz biosdevname=0 imgpart=UUID=61cb7b6c-d4cb-431d-9399-36325980667c bootpart=UUID=5D14-3AFB datapart=UUID=77451eee-ccc4-4fda-b095-beb6b9a2c81d hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. May 29 15:00:54 volumio-hc kernel: random: crng init done May 29 15:00:54 volumio-hc kernel: Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear) May 29 15:00:54 volumio-hc kernel: Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) May 29 15:00:54 volumio-hc kernel: Built 1 zonelists, mobility grouping on. Total pages: 4072642 May 29 15:00:54 volumio-hc kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off May 29 15:00:54 volumio-hc kernel: software IO TLB: area num 4. May 29 15:00:54 volumio-hc kernel: Memory: 15975384K/16549800K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 574160K reserved, 0K cma-reserved) May 29 15:00:54 volumio-hc kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 29 15:00:54 volumio-hc kernel: ftrace: allocating 40433 entries in 158 pages May 29 15:00:54 volumio-hc kernel: ftrace: allocated 158 pages with 5 groups May 29 15:00:54 volumio-hc kernel: Dynamic Preempt: voluntary May 29 15:00:54 volumio-hc kernel: rcu: Preemptible hierarchical RCU implementation. May 29 15:00:54 volumio-hc kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. May 29 15:00:54 volumio-hc kernel: Trampoline variant of Tasks RCU enabled. May 29 15:00:54 volumio-hc kernel: Rude variant of Tasks RCU enabled. May 29 15:00:54 volumio-hc kernel: Tracing variant of Tasks RCU enabled. May 29 15:00:54 volumio-hc kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. May 29 15:00:54 volumio-hc kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 29 15:00:54 volumio-hc kernel: NR_IRQS: 4352, nr_irqs: 1024, preallocated irqs: 16 May 29 15:00:54 volumio-hc kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 29 15:00:54 volumio-hc kernel: Console: colour dummy device 80x25 May 29 15:00:54 volumio-hc kernel: printk: console [tty0] enabled May 29 15:00:54 volumio-hc kernel: ACPI: Core revision 20230628 May 29 15:00:54 volumio-hc kernel: hpet: HPET dysfunctional in PC10. Force disabled. May 29 15:00:54 volumio-hc kernel: APIC: Switch to symmetric I/O mode setup May 29 15:00:54 volumio-hc kernel: x2apic: IRQ remapping doesn't support X2APIC mode May 29 15:00:54 volumio-hc kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x185ac6cdb26, max_idle_ns: 440795262339 ns May 29 15:00:54 volumio-hc kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 3379.20 BogoMIPS (lpj=6758400) May 29 15:00:54 volumio-hc kernel: CPU0: Thermal monitoring enabled (TM1) May 29 15:00:54 volumio-hc kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 29 15:00:54 volumio-hc kernel: process: using mwait in idle threads May 29 15:00:54 volumio-hc kernel: CET detected: Indirect Branch Tracking enabled May 29 15:00:54 volumio-hc kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 May 29 15:00:54 volumio-hc kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 May 29 15:00:54 volumio-hc kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 29 15:00:54 volumio-hc kernel: Spectre V2 : Mitigation: Enhanced / Automatic IBRS May 29 15:00:54 volumio-hc kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch May 29 15:00:54 volumio-hc kernel: Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT May 29 15:00:54 volumio-hc kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 29 15:00:54 volumio-hc kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 29 15:00:54 volumio-hc kernel: Register File Data Sampling: Vulnerable: No microcode May 29 15:00:54 volumio-hc kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 29 15:00:54 volumio-hc kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 29 15:00:54 volumio-hc kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 29 15:00:54 volumio-hc kernel: x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' May 29 15:00:54 volumio-hc kernel: x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' May 29 15:00:54 volumio-hc kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 29 15:00:54 volumio-hc kernel: x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8 May 29 15:00:54 volumio-hc kernel: x86/fpu: xstate_offset[11]: 840, xstate_sizes[11]: 16 May 29 15:00:54 volumio-hc kernel: x86/fpu: Enabled xstate features 0xa07, context size is 856 bytes, using 'compacted' format. May 29 15:00:54 volumio-hc kernel: Freeing SMP alternatives memory: 32K May 29 15:00:54 volumio-hc kernel: pid_max: default: 32768 minimum: 301 May 29 15:00:54 volumio-hc kernel: LSM: initializing lsm=capability,yama,integrity May 29 15:00:54 volumio-hc kernel: Yama: becoming mindful. May 29 15:00:54 volumio-hc kernel: Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) May 29 15:00:54 volumio-hc kernel: Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) May 29 15:00:54 volumio-hc kernel: smpboot: CPU0: Intel(R) N95 (family: 0x6, model: 0xbe, stepping: 0x0) May 29 15:00:54 volumio-hc kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. May 29 15:00:54 volumio-hc kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. May 29 15:00:54 volumio-hc kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. May 29 15:00:54 volumio-hc kernel: Performance Events: XSAVE Architectural LBR, PEBS fmt4+-baseline, PEBS-via-PT, AnyThread deprecated, Gracemont events, 32-deep LBR, full-width counters, Intel PMU driver. May 29 15:00:54 volumio-hc kernel: ... version: 5 May 29 15:00:54 volumio-hc kernel: ... bit width: 48 May 29 15:00:54 volumio-hc kernel: ... generic registers: 6 May 29 15:00:54 volumio-hc kernel: ... value mask: 0000ffffffffffff May 29 15:00:54 volumio-hc kernel: ... max period: 00007fffffffffff May 29 15:00:54 volumio-hc kernel: ... fixed-purpose events: 3 May 29 15:00:54 volumio-hc kernel: ... event mask: 000000070000003f May 29 15:00:54 volumio-hc kernel: signal: max sigframe size: 3632 May 29 15:00:54 volumio-hc kernel: Estimated ratio of average max frequency by base frequency (times 1024): 1626 May 29 15:00:54 volumio-hc kernel: rcu: Hierarchical SRCU implementation. May 29 15:00:54 volumio-hc kernel: rcu: Max phase no-delay instances is 1000. May 29 15:00:54 volumio-hc kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. May 29 15:00:54 volumio-hc kernel: smp: Bringing up secondary CPUs ... May 29 15:00:54 volumio-hc kernel: smpboot: x86: Booting SMP configuration: May 29 15:00:54 volumio-hc kernel: .... node #0, CPUs: #1 #2 #3 May 29 15:00:54 volumio-hc kernel: smp: Brought up 1 node, 4 CPUs May 29 15:00:54 volumio-hc kernel: smpboot: Max logical packages: 1 May 29 15:00:54 volumio-hc kernel: smpboot: Total of 4 processors activated (13516.80 BogoMIPS) May 29 15:00:54 volumio-hc kernel: devtmpfs: initialized May 29 15:00:54 volumio-hc kernel: x86/mm: Memory block size: 128MB May 29 15:00:54 volumio-hc kernel: ACPI: PM: Registering ACPI NVS region [mem 0x7581b000-0x758dafff] (786432 bytes) May 29 15:00:54 volumio-hc kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns May 29 15:00:54 volumio-hc kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 29 15:00:54 volumio-hc kernel: pinctrl core: initialized pinctrl subsystem May 29 15:00:54 volumio-hc kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 29 15:00:54 volumio-hc kernel: audit: initializing netlink subsys (disabled) May 29 15:00:54 volumio-hc kernel: thermal_sys: Registered thermal governor 'fair_share' May 29 15:00:54 volumio-hc kernel: thermal_sys: Registered thermal governor 'bang_bang' May 29 15:00:54 volumio-hc kernel: thermal_sys: Registered thermal governor 'step_wise' May 29 15:00:54 volumio-hc kernel: thermal_sys: Registered thermal governor 'user_space' May 29 15:00:54 volumio-hc kernel: EISA bus registered May 29 15:00:54 volumio-hc kernel: audit: type=2000 audit(1748523649.000:1): state=initialized audit_enabled=0 res=1 May 29 15:00:54 volumio-hc kernel: cpuidle: using governor ladder May 29 15:00:54 volumio-hc kernel: cpuidle: using governor menu May 29 15:00:54 volumio-hc kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 29 15:00:54 volumio-hc kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xc0000000-0xcfffffff] (base 0xc0000000) May 29 15:00:54 volumio-hc kernel: PCI: not using MMCONFIG May 29 15:00:54 volumio-hc kernel: PCI: Using configuration type 1 for base access May 29 15:00:54 volumio-hc kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance' May 29 15:00:54 volumio-hc kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 29 15:00:54 volumio-hc kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 29 15:00:54 volumio-hc kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 29 15:00:54 volumio-hc kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 29 15:00:54 volumio-hc kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 29 15:00:54 volumio-hc kernel: ACPI: Added _OSI(Module Device) May 29 15:00:54 volumio-hc kernel: ACPI: Added _OSI(Processor Device) May 29 15:00:54 volumio-hc kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 29 15:00:54 volumio-hc kernel: ACPI: Added _OSI(Processor Aggregator Device) May 29 15:00:54 volumio-hc kernel: ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PC00.TXHC.RHUB.SS01], AE_NOT_FOUND (20230628/dswload2-162) May 29 15:00:54 volumio-hc kernel: ACPI Error: AE_NOT_FOUND, During name lookup/catalog (20230628/psobject-220) May 29 15:00:54 volumio-hc kernel: ACPI: Skipping parse of AML opcode: OpcodeName unavailable (0x0010) May 29 15:00:54 volumio-hc kernel: ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PC00.TXHC.RHUB.SS02], AE_NOT_FOUND (20230628/dswload2-162) May 29 15:00:54 volumio-hc kernel: ACPI Error: AE_NOT_FOUND, During name lookup/catalog (20230628/psobject-220) May 29 15:00:54 volumio-hc kernel: ACPI: Skipping parse of AML opcode: OpcodeName unavailable (0x0010) May 29 15:00:54 volumio-hc kernel: ACPI: 12 ACPI AML tables successfully acquired and loaded May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100B96400 000394 (v02 PmRef Cpu0Cst 00003001 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100B96800 0003B3 (v02 PmRef Cpu0Ist 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100B5D400 0001AB (v02 PmRef Cpu0Psd 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100A2A800 0004B5 (v02 PmRef Cpu0Hwp 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100A22000 001BAF (v02 PmRef ApIst 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100A24000 001038 (v02 PmRef ApHwp 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100A26000 001349 (v02 PmRef ApPsd 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: Dynamic OEM Table Load: May 29 15:00:54 volumio-hc kernel: ACPI: SSDT 0xFFFF888100A31000 000FBB (v02 PmRef ApCst 00003000 INTL 20200717) May 29 15:00:54 volumio-hc kernel: ACPI: _OSC evaluated successfully for all CPUs May 29 15:00:54 volumio-hc kernel: ACPI: Interpreter enabled May 29 15:00:54 volumio-hc kernel: ACPI: PM: (supports S0 S3 S4 S5) May 29 15:00:54 volumio-hc kernel: ACPI: Using IOAPIC for interrupt routing May 29 15:00:54 volumio-hc kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xc0000000-0xcfffffff] (base 0xc0000000) May 29 15:00:54 volumio-hc kernel: PCI: MMCONFIG at [mem 0xc0000000-0xcfffffff] reserved as ACPI motherboard resource May 29 15:00:54 volumio-hc kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 29 15:00:54 volumio-hc kernel: PCI: Ignoring E820 reservations for host bridge windows May 29 15:00:54 volumio-hc kernel: ACPI: Enabled 7 GPEs in block 00 to 7F May 29 15:00:54 volumio-hc kernel: ACPI: \_SB_.PC00.CNVW.WRST: New power resource May 29 15:00:54 volumio-hc kernel: ACPI: \_TZ_.FN00: New power resource May 29 15:00:54 volumio-hc kernel: ACPI: \_TZ_.FN01: New power resource May 29 15:00:54 volumio-hc kernel: ACPI: \_TZ_.FN02: New power resource May 29 15:00:54 volumio-hc kernel: ACPI: \_TZ_.FN03: New power resource May 29 15:00:54 volumio-hc kernel: ACPI: \_TZ_.FN04: New power resource May 29 15:00:54 volumio-hc kernel: ACPI: \PIN_: New power resource May 29 15:00:54 volumio-hc kernel: ACPI: PCI Root Bridge [PC00] (domain 0000 [bus 00-fe]) May 29 15:00:54 volumio-hc kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 29 15:00:54 volumio-hc kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR] May 29 15:00:54 volumio-hc kernel: PCI host bridge to bus 0000:00 May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000fffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: root bus resource [mem 0x80400000-0xbfffffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: root bus resource [mem 0x4000000000-0x7fffffffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: root bus resource [bus 00-fe] May 29 15:00:54 volumio-hc kernel: pci 0000:00:00.0: [8086:4618] type 00 class 0x060000 May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: [8086:46d2] type 00 class 0x030000 May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: reg 0x10: [mem 0x6000000000-0x6000ffffff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: reg 0x18: [mem 0x4000000000-0x400fffffff 64bit pref] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: reg 0x20: [io 0x6000-0x603f] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: DMAR: Skip IOMMU disabling for graphics May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: reg 0x344: [mem 0x00000000-0x00ffffff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: VF(n) BAR0 space: [mem 0x00000000-0x06ffffff 64bit] (contains BAR0 for 7 VFs) May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: reg 0x34c: [mem 0x00000000-0x1fffffff 64bit pref] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: VF(n) BAR2 space: [mem 0x00000000-0xdfffffff 64bit pref] (contains BAR2 for 7 VFs) May 29 15:00:54 volumio-hc kernel: pci 0000:00:14.0: [8086:54ed] type 00 class 0x0c0330 May 29 15:00:54 volumio-hc kernel: pci 0000:00:14.0: reg 0x10: [mem 0x6001100000-0x600110ffff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:14.0: PME# supported from D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:14.2: [8086:54ef] type 00 class 0x050000 May 29 15:00:54 volumio-hc kernel: pci 0000:00:14.2: reg 0x10: [mem 0x6001114000-0x6001117fff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:14.2: reg 0x18: [mem 0x600111a000-0x600111afff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:16.0: [8086:54e0] type 00 class 0x078000 May 29 15:00:54 volumio-hc kernel: pci 0000:00:16.0: reg 0x10: [mem 0x6001119000-0x6001119fff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:16.0: PME# supported from D3hot May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: [8086:54d3] type 00 class 0x010601 May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: reg 0x10: [mem 0x80700000-0x80701fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: reg 0x14: [mem 0x80703000-0x807030ff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: reg 0x18: [io 0x6090-0x6097] May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: reg 0x1c: [io 0x6080-0x6083] May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: reg 0x20: [io 0x6060-0x607f] May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: reg 0x24: [mem 0x80702000-0x807027ff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:17.0: PME# supported from D3hot May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: [8086:54bb] type 01 class 0x060400 May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: [8086:54b2] type 01 class 0x060400 May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: [8086:54b3] type 01 class 0x060400 May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: PME# supported from D0 D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.0: [8086:5482] type 00 class 0x060100 May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.3: [8086:54c8] type 00 class 0x040300 May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.3: reg 0x10: [mem 0x6001110000-0x6001113fff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.3: reg 0x20: [mem 0x6001000000-0x60010fffff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.3: PME# supported from D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.4: [8086:54a3] type 00 class 0x0c0500 May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.4: reg 0x10: [mem 0x6001118000-0x60011180ff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.4: reg 0x20: [io 0xefa0-0xefbf] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.5: [8086:54a4] type 00 class 0x0c8000 May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.5: reg 0x10: [mem 0xfe010000-0xfe010fff] May 29 15:00:54 volumio-hc kernel: pci 0000:01:00.0: [10ec:8168] type 00 class 0x020000 May 29 15:00:54 volumio-hc kernel: pci 0000:01:00.0: reg 0x10: [io 0x5000-0x50ff] May 29 15:00:54 volumio-hc kernel: pci 0000:01:00.0: reg 0x18: [mem 0x80604000-0x80604fff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:01:00.0: reg 0x20: [mem 0x80600000-0x80603fff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:01:00.0: supports D1 D2 May 29 15:00:54 volumio-hc kernel: pci 0000:01:00.0: PME# supported from D0 D1 D2 D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: PCI bridge to [bus 01] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: bridge window [io 0x5000-0x5fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: bridge window [mem 0x80600000-0x806fffff] May 29 15:00:54 volumio-hc kernel: pci 0000:02:00.0: [10ec:c821] type 00 class 0x028000 May 29 15:00:54 volumio-hc kernel: pci 0000:02:00.0: reg 0x10: [io 0x4000-0x40ff] May 29 15:00:54 volumio-hc kernel: pci 0000:02:00.0: reg 0x18: [mem 0x80500000-0x8050ffff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:02:00.0: supports D1 D2 May 29 15:00:54 volumio-hc kernel: pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: PCI bridge to [bus 02] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: bridge window [io 0x4000-0x4fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: bridge window [mem 0x80500000-0x805fffff] May 29 15:00:54 volumio-hc kernel: pci 0000:03:00.0: [10ec:8168] type 00 class 0x020000 May 29 15:00:54 volumio-hc kernel: pci 0000:03:00.0: reg 0x10: [io 0x3000-0x30ff] May 29 15:00:54 volumio-hc kernel: pci 0000:03:00.0: reg 0x18: [mem 0x80404000-0x80404fff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:03:00.0: reg 0x20: [mem 0x80400000-0x80403fff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:03:00.0: supports D1 D2 May 29 15:00:54 volumio-hc kernel: pci 0000:03:00.0: PME# supported from D0 D1 D2 D3hot D3cold May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: PCI bridge to [bus 03] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: bridge window [io 0x3000-0x3fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: bridge window [mem 0x80400000-0x804fffff] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: on NUMA node 0 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 0 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 1 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 0 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 0 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 0 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 0 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 0 May 29 15:00:54 volumio-hc kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 0 May 29 15:00:54 volumio-hc kernel: iommu: Default domain type: Translated May 29 15:00:54 volumio-hc kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 29 15:00:54 volumio-hc kernel: SCSI subsystem initialized May 29 15:00:54 volumio-hc kernel: libata version 3.00 loaded. May 29 15:00:54 volumio-hc kernel: ACPI: bus type USB registered May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver usbfs May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver hub May 29 15:00:54 volumio-hc kernel: usbcore: registered new device driver usb May 29 15:00:54 volumio-hc kernel: efivars: Registered efivars operations May 29 15:00:54 volumio-hc kernel: PCI: Using ACPI for IRQ routing May 29 15:00:54 volumio-hc kernel: PCI: pci_cache_line_size set to 64 bytes May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.5: can't claim BAR 0 [mem 0xfe010000-0xfe010fff]: no compatible bridge window May 29 15:00:54 volumio-hc kernel: e820: reserve RAM buffer [mem 0x0009e000-0x0009ffff] May 29 15:00:54 volumio-hc kernel: e820: reserve RAM buffer [mem 0x6eb9d000-0x6fffffff] May 29 15:00:54 volumio-hc kernel: e820: reserve RAM buffer [mem 0x7264b000-0x73ffffff] May 29 15:00:54 volumio-hc kernel: e820: reserve RAM buffer [mem 0x76000000-0x77ffffff] May 29 15:00:54 volumio-hc kernel: e820: reserve RAM buffer [mem 0x47fc00000-0x47fffffff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: vgaarb: bridge control possible May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 29 15:00:54 volumio-hc kernel: vgaarb: loaded May 29 15:00:54 volumio-hc kernel: clocksource: Switched to clocksource tsc-early May 29 15:00:54 volumio-hc kernel: VFS: Disk quotas dquot_6.6.0 May 29 15:00:54 volumio-hc kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 29 15:00:54 volumio-hc kernel: pnp: PnP ACPI init May 29 15:00:54 volumio-hc kernel: system 00:00: [io 0x0680-0x069f] has been reserved May 29 15:00:54 volumio-hc kernel: system 00:00: [io 0x164e-0x164f] has been reserved May 29 15:00:54 volumio-hc kernel: system 00:01: [io 0x1854-0x1857] has been reserved May 29 15:00:54 volumio-hc kernel: pnp 00:02: disabling [mem 0xc0000000-0xcfffffff] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref] May 29 15:00:54 volumio-hc kernel: system 00:02: [mem 0xfedc0000-0xfedc7fff] has been reserved May 29 15:00:54 volumio-hc kernel: system 00:02: [mem 0xfeda0000-0xfeda0fff] has been reserved May 29 15:00:54 volumio-hc kernel: system 00:02: [mem 0xfeda1000-0xfeda1fff] has been reserved May 29 15:00:54 volumio-hc kernel: system 00:02: [mem 0xfed20000-0xfed7ffff] could not be reserved May 29 15:00:54 volumio-hc kernel: system 00:02: [mem 0xfed90000-0xfed93fff] has been reserved May 29 15:00:54 volumio-hc kernel: system 00:02: [mem 0xfed45000-0xfed8ffff] could not be reserved May 29 15:00:54 volumio-hc kernel: system 00:02: [mem 0xfee00000-0xfeefffff] could not be reserved May 29 15:00:54 volumio-hc kernel: system 00:03: [io 0x2000-0x20fe] has been reserved May 29 15:00:54 volumio-hc kernel: pnp: PnP ACPI: found 5 devices May 29 15:00:54 volumio-hc kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 29 15:00:54 volumio-hc kernel: NET: Registered PF_INET protocol family May 29 15:00:54 volumio-hc kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 29 15:00:54 volumio-hc kernel: tcp_listen_portaddr_hash hash table entries: 8192 (order: 5, 131072 bytes, linear) May 29 15:00:54 volumio-hc kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 29 15:00:54 volumio-hc kernel: TCP established hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 29 15:00:54 volumio-hc kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 29 15:00:54 volumio-hc kernel: TCP: Hash tables configured (established 131072 bind 65536) May 29 15:00:54 volumio-hc kernel: UDP hash table entries: 8192 (order: 6, 262144 bytes, linear) May 29 15:00:54 volumio-hc kernel: UDP-Lite hash table entries: 8192 (order: 6, 262144 bytes, linear) May 29 15:00:54 volumio-hc kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: max bus depth: 1 pci_try_num: 2 May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: BAR 9: assigned [mem 0x4020000000-0x40ffffffff 64bit pref] May 29 15:00:54 volumio-hc kernel: pci 0000:00:02.0: BAR 7: assigned [mem 0x4010000000-0x4016ffffff 64bit] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1f.5: BAR 0: assigned [mem 0x80704000-0x80704fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: PCI bridge to [bus 01] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: bridge window [io 0x5000-0x5fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1c.0: bridge window [mem 0x80600000-0x806fffff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: PCI bridge to [bus 02] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: bridge window [io 0x4000-0x4fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.0: bridge window [mem 0x80500000-0x805fffff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: PCI bridge to [bus 03] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: bridge window [io 0x3000-0x3fff] May 29 15:00:54 volumio-hc kernel: pci 0000:00:1d.3: bridge window [mem 0x80400000-0x804fffff] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: resource 7 [mem 0x000e0000-0x000fffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: resource 8 [mem 0x80400000-0xbfffffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:00: resource 9 [mem 0x4000000000-0x7fffffffff window] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:01: resource 0 [io 0x5000-0x5fff] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:01: resource 1 [mem 0x80600000-0x806fffff] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:02: resource 0 [io 0x4000-0x4fff] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:02: resource 1 [mem 0x80500000-0x805fffff] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:03: resource 0 [io 0x3000-0x3fff] May 29 15:00:54 volumio-hc kernel: pci_bus 0000:03: resource 1 [mem 0x80400000-0x804fffff] May 29 15:00:54 volumio-hc kernel: PCI: CLS 64 bytes, default 64 May 29 15:00:54 volumio-hc kernel: DMAR: Host address width 39 May 29 15:00:54 volumio-hc kernel: DMAR: DRHD base: 0x000000fed90000 flags: 0x0 May 29 15:00:54 volumio-hc kernel: DMAR: dmar0: reg_base_addr fed90000 ver 4:0 cap 1c0000c40660462 ecap 29a00f0505e May 29 15:00:54 volumio-hc kernel: DMAR: DRHD base: 0x000000fed91000 flags: 0x1 May 29 15:00:54 volumio-hc kernel: DMAR: dmar1: reg_base_addr fed91000 ver 5:0 cap d2008c40660462 ecap f050da May 29 15:00:54 volumio-hc kernel: DMAR: RMRR base: 0x0000007c000000 end: 0x000000803fffff May 29 15:00:54 volumio-hc kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 29 15:00:54 volumio-hc kernel: software IO TLB: mapped [mem 0x00000000663ec000-0x000000006a3ec000] (64MB) May 29 15:00:54 volumio-hc kernel: Trying to unpack rootfs image as initramfs... May 29 15:00:54 volumio-hc kernel: RAPL PMU: API unit is 2^-32 Joules, 3 fixed counters, 655360 ms ovfl timer May 29 15:00:54 volumio-hc kernel: RAPL PMU: hw unit of domain pp0-core 2^-14 Joules May 29 15:00:54 volumio-hc kernel: RAPL PMU: hw unit of domain package 2^-14 Joules May 29 15:00:54 volumio-hc kernel: RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules May 29 15:00:54 volumio-hc kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x185ac6cdb26, max_idle_ns: 440795262339 ns May 29 15:00:54 volumio-hc kernel: clocksource: Switched to clocksource tsc May 29 15:00:54 volumio-hc kernel: platform rtc_cmos: registered platform RTC device (no PNP device found) May 29 15:00:54 volumio-hc kernel: Initialise system trusted keyrings May 29 15:00:54 volumio-hc kernel: workingset: timestamp_bits=46 max_order=22 bucket_order=0 May 29 15:00:54 volumio-hc kernel: zbud: loaded May 29 15:00:54 volumio-hc kernel: Key type asymmetric registered May 29 15:00:54 volumio-hc kernel: Asymmetric key parser 'x509' registered May 29 15:00:54 volumio-hc kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) May 29 15:00:54 volumio-hc kernel: io scheduler mq-deadline registered May 29 15:00:54 volumio-hc kernel: io scheduler kyber registered May 29 15:00:54 volumio-hc kernel: pcieport 0000:00:1c.0: PME: Signaling with IRQ 120 May 29 15:00:54 volumio-hc kernel: pcieport 0000:00:1c.0: AER: enabled with IRQ 120 May 29 15:00:54 volumio-hc kernel: pcieport 0000:00:1d.0: PME: Signaling with IRQ 121 May 29 15:00:54 volumio-hc kernel: pcieport 0000:00:1d.0: AER: enabled with IRQ 121 May 29 15:00:54 volumio-hc kernel: pcieport 0000:00:1d.3: PME: Signaling with IRQ 122 May 29 15:00:54 volumio-hc kernel: pcieport 0000:00:1d.3: AER: enabled with IRQ 122 May 29 15:00:54 volumio-hc kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 May 29 15:00:54 volumio-hc kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input0 May 29 15:00:54 volumio-hc kernel: ACPI: button: Sleep Button [SLPB] May 29 15:00:54 volumio-hc kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 May 29 15:00:54 volumio-hc kernel: ACPI: button: Power Button [PWRB] May 29 15:00:54 volumio-hc kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 May 29 15:00:54 volumio-hc kernel: ACPI: button: Power Button [PWRF] May 29 15:00:54 volumio-hc kernel: thermal LNXTHERM:00: registered as thermal_zone0 May 29 15:00:54 volumio-hc kernel: ACPI: thermal: Thermal Zone [TZ00] (28 C) May 29 15:00:54 volumio-hc kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled May 29 15:00:54 volumio-hc kernel: hpet_acpi_add: no address or irqs in _CRS May 29 15:00:54 volumio-hc kernel: Linux agpgart interface v0.103 May 29 15:00:54 volumio-hc kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller May 29 15:00:54 volumio-hc kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 May 29 15:00:54 volumio-hc kernel: xhci_hcd 0000:00:14.0: hcc params 0x20007fc1 hci version 0x120 quirks 0x0000100200009810 May 29 15:00:54 volumio-hc kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller May 29 15:00:54 volumio-hc kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2 May 29 15:00:54 volumio-hc kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.1 Enhanced SuperSpeed May 29 15:00:54 volumio-hc kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 May 29 15:00:54 volumio-hc kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 29 15:00:54 volumio-hc kernel: usb usb1: Product: xHCI Host Controller May 29 15:00:54 volumio-hc kernel: usb usb1: Manufacturer: Linux 6.6.32-volumio xhci-hcd May 29 15:00:54 volumio-hc kernel: usb usb1: SerialNumber: 0000:00:14.0 May 29 15:00:54 volumio-hc kernel: hub 1-0:1.0: USB hub found May 29 15:00:54 volumio-hc kernel: hub 1-0:1.0: 12 ports detected May 29 15:00:54 volumio-hc kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 May 29 15:00:54 volumio-hc kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 29 15:00:54 volumio-hc kernel: usb usb2: Product: xHCI Host Controller May 29 15:00:54 volumio-hc kernel: usb usb2: Manufacturer: Linux 6.6.32-volumio xhci-hcd May 29 15:00:54 volumio-hc kernel: usb usb2: SerialNumber: 0000:00:14.0 May 29 15:00:54 volumio-hc kernel: hub 2-0:1.0: USB hub found May 29 15:00:54 volumio-hc kernel: hub 2-0:1.0: 4 ports detected May 29 15:00:54 volumio-hc kernel: i8042: PNP: No PS/2 controller found. May 29 15:00:54 volumio-hc kernel: mousedev: PS/2 mouse device common for all mice May 29 15:00:54 volumio-hc kernel: rtc_cmos rtc_cmos: RTC can wake from S4 May 29 15:00:54 volumio-hc kernel: rtc_cmos rtc_cmos: registered as rtc0 May 29 15:00:54 volumio-hc kernel: rtc_cmos rtc_cmos: setting system clock to 2025-05-29T13:00:49 UTC (1748523649) May 29 15:00:54 volumio-hc kernel: rtc_cmos rtc_cmos: alarms up to one month, y3k, 114 bytes nvram May 29 15:00:54 volumio-hc kernel: i2c_dev: i2c /dev entries driver May 29 15:00:54 volumio-hc kernel: platform eisa.0: Probing EISA bus 0 May 29 15:00:54 volumio-hc kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard May 29 15:00:54 volumio-hc kernel: intel_pstate: Intel P-state driver initializing May 29 15:00:54 volumio-hc kernel: intel_pstate: HWP enabled May 29 15:00:54 volumio-hc kernel: ledtrig-cpu: registered to indicate activity on CPUs May 29 15:00:54 volumio-hc kernel: intel_pmc_core INT33A1:00: initialized May 29 15:00:54 volumio-hc kernel: NET: Registered PF_INET6 protocol family May 29 15:00:54 volumio-hc kernel: Segment Routing with IPv6 May 29 15:00:54 volumio-hc kernel: In-situ OAM (IOAM) with IPv6 May 29 15:00:54 volumio-hc kernel: mip6: Mobile IPv6 May 29 15:00:54 volumio-hc kernel: NET: Registered PF_PACKET protocol family May 29 15:00:54 volumio-hc kernel: mpls_gso: MPLS GSO support May 29 15:00:54 volumio-hc kernel: microcode: Microcode Update Driver: v2.2. May 29 15:00:54 volumio-hc kernel: IPI shorthand broadcast: enabled May 29 15:00:54 volumio-hc kernel: sched_clock: Marking stable (293394349, 6588102)->(317832884, -17850433) May 29 15:00:54 volumio-hc kernel: Loading compiled-in X.509 certificates May 29 15:00:54 volumio-hc kernel: clk: Disabling unused clocks May 29 15:00:54 volumio-hc kernel: usb 1-1: new full-speed USB device number 2 using xhci_hcd May 29 15:00:54 volumio-hc kernel: usb 1-1: New USB device found, idVendor=0bda, idProduct=c821, bcdDevice= 1.10 May 29 15:00:54 volumio-hc kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 29 15:00:54 volumio-hc kernel: usb 1-1: Product: Bluetooth Radio May 29 15:00:54 volumio-hc kernel: usb 1-1: Manufacturer: Realtek May 29 15:00:54 volumio-hc kernel: usb 1-1: SerialNumber: 00e04c000001 May 29 15:00:54 volumio-hc kernel: usb 1-2: new high-speed USB device number 3 using xhci_hcd May 29 15:00:54 volumio-hc kernel: usb 1-2: New USB device found, idVendor=0424, idProduct=2412, bcdDevice= b.b2 May 29 15:00:54 volumio-hc kernel: usb 1-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 29 15:00:54 volumio-hc kernel: hub 1-2:1.0: USB hub found May 29 15:00:54 volumio-hc kernel: hub 1-2:1.0: 1 port detected May 29 15:00:54 volumio-hc kernel: Freeing initrd memory: 68108K May 29 15:00:54 volumio-hc kernel: Freeing unused kernel image (initmem) memory: 2988K May 29 15:00:54 volumio-hc kernel: Write protecting the kernel read-only data: 20480k May 29 15:00:54 volumio-hc kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K May 29 15:00:54 volumio-hc kernel: Run /init as init process May 29 15:00:54 volumio-hc kernel: with arguments: May 29 15:00:54 volumio-hc kernel: /init May 29 15:00:54 volumio-hc kernel: splash May 29 15:00:54 volumio-hc kernel: with environment: May 29 15:00:54 volumio-hc kernel: HOME=/ May 29 15:00:54 volumio-hc kernel: TERM=linux May 29 15:00:54 volumio-hc kernel: BOOT_IMAGE=/vmlinuz May 29 15:00:54 volumio-hc kernel: biosdevname=0 May 29 15:00:54 volumio-hc kernel: imgpart=UUID=61cb7b6c-d4cb-431d-9399-36325980667c May 29 15:00:54 volumio-hc kernel: bootpart=UUID=5D14-3AFB May 29 15:00:54 volumio-hc kernel: datapart=UUID=77451eee-ccc4-4fda-b095-beb6b9a2c81d May 29 15:00:54 volumio-hc kernel: hwdevice=x86 May 29 15:00:54 volumio-hc kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg May 29 15:00:54 volumio-hc kernel: imgfile=/volumio_current.sqsh May 29 15:00:54 volumio-hc kernel: use_kmsg=no May 29 15:00:54 volumio-hc kernel: ahci 0000:00:17.0: version 3.0 May 29 15:00:54 volumio-hc kernel: ahci 0000:00:17.0: AHCI 0001.0301 32 slots 1 ports 6 Gbps 0x2 impl SATA mode May 29 15:00:54 volumio-hc kernel: ahci 0000:00:17.0: flags: 64bit ncq sntf pm clo only pio slum part deso sadm sds May 29 15:00:54 volumio-hc kernel: ACPI: bus type drm_connector registered May 29 15:00:54 volumio-hc kernel: i801_smbus 0000:00:1f.4: enabling device (0000 -> 0003) May 29 15:00:54 volumio-hc kernel: i801_smbus 0000:00:1f.4: SPD Write Disable is set May 29 15:00:54 volumio-hc kernel: i801_smbus 0000:00:1f.4: SMBus using PCI interrupt May 29 15:00:54 volumio-hc kernel: scsi host0: ahci May 29 15:00:54 volumio-hc kernel: scsi host1: ahci May 29 15:00:54 volumio-hc kernel: usb 1-6: new full-speed USB device number 4 using xhci_hcd May 29 15:00:54 volumio-hc kernel: i2c i2c-0: 4/8 memory slots populated (from DMI) May 29 15:00:54 volumio-hc kernel: i2c i2c-0: Systems with more than 4 memory slots not supported yet, not instantiating SPD May 29 15:00:54 volumio-hc kernel: ata1: DUMMY May 29 15:00:54 volumio-hc kernel: ata2: SATA max UDMA/133 abar m2048@0x80702000 port 0x80702180 irq 124 May 29 15:00:54 volumio-hc kernel: usb 1-6: New USB device found, idVendor=0573, idProduct=1573, bcdDevice=80.07 May 29 15:00:54 volumio-hc kernel: usb 1-6: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 29 15:00:54 volumio-hc kernel: usb 1-6: Product: USB Audio and HID May 29 15:00:54 volumio-hc kernel: usb 1-6: Manufacturer: CSCTEK May 29 15:00:54 volumio-hc kernel: usb 1-6: SerialNumber: A34004801402 May 29 15:00:54 volumio-hc kernel: usb 1-2.1: new high-speed USB device number 5 using xhci_hcd May 29 15:00:54 volumio-hc kernel: ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 300) May 29 15:00:54 volumio-hc kernel: ata2.00: ATA-9: NS512GSSD330, W0406B0, max UDMA/133 May 29 15:00:54 volumio-hc kernel: ata2.00: 1000215216 sectors, multi 1: LBA48 NCQ (depth 32), AA May 29 15:00:54 volumio-hc kernel: ata2.00: configured for UDMA/133 May 29 15:00:54 volumio-hc kernel: scsi 1:0:0:0: Direct-Access ATA NS512GSSD330 6B0 PQ: 0 ANSI: 5 May 29 15:00:54 volumio-hc kernel: sd 1:0:0:0: [sda] 1000215216 512-byte logical blocks: (512 GB/477 GiB) May 29 15:00:54 volumio-hc kernel: sd 1:0:0:0: [sda] Write Protect is off May 29 15:00:54 volumio-hc kernel: sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00 May 29 15:00:54 volumio-hc kernel: sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 29 15:00:54 volumio-hc kernel: sd 1:0:0:0: [sda] Preferred minimum I/O size 512 bytes May 29 15:00:54 volumio-hc kernel: usb 1-2.1: New USB device found, idVendor=20b1, idProduct=3033, bcdDevice=51.11 May 29 15:00:54 volumio-hc kernel: usb 1-2.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 May 29 15:00:54 volumio-hc kernel: usb 1-2.1: Product: Gustard USB Audio 2.0 May 29 15:00:54 volumio-hc kernel: usb 1-2.1: Manufacturer: Gustard May 29 15:00:54 volumio-hc kernel: sda: sda1 sda2 sda3 May 29 15:00:54 volumio-hc kernel: sd 1:0:0:0: [sda] Attached SCSI disk May 29 15:00:54 volumio-hc kernel: r8169 0000:01:00.0 eth0: RTL8168h/8111h, 68:1d:ef:35:3a:ce, XID 541, IRQ 125 May 29 15:00:54 volumio-hc kernel: r8169 0000:01:00.0 eth0: jumbo features [frames: 9194 bytes, tx checksumming: ko] May 29 15:00:54 volumio-hc kernel: r8169 0000:03:00.0 eth1: RTL8168h/8111h, 68:1d:ef:35:3a:d1, XID 541, IRQ 126 May 29 15:00:54 volumio-hc kernel: r8169 0000:03:00.0 eth1: jumbo features [frames: 9194 bytes, tx checksumming: ko] May 29 15:00:54 volumio-hc kernel: usb 1-7: new full-speed USB device number 6 using xhci_hcd May 29 15:00:54 volumio-hc kernel: usb 1-7: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice=81.34 May 29 15:00:54 volumio-hc kernel: usb 1-7: New USB device strings: Mfr=0, Product=2, SerialNumber=0 May 29 15:00:54 volumio-hc kernel: usb 1-7: Product: USB Serial May 29 15:00:54 volumio-hc kernel: hid: raw HID events driver (C) Jiri Kosina May 29 15:00:54 volumio-hc kernel: usbhid 1-6:1.3: couldn't find an input interrupt endpoint May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: vgaarb: deactivate vga console May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] Using Transparent Hugepages May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver usbhid May 29 15:00:54 volumio-hc kernel: usbhid: USB HID core driver May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem May 29 15:00:54 volumio-hc kernel: hid-generic 0003:20B1:3033.0001: hiddev0,hidraw0: USB HID v1.10 Device [Gustard Gustard USB Audio 2.0] on usb-0000:00:14.0-2.1/input3 May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] Finished loading DMC firmware i915/adlp_dmc.bin (v2.20) May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] GT0: GuC firmware i915/tgl_guc_70.bin version 70.5.1 May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] GT0: HuC firmware i915/tgl_huc.bin version 7.9.3 May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] GT0: HuC: authenticated for all workloads May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] GT0: GUC: submission enabled May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] GT0: GUC: SLPC enabled May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] GT0: GUC: RC enabled May 29 15:00:54 volumio-hc kernel: [drm] Initialized i915 1.6.0 20201103 for 0000:00:02.0 on minor 0 May 29 15:00:54 volumio-hc kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) May 29 15:00:54 volumio-hc kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input3 May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes May 29 15:00:54 volumio-hc kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes May 29 15:00:54 volumio-hc kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 29 15:00:54 volumio-hc kernel: sdhci: Secure Digital Host Controller Interface driver May 29 15:00:54 volumio-hc kernel: sdhci: Copyright(c) Pierre Ossman May 29 15:00:54 volumio-hc kernel: fuse: init (API version 7.39) May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver usb-storage May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver uas May 29 15:00:54 volumio-hc kernel: [drm] radeon kernel modesetting enabled. May 29 15:00:54 volumio-hc kernel: scsi host2: pata_legacy May 29 15:00:54 volumio-hc kernel: ata3: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 May 29 15:00:54 volumio-hc kernel: scsi host2: pata_legacy May 29 15:00:54 volumio-hc kernel: ata4: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 May 29 15:00:54 volumio-hc kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 29 15:00:54 volumio-hc kernel: EXT4-fs (sda2): recovery complete May 29 15:00:54 volumio-hc kernel: EXT4-fs (sda2): mounted filesystem 61cb7b6c-d4cb-431d-9399-36325980667c r/w with ordered data mode. Quota mode: none. May 29 15:00:54 volumio-hc kernel: loop: module loaded May 29 15:00:54 volumio-hc kernel: block device autoloading is deprecated and will be removed. May 29 15:00:54 volumio-hc kernel: loop0: detected capacity change from 0 to 1725048 May 29 15:00:54 volumio-hc kernel: EXT4-fs (sda3): recovery complete May 29 15:00:54 volumio-hc kernel: EXT4-fs (sda3): mounted filesystem 77451eee-ccc4-4fda-b095-beb6b9a2c81d r/w with ordered data mode. Quota mode: none. May 29 15:00:54 volumio-hc systemd[1]: Inserted module 'autofs4' May 29 15:00:54 volumio-hc systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) May 29 15:00:54 volumio-hc systemd[1]: Detected architecture x86-64. May 29 15:00:54 volumio-hc systemd[1]: Set hostname to . May 29 15:00:54 volumio-hc systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. May 29 15:00:54 volumio-hc systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) May 29 15:00:54 volumio-hc systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 29 15:00:54 volumio-hc systemd[1]: Listening on initctl Compatibility Named Pipe. May 29 15:00:54 volumio-hc systemd[1]: Started Forward Password Requests to Wall Directory Watch. May 29 15:00:54 volumio-hc systemd[1]: Listening on Journal Audit Socket. May 29 15:00:54 volumio-hc systemd[1]: Listening on Journal Socket (/dev/log). May 29 15:00:54 volumio-hc systemd-journald[473]: Journal started May 29 15:00:54 volumio-hc systemd-journald[473]: Runtime journal (/run/log/journal/c11fdee23ced21dd746a26f464986581) is 3.7M, max 30.0M, 26.2M free. May 29 15:00:54 volumio-hc fake-hwclock[469]: Current system time: 2025-05-29 13:00:54 May 29 15:00:54 volumio-hc fake-hwclock[469]: fake-hwclock saved clock information is in the past: 2025-05-29 13:00:42 May 29 15:00:54 volumio-hc fake-hwclock[469]: To set system time to this saved clock anyway, use "force" May 29 15:00:54 volumio-hc systemd[1]: Started Restore / save the current clock. May 29 15:00:54 volumio-hc systemd[1]: Mounted FUSE Control File System. May 29 15:00:54 volumio-hc systemd[1]: Started Apply Kernel Variables. May 29 15:00:54 volumio-hc systemd[1]: Started Load/Save Random Seed. May 29 15:00:54 volumio-hc systemd[1]: dynamicswap.service: Succeeded. May 29 15:00:54 volumio-hc systemd[1]: Mounted RPC Pipe File System. May 29 15:00:54 volumio-hc kernel: RPC: Registered named UNIX socket transport module. May 29 15:00:54 volumio-hc kernel: RPC: Registered udp transport module. May 29 15:00:54 volumio-hc kernel: RPC: Registered tcp transport module. May 29 15:00:54 volumio-hc kernel: RPC: Registered tcp-with-tls transport module. May 29 15:00:54 volumio-hc kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 29 15:00:54 volumio-hc systemd[1]: Started Create System Users. May 29 15:00:54 volumio-hc systemd[1]: Starting Create Static Device Nodes in /dev... May 29 15:00:54 volumio-hc systemd[1]: Started Create Static Device Nodes in /dev. May 29 15:00:54 volumio-hc systemd[1]: Starting udev Kernel Device Manager... May 29 15:00:54 volumio-hc systemd[1]: Reached target Local File Systems (Pre). May 29 15:00:54 volumio-hc systemd[1]: Mounting /var/spool/cups... May 29 15:00:54 volumio-hc systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 29 15:00:54 volumio-hc systemd[1]: Mounting /var/log... May 29 15:00:54 volumio-hc systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 29 15:00:54 volumio-hc systemd[1]: Mounting /tmp... May 29 15:00:54 volumio-hc systemd[1]: Mounted /var/spool/cups. May 29 15:00:54 volumio-hc systemd[1]: Mounted /var/log. May 29 15:00:54 volumio-hc systemd[1]: Starting Flush Journal to Persistent Storage... May 29 15:00:54 volumio-hc systemd[1]: Mounting /var/spool/cups/tmp... May 29 15:00:54 volumio-hc systemd[1]: Mounted /tmp. May 29 15:00:54 volumio-hc systemd[1]: Mounted /var/spool/cups/tmp. May 29 15:00:54 volumio-hc systemd-journald[473]: Runtime journal (/run/log/journal/c11fdee23ced21dd746a26f464986581) is 3.7M, max 30.0M, 26.2M free. May 29 15:00:54 volumio-hc systemd-udevd[499]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 29 15:00:54 volumio-hc systemd[1]: Started udev Kernel Device Manager. May 29 15:00:54 volumio-hc systemd[1]: Started udev Coldplug all Devices. May 29 15:00:54 volumio-hc systemd[1]: Starting Show Plymouth Boot Screen... May 29 15:00:54 volumio-hc systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 29 15:00:54 volumio-hc systemd[1]: Started Flush Journal to Persistent Storage. May 29 15:00:54 volumio-hc systemd[1]: plymouth-start.service: Succeeded. May 29 15:00:54 volumio-hc systemd[1]: Started Show Plymouth Boot Screen. May 29 15:00:54 volumio-hc systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 29 15:00:54 volumio-hc systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 29 15:00:54 volumio-hc systemd[1]: Reached target Local Encrypted Volumes. May 29 15:00:54 volumio-hc systemd[1]: Reached target Paths. May 29 15:00:54 volumio-hc systemd-udevd[544]: Using default interface naming scheme 'v240'. May 29 15:00:54 volumio-hc systemd-udevd[537]: Using default interface naming scheme 'v240'. May 29 15:00:54 volumio-hc systemd-udevd[537]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 29 15:00:54 volumio-hc kernel: pstore: Using crash dump compression: deflate May 29 15:00:54 volumio-hc systemd-udevd[544]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 29 15:00:54 volumio-hc kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 May 29 15:00:54 volumio-hc kernel: pstore: Registered efi_pstore as persistent store backend May 29 15:00:54 volumio-hc kernel: cryptd: max_cpu_qlen set to 1000 May 29 15:00:54 volumio-hc systemd-udevd[526]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 29 15:00:54 volumio-hc kernel: mei_me 0000:00:16.0: enabling device (0000 -> 0002) May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver usbserial_generic May 29 15:00:54 volumio-hc kernel: usbserial: USB Serial support registered for generic May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver ch341 May 29 15:00:54 volumio-hc kernel: usbserial: USB Serial support registered for ch341-uart May 29 15:00:54 volumio-hc kernel: ch341 1-7:1.0: ch341-uart converter detected May 29 15:00:54 volumio-hc kernel: usb 1-7: ch341-uart converter now attached to ttyUSB0 May 29 15:00:54 volumio-hc kernel: AVX2 version of gcm_enc/dec engaged. May 29 15:00:54 volumio-hc kernel: AES CTR mode by8 optimization enabled May 29 15:00:54 volumio-hc kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 29 15:00:54 volumio-hc kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 29 15:00:54 volumio-hc kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 29 15:00:54 volumio-hc kernel: snd_hda_intel 0000:00:1f.3: enabling device (0000 -> 0002) May 29 15:00:54 volumio-hc kernel: snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops __SCT__tp_func_intel_frontbuffer_flush [i915]) May 29 15:00:54 volumio-hc kernel: usb 1-6: Warning! Unlikely big volume range (=511), cval->res is probably wrong. May 29 15:00:54 volumio-hc kernel: usb 1-6: [5] FU [Mic Capture Volume] ch = 1, val = -8192/-1/16 May 29 15:00:54 volumio-hc kernel: usb 1-6: Warning! Unlikely big volume range (=896), cval->res is probably wrong. May 29 15:00:54 volumio-hc kernel: usb 1-6: [6] FU [PCM Playback Volume] ch = 2, val = -16384/-2048/16 May 29 15:00:54 volumio-hc kernel: usb 1-2.1: 1:3 : unsupported format bits 0x100000000 May 29 15:00:54 volumio-hc kernel: usbcore: registered new interface driver snd-usb-audio May 29 15:00:54 volumio-hc kernel: input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input4 May 29 15:00:54 volumio-hc kernel: input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input5 May 29 15:00:54 volumio-hc kernel: input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input6 May 29 15:00:54 volumio-hc kernel: input: HDA Intel PCH HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input7 May 29 15:00:54 volumio-hc systemd-udevd[528]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:54 volumio-hc systemd-udevd[507]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:54 volumio-hc systemd-udevd[527]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:54 volumio-hc kernel: rtw_8821ce 0000:02:00.0: enabling device (0000 -> 0003) May 29 15:00:54 volumio-hc kernel: rtw_8821ce 0000:02:00.0: Firmware version 24.11.0, H2C version 12 May 29 15:00:54 volumio-hc systemd-udevd[534]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:55 volumio-hc kernel: Bluetooth: Core ver 2.22 May 29 15:00:55 volumio-hc kernel: NET: Registered PF_BLUETOOTH protocol family May 29 15:00:55 volumio-hc kernel: Bluetooth: HCI device and connection manager initialized May 29 15:00:55 volumio-hc kernel: Bluetooth: HCI socket layer initialized May 29 15:00:55 volumio-hc kernel: Bluetooth: L2CAP socket layer initialized May 29 15:00:55 volumio-hc kernel: Bluetooth: SCO socket layer initialized May 29 15:00:55 volumio-hc kernel: usbcore: registered new interface driver btusb May 29 15:00:55 volumio-hc kernel: Bluetooth: hci0: RTL: examining hci_ver=08 hci_rev=000c lmp_ver=08 lmp_subver=8821 May 29 15:00:55 volumio-hc kernel: Bluetooth: hci0: RTL: rom_version status=0 version=1 May 29 15:00:55 volumio-hc kernel: Bluetooth: hci0: RTL: loading rtl_bt/rtl8821c_fw.bin May 29 15:00:55 volumio-hc kernel: Bluetooth: hci0: RTL: loading rtl_bt/rtl8821c_config.bin May 29 15:00:55 volumio-hc kernel: Bluetooth: hci0: RTL: cfg_sz 10, total sz 34926 May 29 15:00:55 volumio-hc systemd-udevd[534]: Using default interface naming scheme 'v240'. May 29 15:00:55 volumio-hc systemd-udevd[534]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 29 15:00:55 volumio-hc systemd-udevd[527]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:55 volumio-hc systemd-udevd[529]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:55 volumio-hc systemd-udevd[537]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:55 volumio-hc systemd-udevd[509]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 15:00:55 volumio-hc systemd-udevd[538]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. May 29 15:00:55 volumio-hc systemd-udevd[531]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. May 29 15:00:55 volumio-hc kernel: alsactl[684]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 29 15:00:55 volumio-hc kernel: alsactl[675]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 29 15:00:55 volumio-hc systemd[1]: Found device NS512GSSD330 boot. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller being skipped. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in RTL8821CE 802.11ac PCIe Wireless Network Adapter being skipped. May 29 15:00:55 volumio-hc systemd[1]: Starting Show Plymouth Boot Screen... May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 29 15:00:55 volumio-hc systemd[1]: Starting File System Check on /dev/disk/by-uuid/5D14-3AFB... May 29 15:00:55 volumio-hc systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 29 15:00:55 volumio-hc systemd[1]: Starting Load/Save RF Kill Switch Status... May 29 15:00:55 volumio-hc systemd[1]: Started File System Check Daemon to report status. May 29 15:00:55 volumio-hc systemd[1]: Started Load/Save RF Kill Switch Status. May 29 15:00:55 volumio-hc systemd[1]: plymouth-start.service: Succeeded. May 29 15:00:55 volumio-hc systemd[1]: Started Show Plymouth Boot Screen. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 29 15:00:55 volumio-hc systemd-fsck[707]: fsck.fat 4.1 (2017-01-24) May 29 15:00:55 volumio-hc systemd-fsck[707]: There are differences between boot sector and its backup. May 29 15:00:55 volumio-hc systemd-fsck[707]: This is mostly harmless. Differences: (offset:original/backup) May 29 15:00:55 volumio-hc systemd-fsck[707]: 3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb May 29 15:00:55 volumio-hc systemd-fsck[707]: , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb May 29 15:00:55 volumio-hc systemd-fsck[707]: , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd May 29 15:00:55 volumio-hc systemd-fsck[707]: , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e May 29 15:00:55 volumio-hc systemd-fsck[707]: , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c May 29 15:00:55 volumio-hc systemd-fsck[707]: , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b May 29 15:00:55 volumio-hc systemd-fsck[707]: , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f May 29 15:00:55 volumio-hc systemd-fsck[707]: , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e May 29 15:00:55 volumio-hc systemd-fsck[707]: , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a May 29 15:00:55 volumio-hc systemd-fsck[707]: , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 282:2d/00, 283:15/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: , 508:18/00, 509:37/00 May 29 15:00:55 volumio-hc systemd-fsck[707]: Not automatically fixing this. May 29 15:00:55 volumio-hc systemd-fsck[707]: /dev/sda1: 23 files, 160486/344788 clusters May 29 15:00:55 volumio-hc systemd[1]: Started File System Check on /dev/disk/by-uuid/5D14-3AFB. May 29 15:00:55 volumio-hc systemd[1]: Mounting /boot... May 29 15:00:55 volumio-hc kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 29 15:00:55 volumio-hc systemd[1]: Mounted /boot. May 29 15:00:55 volumio-hc systemd[1]: Reached target Local File Systems. May 29 15:00:55 volumio-hc systemd[1]: Started ifup for wlan0. May 29 15:00:55 volumio-hc systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 29 15:00:55 volumio-hc systemd[1]: Started ifup for eth0. May 29 15:00:55 volumio-hc systemd[1]: Starting Preprocess NFS configuration... May 29 15:00:55 volumio-hc systemd[1]: Starting Create Volatile Files and Directories... May 29 15:00:55 volumio-hc systemd[1]: Received SIGRTMIN+20 from PID 361 (plymouthd). May 29 15:00:55 volumio-hc systemd[1]: plymouth-read-write.service: Succeeded. May 29 15:00:55 volumio-hc systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 29 15:00:55 volumio-hc systemd[1]: nfs-config.service: Succeeded. May 29 15:00:55 volumio-hc systemd[1]: Started Preprocess NFS configuration. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 29 15:00:55 volumio-hc systemd[1]: Reached target NFS client services. May 29 15:00:55 volumio-hc systemd[1]: Started Create Volatile Files and Directories. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 29 15:00:55 volumio-hc kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 May 29 15:00:55 volumio-hc systemd[1]: Starting RPC bind portmap service... May 29 15:00:55 volumio-hc kernel: 8021q: 802.1Q VLAN Support v1.8 May 29 15:00:55 volumio-hc systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 29 15:00:55 volumio-hc systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 29 15:00:55 volumio-hc systemd[1]: Started Update UTMP about System Boot/Shutdown. May 29 15:00:55 volumio-hc systemd[1]: Reached target System Initialization. May 29 15:00:55 volumio-hc systemd[1]: Started Daily man-db regeneration. May 29 15:00:55 volumio-hc systemd[1]: Listening on triggerhappy.socket. May 29 15:00:55 volumio-hc systemd[1]: Started Daily Cleanup of Temporary Directories. May 29 15:00:55 volumio-hc systemd[1]: Listening on D-Bus System Message Bus Socket. May 29 15:00:55 volumio-hc systemd[1]: Started Daily apt download activities. May 29 15:00:55 volumio-hc systemd[1]: Started Daily apt upgrade and clean activities. May 29 15:00:55 volumio-hc systemd[1]: Reached target Timers. May 29 15:00:55 volumio-hc systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 29 15:00:55 volumio-hc systemd[1]: Reached target Sockets. May 29 15:00:55 volumio-hc systemd[1]: Reached target Basic System. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 29 15:00:55 volumio-hc systemd[1]: Started volumio-remote-updater.service. May 29 15:00:55 volumio-hc systemd[1]: Started Manage Sound Card State (restore and store). May 29 15:00:55 volumio-hc systemd[1]: Started Volumio Iptables Module. May 29 15:00:55 volumio-hc systemd[1]: Started Volumio Log Rotation Service. May 29 15:00:55 volumio-hc alsactl[759]: alsactl 1.1.8 daemon started May 29 15:00:55 volumio-hc systemd[1]: Starting Login Service... May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 29 15:00:55 volumio-hc systemd[1]: Starting Save/Restore Sound Card State... May 29 15:00:55 volumio-hc systemd[1]: Starting Wireless Services... May 29 15:00:55 volumio-hc systemd[1]: Started UPnP Renderer front-end to MPD. May 29 15:00:55 volumio-hc systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 29 15:00:55 volumio-hc systemd[1]: Starting DHCP Client Daemon... May 29 15:00:55 volumio-hc systemd[1]: Starting Bluetooth service... May 29 15:00:55 volumio-hc systemd[1]: Started D-Bus System Message Bus. May 29 15:00:55 volumio-hc systemd[1]: Starting WPA supplicant... May 29 15:00:55 volumio-hc systemd[1]: Starting triggerhappy global hotkey daemon... May 29 15:00:55 volumio-hc systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 29 15:00:55 volumio-hc thd[771]: Unable to parse trigger line: May 29 15:00:55 volumio-hc thd[771]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 29 15:00:55 volumio-hc thd[771]: Unable to parse trigger line: May 29 15:00:55 volumio-hc thd[771]: Unable to parse trigger line: May 29 15:00:55 volumio-hc thd[771]: Found socket passed from systemd May 29 15:00:55 volumio-hc systemd-logind[762]: New seat seat0. May 29 15:00:55 volumio-hc systemd-logind[762]: Watching system buttons on /dev/input/event2 (Power Button) May 29 15:00:55 volumio-hc systemd-logind[762]: Watching system buttons on /dev/input/event1 (Power Button) May 29 15:00:55 volumio-hc systemd-logind[762]: Watching system buttons on /dev/input/event0 (Sleep Button) May 29 15:00:55 volumio-hc kernel: Bluetooth: hci0: RTL: fw version 0x75b8f098 May 29 15:00:55 volumio-hc avahi-daemon[772]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). May 29 15:00:55 volumio-hc avahi-daemon[772]: Successfully dropped root privileges. May 29 15:00:55 volumio-hc avahi-daemon[772]: avahi-daemon 0.7 starting up. May 29 15:00:55 volumio-hc volumio-remote-updater[758]: Error: No active session May 29 15:00:55 volumio-hc bluetoothd[768]: Bluetooth daemon 5.50 May 29 15:00:55 volumio-hc bluetoothd[768]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 29 15:00:55 volumio-hc avahi-daemon[772]: Successfully called chroot(). May 29 15:00:55 volumio-hc avahi-daemon[772]: Successfully dropped remaining capabilities. May 29 15:00:55 volumio-hc systemd[1]: Started Bluetooth service. May 29 15:00:55 volumio-hc systemd[1]: Started triggerhappy global hotkey daemon. May 29 15:00:55 volumio-hc systemd[1]: Started Save/Restore Sound Card State. May 29 15:00:55 volumio-hc systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 29 15:00:55 volumio-hc wpa_supplicant[770]: Successfully initialized wpa_supplicant May 29 15:00:55 volumio-hc systemd[1]: Started WPA supplicant. May 29 15:00:55 volumio-hc bluetoothd[768]: Starting SDP server May 29 15:00:55 volumio-hc systemd[1]: Reached target Sound Card. May 29 15:00:55 volumio-hc bluetoothd[768]: Excluding (cli) sap May 29 15:00:55 volumio-hc systemd[1]: Reached target Bluetooth. May 29 15:00:55 volumio-hc avahi-daemon[772]: Loading service file /services/volumio.service. May 29 15:00:55 volumio-hc systemd[1]: Started Login Service. May 29 15:00:55 volumio-hc dbus-daemon[769]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=768 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 29 15:00:55 volumio-hc bluetoothd[768]: Bluetooth management interface 1.22 initialized May 29 15:00:55 volumio-hc kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 29 15:00:55 volumio-hc kernel: Bluetooth: BNEP filters: protocol multicast May 29 15:00:55 volumio-hc kernel: Bluetooth: BNEP socket layer initialized May 29 15:00:55 volumio-hc kernel: Bluetooth: MGMT ver 1.22 May 29 15:00:55 volumio-hc volumio[765]: Could not open config: /tmp/upmpdcli.conf May 29 15:00:55 volumio-hc systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 29 15:00:55 volumio-hc systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 29 15:00:55 volumio-hc systemd[1]: Starting Hostname Service... May 29 15:00:55 volumio-hc wireless.js[764]: Cleaning previous... May 29 15:00:55 volumio-hc volumio-remote-updater[758]: [2025-05-29 15:00:55] [info] asio async_connect error: system:111 (Connection refused) May 29 15:00:55 volumio-hc volumio-remote-updater[758]: [2025-05-29 15:00:55] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 29 15:00:55 volumio-hc volumio-remote-updater[758]: [2025-05-29 15:00:55] [error] handle_connect error: Underlying Transport Error May 29 15:00:55 volumio-hc avahi-daemon[772]: Network interface enumeration completed. May 29 15:00:55 volumio-hc systemd[1]: Started RPC bind portmap service. May 29 15:00:55 volumio-hc systemd[1]: Reached target RPC Port Mapper. May 29 15:00:55 volumio-hc systemd[1]: Reached target Remote File Systems (Pre). May 29 15:00:55 volumio-hc systemd[1]: Reached target Remote File Systems. May 29 15:00:55 volumio-hc avahi-daemon[772]: Server startup complete. Host name is volumio-hc.local. Local service cookie is 256443960. May 29 15:00:55 volumio-hc avahi-daemon[772]: Service "Volumio HC" (/services/volumio.service) successfully established. May 29 15:00:55 volumio-hc systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 29 15:00:55 volumio-hc sh[715]: wlan0=wlan0 May 29 15:00:55 volumio-hc dbus-daemon[769]: [system] Successfully activated service 'org.freedesktop.hostname1' May 29 15:00:55 volumio-hc systemd[1]: Started Hostname Service. May 29 15:00:55 volumio-hc sudo[810]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 29 15:00:55 volumio-hc sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:55 volumio-hc systemd[1]: Started Helper to synchronize boot up for ifupdown. May 29 15:00:56 volumio-hc systemd[1]: Starting Raise network interfaces... May 29 15:00:56 volumio-hc systemd[1]: iptables.service: Succeeded. May 29 15:00:56 volumio-hc kernel: Generic FE-GE Realtek PHY r8169-0-100:00: attached PHY driver (mii_bus:phy_addr=r8169-0-100:00, irq=MAC) May 29 15:00:56 volumio-hc loadcpufreq[813]: Loading cpufreq kernel modules...done (acpi-cpufreq). May 29 15:00:56 volumio-hc systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 29 15:00:56 volumio-hc systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 29 15:00:56 volumio-hc cpufrequtils[908]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. May 29 15:00:56 volumio-hc systemd[1]: Started LSB: set CPUFreq kernel parameters. May 29 15:00:56 volumio-hc haveged[756]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K May 29 15:00:56 volumio-hc haveged[756]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 64K (L4 V); idx: 39/40; sz: 52825/52825 May 29 15:00:56 volumio-hc haveged[756]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00173 May 29 15:00:56 volumio-hc haveged[756]: haveged: fills: 0, generated: 0 May 29 15:00:56 volumio-hc sh[717]: eth0: waiting for carrier May 29 15:00:56 volumio-hc sh[717]: eth0: carrier acquired May 29 15:00:56 volumio-hc dhcpcd[766]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 May 29 15:00:56 volumio-hc dhcpcd[766]: wlan0: IAID a4:09:fe:ac May 29 15:00:56 volumio-hc dhcpcd[766]: wlan0: adding address fe80::aa43:a4ff:fe09:feac May 29 15:00:56 volumio-hc dhcpcd[766]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: waiting for carrier May 29 15:00:56 volumio-hc dhcpcd[753]: eth0: waiting for carrier May 29 15:00:56 volumio-hc dhcpcd[753]: eth0: carrier acquired May 29 15:00:56 volumio-hc dhcpcd[766]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 May 29 15:00:56 volumio-hc dhcpcd[766]: wlan0: IAID a4:09:fe:ac May 29 15:00:56 volumio-hc dhcpcd[766]: wlan0: adding address fe80::aa43:a4ff:fe09:feac May 29 15:00:56 volumio-hc dhcpcd[766]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: waiting for carrier May 29 15:00:56 volumio-hc sudo[810]: pam_unix(sudo:session): session closed for user root May 29 15:00:56 volumio-hc kernel: r8169 0000:01:00.0 eth0: Link is Down May 29 15:00:56 volumio-hc kernel: Generic FE-GE Realtek PHY r8169-0-300:00: attached PHY driver (mii_bus:phy_addr=r8169-0-300:00, irq=MAC) May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: waiting for carrier May 29 15:00:56 volumio-hc dhcpcd[766]: wlan0: carrier lost May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: waiting for carrier May 29 15:00:56 volumio-hc dhcpcd[766]: wlan0: carrier lost May 29 15:00:56 volumio-hc kernel: r8169 0000:03:00.0 eth1: Link is Down May 29 15:00:56 volumio-hc sh[717]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 May 29 15:00:56 volumio-hc sh[717]: eth0: IAID ef:35:3a:ce May 29 15:00:56 volumio-hc sh[717]: eth0: adding address fe80::6a1d:efff:fe35:3ace May 29 15:00:56 volumio-hc sh[717]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc dhcpcd[753]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 May 29 15:00:56 volumio-hc dhcpcd[753]: eth0: IAID ef:35:3a:ce May 29 15:00:56 volumio-hc dhcpcd[753]: eth0: adding address fe80::6a1d:efff:fe35:3ace May 29 15:00:56 volumio-hc dhcpcd[753]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc sudo[917]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 29 15:00:56 volumio-hc sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: carrier acquired May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: carrier acquired May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: IAID ef:35:3a:ce May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: adding address fe80::6a1d:efff:fe35:3ace May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: IAID ef:35:3a:ce May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: adding address fe80::6a1d:efff:fe35:3ace May 29 15:00:56 volumio-hc dhcpcd[766]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc dhcpcd[766]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: carrier acquired May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: carrier acquired May 29 15:00:56 volumio-hc sudo[917]: pam_unix(sudo:session): session closed for user root May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: IAID ef:35:3a:d1 May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: adding address fe80::6a1d:efff:fe35:3ad1 May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: IAID ef:35:3a:d1 May 29 15:00:56 volumio-hc dhcpcd[766]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: adding address fe80::6a1d:efff:fe35:3ad1 May 29 15:00:56 volumio-hc dhcpcd[766]: ipv6_addaddr1: Permission denied May 29 15:00:56 volumio-hc wireless.js[764]: Stopped aP May 29 15:00:56 volumio-hc wireless.js[764]: Wireless Networking DISABLED, not starting wireless flow May 29 15:00:56 volumio-hc systemd[1]: wireless.service: Succeeded. May 29 15:00:56 volumio-hc systemd[1]: Started Wireless Services. May 29 15:00:56 volumio-hc systemd[1]: Started Volumio Backend Module. May 29 15:00:56 volumio-hc systemd[1]: Started Volumio Cpu Tweaker. May 29 15:00:56 volumio-hc systemd[1]: Started Intel SST and HDA soundcard init service. May 29 15:00:56 volumio-hc systemd[1]: Started Raise network interfaces. May 29 15:00:56 volumio-hc systemd[1]: Reached target Network. May 29 15:00:56 volumio-hc systemd[1]: Starting Network Time Service... May 29 15:00:56 volumio-hc systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 29 15:00:56 volumio-hc systemd[1]: Starting Permit User Sessions... May 29 15:00:56 volumio-hc systemd[1]: Reached target Network is Online. May 29 15:00:56 volumio-hc systemd[1]: Starting Samba NMB Daemon... May 29 15:00:56 volumio-hc systemd[1]: Starting /etc/rc.local Compatibility... May 29 15:00:56 volumio-hc systemd[1]: Starting LSB: Brings up/down network automatically... May 29 15:00:56 volumio-hc systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 15:00:56 volumio-hc systemd[1]: Started Permit User Sessions. May 29 15:00:56 volumio-hc systemd[1]: Started /etc/rc.local Compatibility. May 29 15:00:56 volumio-hc systemd[1]: Starting Hold until boot process finishes up... May 29 15:00:56 volumio-hc systemd[1]: Starting Terminate Plymouth Boot Screen... May 29 15:00:56 volumio-hc volumio-cpu-tweak[991]: Setting RT Priority for mpd May 29 15:00:56 volumio-hc volumio-cpu-tweak[991]: pid 35's current scheduling policy: SCHED_FIFO May 29 15:00:56 volumio-hc volumio-cpu-tweak[991]: pid 35's current scheduling priority: 99 May 29 15:00:56 volumio-hc volumio-cpu-tweak[991]: Setting MPD Affinity May 29 15:00:56 volumio-hc volumio-cpu-tweak[991]: pid 3's current affinity mask: f May 29 15:00:56 volumio-hc volumio-cpu-tweak[991]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 29 15:00:56 volumio-hc systemd[1]: plymouth-quit-wait.service: Succeeded. May 29 15:00:56 volumio-hc systemd[1]: Started Hold until boot process finishes up. May 29 15:00:56 volumio-hc systemd[1]: plymouth-quit.service: Succeeded. May 29 15:00:56 volumio-hc systemd[1]: Started Terminate Plymouth Boot Screen. May 29 15:00:56 volumio-hc systemd[1]: Received SIGRTMIN+21 from PID 361 (n/a). May 29 15:00:56 volumio-hc systemd[1]: Started Getty on tty1. May 29 15:00:56 volumio-hc systemd[1]: Reached target Login Prompts. May 29 15:00:56 volumio-hc systemd[1]: volumio_cpu_tweak.service: Succeeded. May 29 15:00:56 volumio-hc ifplugd(eth0)[1043]: ifplugd 0.28 initializing. May 29 15:00:56 volumio-hc ifplugd(eth0)[1043]: Using interface eth0/68:1D:EF:35:3A:CE with driver (version: 6.6.32-volumio) May 29 15:00:56 volumio-hc ifplugd(eth0)[1043]: Using detection mode: SIOCETHTOOL May 29 15:00:56 volumio-hc ifplugd(eth0)[1043]: Initialization complete, link beat not detected. May 29 15:00:56 volumio-hc ntpd[1003]: ntpd 4.2.8p12@1.3728-o (1): Starting May 29 15:00:56 volumio-hc systemd[1]: Started Network Time Service. May 29 15:00:56 volumio-hc ntpd[1003]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 29 15:00:56 volumio-hc ntpd[1048]: proto: precision = 0.048 usec (-24) May 29 15:00:56 volumio-hc ntpd[1048]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 29 15:00:56 volumio-hc ntpd[1048]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 29 15:00:56 volumio-hc ntpd[1048]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 884 days ago May 29 15:00:56 volumio-hc ntpd[1048]: Listen and drop on 0 v6wildcard [::]:123 May 29 15:00:56 volumio-hc ntpd[1048]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 29 15:00:56 volumio-hc ntpd[1048]: Listen normally on 2 lo 127.0.0.1:123 May 29 15:00:56 volumio-hc ntpd[1048]: Listening on routing socket on fd #19 for interface updates May 29 15:00:56 volumio-hc ntpd[1048]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 29 15:00:56 volumio-hc ntpd[1048]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 29 15:00:56 volumio-hc sh[717]: eth0: soliciting a DHCP lease May 29 15:00:56 volumio-hc dhcpcd[753]: eth0: soliciting a DHCP lease May 29 15:00:56 volumio-hc ifplugd[1013]: Network Interface Plugging Daemon...start eth0...done. May 29 15:00:56 volumio-hc systemd[1]: Started LSB: Brings up/down network automatically. May 29 15:00:56 volumio-hc nmbd[1017]: [2025/05/29 15:00:56.802539, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 29 15:00:56 volumio-hc nmbd[1017]: started asyncdns process 1112 May 29 15:00:56 volumio-hc nmbd[1017]: [2025/05/29 15:00:56.803407, 0] ../lib/util/become_daemon.c:149(daemon_status) May 29 15:00:56 volumio-hc nmbd[1017]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 29 15:00:56 volumio-hc nmbd[1017]: [2025/05/29 15:00:56.803434, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 29 15:00:56 volumio-hc nmbd[1017]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 29 15:00:56 volumio-hc systemd[1]: soundcard-init.service: Succeeded. May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: soliciting a DHCP lease May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: soliciting a DHCP lease May 29 15:00:56 volumio-hc sh[717]: eth0: carrier lost May 29 15:00:56 volumio-hc dhcpcd[753]: eth0: carrier lost May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: carrier lost May 29 15:00:56 volumio-hc dhcpcd[766]: eth0: carrier lost May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: carrier lost May 29 15:00:56 volumio-hc dhcpcd[766]: eth1: carrier lost May 29 15:00:57 volumio-hc volumio[990]: info: ------------------------------------------- May 29 15:00:57 volumio-hc volumio[990]: info: ----- Volumio3 ---- May 29 15:00:57 volumio-hc volumio[990]: info: ------------------------------------------- May 29 15:00:57 volumio-hc volumio[990]: info: ----- System startup ---- May 29 15:00:57 volumio-hc volumio[990]: info: ------------------------------------------- May 29 15:00:57 volumio-hc volumio[990]: info: MYVOLUMIO Environment detected May 29 15:00:57 volumio-hc volumio[990]: info: Plugin folders cleanup May 29 15:00:57 volumio-hc volumio[990]: info: Scanning into folder /volumio/app/plugins/ May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category audio_interface May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category miscellanea May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category music_service May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category plugins.json May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category system_controller May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category user_interface May 29 15:00:57 volumio-hc volumio[990]: info: Scanning into folder /data/plugins/ May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category audio_interface May 29 15:00:57 volumio-hc volumio[990]: info: Scanning category music_service May 29 15:00:57 volumio-hc volumio[990]: info: Plugin folders cleanup completed May 29 15:00:57 volumio-hc volumio[990]: info: ------------------------------------------- May 29 15:00:57 volumio-hc volumio[990]: info: ----- Core plugins startup ---- May 29 15:00:57 volumio-hc volumio[990]: info: ------------------------------------------- May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugins from folder /volumio/app/plugins/ May 29 15:00:57 volumio-hc volumio[990]: info: Adding plugin upnp to MyMusic Plugins May 29 15:00:57 volumio-hc volumio[990]: info: Adding plugin airplay_emulation to MyMusic Plugins May 29 15:00:57 volumio-hc volumio[990]: info: Adding plugin upnp_browser to MyMusic Plugins May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugins from folder /data/plugins/ May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "system"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "appearance"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "network"... May 29 15:00:57 volumio-hc volumio[990]: info: Refreshing Cached IP Addresses May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "services"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "alsa_controller"... May 29 15:00:57 volumio-hc sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 15:00:57 volumio-hc sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:57 volumio-hc sudo[1207]: pam_unix(sudo:session): session closed for user root May 29 15:00:57 volumio-hc sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 15:00:57 volumio-hc sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:57 volumio-hc sudo[1209]: pam_unix(sudo:session): session closed for user root May 29 15:00:57 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "wizard"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "networkfs"... May 29 15:00:57 volumio-hc ntpd[1048]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 29 15:00:57 volumio-hc volumio[990]: info: Starting Udev Watcher for removable devices May 29 15:00:57 volumio-hc sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.33/Public/Musique /mnt/NAS/Musique May 29 15:00:57 volumio-hc sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:57 volumio-hc volumio[990]: info: Ignoring mount for partition: boot May 29 15:00:57 volumio-hc volumio[990]: info: Ignoring mount for partition: volumio May 29 15:00:57 volumio-hc volumio[990]: info: Ignoring mount for partition: volumio_data May 29 15:00:57 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "volumio_command_line_client"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "upnp"... May 29 15:00:57 volumio-hc volumio[990]: info: [1748523657660] Starting Upmpd Daemon May 29 15:00:57 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "my_music"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "mpd"... May 29 15:00:57 volumio-hc kernel: FS-Cache: Loaded May 29 15:00:57 volumio-hc kernel: Key type dns_resolver registered May 29 15:00:57 volumio-hc sudo[1215]: pam_unix(sudo:session): session closed for user root May 29 15:00:57 volumio-hc kernel: Key type cifs.spnego registered May 29 15:00:57 volumio-hc kernel: Key type cifs.idmap registered May 29 15:00:57 volumio-hc 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. May 29 15:00:57 volumio-hc kernel: CIFS: Attempting to mount //192.168.1.33/Public/Musique May 29 15:00:57 volumio-hc kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 29 15:00:57 volumio-hc kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "upnp_browser"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "alarm-clock"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "airplay_emulation"... May 29 15:00:57 volumio-hc volumio[990]: info: Starting Shairport Sync May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "last_100"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "webradio"... May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "i2s_dacs"... May 29 15:00:57 volumio-hc volumio[990]: info: I2S DAC not set, start Auto-detection May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "volumiodiscovery"... May 29 15:00:57 volumio-hc volumio[990]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 15:00:57 volumio-hc volumio[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 15:00:57 volumio-hc volumio[990]: *** WARNING *** For more information see May 29 15:00:57 volumio-hc volumio[990]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 15:00:57 volumio-hc node[990]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 15:00:57 volumio-hc volumio[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 15:00:57 volumio-hc volumio[990]: *** WARNING *** For more information see May 29 15:00:57 volumio-hc node[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 15:00:57 volumio-hc node[990]: *** WARNING *** For more information see May 29 15:00:57 volumio-hc node[990]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 15:00:57 volumio-hc node[990]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 15:00:57 volumio-hc node[990]: *** WARNING *** For more information see May 29 15:00:57 volumio-hc volumio[990]: info: Applying required configuration parameters for plugin volumiodiscovery May 29 15:00:57 volumio-hc volumio[990]: info: Discovery: Started advertising with name: Volumio HC May 29 15:00:57 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 15:00:57 volumio-hc volumio[990]: info: Loading plugin "spop"... May 29 15:00:58 volumio-hc volumio[990]: info: Loading plugin "outputs"... May 29 15:00:58 volumio-hc volumio[990]: info: Loading plugin "albumart"... May 29 15:00:58 volumio-hc volumio[990]: info: Plugin example_plugin is not enabled May 29 15:00:58 volumio-hc volumio[990]: info: Loading plugin "inputs"... May 29 15:00:58 volumio-hc volumio[990]: info: Loading plugin "updater_comm"... May 29 15:00:58 volumio-hc volumio[990]: Forking 3 albumart workers May 29 15:00:58 volumio-hc volumio[990]: info: Plugin mpdemulation is not enabled May 29 15:00:58 volumio-hc volumio[990]: info: Loading plugin "rest_api"... May 29 15:00:58 volumio-hc volumio[990]: info: Loading plugin "websocket"... May 29 15:00:58 volumio-hc volumio[990]: info: Starting Socket.io Server version 2.3.0 May 29 15:00:58 volumio-hc volumio[990]: info: Loading plugin "fusiondsp"... May 29 15:00:58 volumio-hc volumio[990]: info: Applying required configuration parameters for plugin fusiondsp May 29 15:00:58 volumio-hc volumio[990]: Starting albumart workers May 29 15:00:58 volumio-hc volumio[990]: Starting albumart workers May 29 15:00:58 volumio-hc volumio[990]: Starting albumart workers May 29 15:00:58 volumio-hc volumio[990]: info: Loading i18n strings for locale fr May 29 15:00:58 volumio-hc volumio[990]: Updating browse sources language May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::initPlayerControls May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: Express server listening on port 3000 May 29 15:00:58 volumio-hc volumio[990]: [Metrics] WebUI: 1s 477.61ms May 29 15:00:58 volumio-hc volumio[990]: info: Setting Device type: x86 May 29 15:00:58 volumio-hc volumio[990]: info: CoreStateMachine::resetVolumioState May 29 15:00:58 volumio-hc volumio[990]: info: CoreStateMachine::getcurrentVolume May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioRetrievevolume May 29 15:00:58 volumio-hc volumio[990]: info: CoreStateMachine::pushState May 29 15:00:58 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioPushState May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 29 15:00:58 volumio-hc volumio[990]: info: Completed loading Core Plugins May 29 15:00:58 volumio-hc volumio[990]: info: Preparing to generate the ALSA configuration file May 29 15:00:58 volumio-hc volumio[990]: info: Cannot mount NAS Musique at system boot, trial number 1 ,retrying in 5 seconds May 29 15:00:58 volumio-hc volumio[990]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 29 15:00:58 volumio-hc volumio[990]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 15:00:58 volumio-hc volumio[990]: info: Reading ALSA contributions from plugins. May 29 15:00:58 volumio-hc volumio[990]: info: Reloading queue from file May 29 15:00:58 volumio-hc volumio[990]: info: CoreStateMachine::setRepeat true single undefined May 29 15:00:58 volumio-hc volumio[990]: info: CoreStateMachine::pushState May 29 15:00:58 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioPushState May 29 15:00:58 volumio-hc volumio[990]: info: CoreStateMachine::setRandom null May 29 15:00:58 volumio-hc volumio[990]: info: CoreStateMachine::pushState May 29 15:00:58 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioPushState May 29 15:00:58 volumio-hc volumio[990]: info: Asound.conf file unchanged, so no further update is needed May 29 15:00:58 volumio-hc volumio[990]: info: Output device has changed, restarting MPD May 29 15:00:58 volumio-hc sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 15:00:58 volumio-hc sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc volumio[990]: info: Output device has changed, restarting Shairport Sync May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc sudo[1286]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 15:00:58 volumio-hc sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc systemd[1]: Listening on mpd.socket. May 29 15:00:58 volumio-hc volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 15:00:58 volumio-hc volumio[990]: info: ___________ START PLUGINS ___________ May 29 15:00:58 volumio-hc volumio[990]: info: ControllerMpd::onStart: Initializing MPD May 29 15:00:58 volumio-hc volumio[990]: info: Creating MPD Configuration file May 29 15:00:58 volumio-hc sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 15:00:58 volumio-hc sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 15:00:58 volumio-hc volumio[990]: info: [1748523658505] CoreMusicLibrary::Adding element Serveurs Média May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc sudo[1296]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 15:00:58 volumio-hc sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc systemd[1]: Starting Music Player Daemon... May 29 15:00:58 volumio-hc systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 29 15:00:58 volumio-hc systemd[1]: mpd.service: Succeeded. May 29 15:00:58 volumio-hc systemd[1]: Stopped Music Player Daemon. May 29 15:00:58 volumio-hc systemd[1]: Starting Music Player Daemon... May 29 15:00:58 volumio-hc volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 15:00:58 volumio-hc volumio[990]: info: [1748523658529] CoreMusicLibrary::Adding element Last_100 May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 15:00:58 volumio-hc volumio[990]: info: [1748523658530] CoreMusicLibrary::Adding element Webradio May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 15:00:58 volumio-hc volumio[990]: info: Initializing BBC Radios May 29 15:00:58 volumio-hc sudo[1307]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 15:00:58 volumio-hc sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc sudo[1307]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 15:00:58 volumio-hc sudo[1307]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: Creating Spotify config file May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: Loading i18n strings for locale fr May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: FusionDsp - mixtype--------------------- None May 29 15:00:58 volumio-hc volumio[990]: info: Volumio Calling Home May 29 15:00:58 volumio-hc volumio[990]: info: Preparing to generate the ALSA configuration file May 29 15:00:58 volumio-hc volumio[990]: Unhandled rejection Error: No sockets available, cannot start. May 29 15:00:58 volumio-hc volumio[990]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 29 15:00:58 volumio-hc volumio[990]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 29 15:00:58 volumio-hc volumio[990]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 29 15:00:58 volumio-hc volumio[990]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 29 15:00:58 volumio-hc volumio[990]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 29 15:00:58 volumio-hc volumio[990]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 29 15:00:58 volumio-hc volumio[990]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 29 15:00:58 volumio-hc volumio[990]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 29 15:00:58 volumio-hc volumio[990]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 29 15:00:58 volumio-hc volumio[990]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 29 15:00:58 volumio-hc volumio[990]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 29 15:00:58 volumio-hc volumio[990]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 29 15:00:58 volumio-hc volumio[990]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 29 15:00:58 volumio-hc volumio[990]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 29 15:00:58 volumio-hc volumio[990]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 29 15:00:58 volumio-hc volumio[990]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 29 15:00:58 volumio-hc volumio[990]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io May 29 15:00:58 volumio-hc volumio[990]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 15:00:58 volumio-hc volumio[990]: info: Reading ALSA contributions from plugins. May 29 15:00:58 volumio-hc volumio[990]: info: MPD Permissions set May 29 15:00:58 volumio-hc volumio[990]: info: MPD Permissions set May 29 15:00:58 volumio-hc volumio[990]: info: Spotify config file written May 29 15:00:58 volumio-hc sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 29 15:00:58 volumio-hc sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc ntpd[1048]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 29 15:00:58 volumio-hc systemd[1]: Started go-librespot Daemon. May 29 15:00:58 volumio-hc go-librespot[1326]: Librespot-go daemon starting... May 29 15:00:58 volumio-hc sudo[1324]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: No need to fix Spotify hosts May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:00:58 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:00:58 volumio-hc volumio[990]: info: Starting Shairport Sync May 29 15:00:58 volumio-hc volumio[990]: info: Starting Shairport Sync May 29 15:00:58 volumio-hc sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 15:00:58 volumio-hc sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc volumio[990]: info: Starting Shairport Sync May 29 15:00:58 volumio-hc sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 15:00:58 volumio-hc sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 15:00:58 volumio-hc sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 15:00:58 volumio-hc sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc systemd[1]: shairport-sync.service: Succeeded. May 29 15:00:58 volumio-hc volumio[990]: info: Asound.conf file unchanged, so no further update is needed May 29 15:00:58 volumio-hc volumio[990]: info: Output device has changed, restarting MPD May 29 15:00:58 volumio-hc systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 15:00:58 volumio-hc sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 15:00:58 volumio-hc sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc volumio[990]: info: Output device has changed, restarting Shairport Sync May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc go-librespot[1326]: time="2025-05-29T15:00:58+02:00" level=info msg="generated new device id: 325caa13c77ba12f2f3f9441ea33316f9f61c9bf" May 29 15:00:58 volumio-hc go-librespot[1326]: time="2025-05-29T15:00:58+02:00" level=debug msg="stored credentials found for steph-hifi" May 29 15:00:58 volumio-hc sudo[1356]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 15:00:58 volumio-hc sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 15:00:58 volumio-hc sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc sudo[1346]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 15:00:58 volumio-hc go-librespot[1326]: time="2025-05-29T15:00:58+02:00" level=fatal msg="failed running with username and spotify token" 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" May 29 15:00:58 volumio-hc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 29 15:00:58 volumio-hc sudo[1348]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 29 15:00:58 volumio-hc systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 15:00:58 volumio-hc volumio[990]: info: MPD Permissions set May 29 15:00:58 volumio-hc systemd[1]: shairport-sync.service: Succeeded. May 29 15:00:58 volumio-hc volumio[990]: info: Shairport-Sync Started May 29 15:00:58 volumio-hc systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 15:00:58 volumio-hc volumio[990]: Error adding Membership: Error: addMembership EINVAL May 29 15:00:58 volumio-hc volumio[990]: info: Shairport-Sync Started May 29 15:00:58 volumio-hc systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 29 15:00:58 volumio-hc systemd[1]: mpd.service: Succeeded. May 29 15:00:58 volumio-hc systemd[1]: Stopped Music Player Daemon. May 29 15:00:58 volumio-hc sudo[1352]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc systemd[1]: Starting Music Player Daemon... May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 29 15:00:58 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:00:58 volumio-hc volumio[990]: info: Shairport-Sync Started May 29 15:00:58 volumio-hc volumio[990]: info: Starting Shairport Sync May 29 15:00:58 volumio-hc sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 15:00:58 volumio-hc sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc sudo[1367]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 15:00:58 volumio-hc sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:00:58 volumio-hc systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 15:00:58 volumio-hc sudo[1367]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 15:00:58 volumio-hc sudo[1367]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc systemd[1]: shairport-sync.service: Succeeded. May 29 15:00:58 volumio-hc systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 15:00:58 volumio-hc systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 15:00:58 volumio-hc sudo[1376]: pam_unix(sudo:session): session closed for user root May 29 15:00:58 volumio-hc volumio[990]: info: Shairport-Sync Started May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: carrier acquired May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: carrier acquired May 29 15:00:59 volumio-hc sh[717]: eth0: carrier acquired May 29 15:00:59 volumio-hc dhcpcd[753]: eth0: carrier acquired May 29 15:00:59 volumio-hc kernel: r8169 0000:01:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: IAID ef:35:3a:ce May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: IAID ef:35:3a:ce May 29 15:00:59 volumio-hc sh[717]: eth0: IAID ef:35:3a:ce May 29 15:00:59 volumio-hc dhcpcd[753]: eth0: IAID ef:35:3a:ce May 29 15:00:59 volumio-hc mpd[1379]: May 29 15:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: soliciting a DHCP lease May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: soliciting a DHCP lease May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: offered 192.168.1.39 from 192.168.1.1 May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: offered 192.168.1.39 from 192.168.1.1 May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: probing address 192.168.1.39/24 May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: probing address 192.168.1.39/24 May 29 15:00:59 volumio-hc systemd[1]: Started Music Player Daemon. May 29 15:00:59 volumio-hc sudo[1289]: pam_unix(sudo:session): session closed for user root May 29 15:00:59 volumio-hc sudo[1299]: pam_unix(sudo:session): session closed for user root May 29 15:00:59 volumio-hc sudo[1359]: pam_unix(sudo:session): session closed for user root May 29 15:00:59 volumio-hc volumio[990]: info: Completed starting Core Plugins May 29 15:00:59 volumio-hc volumio[990]: info: ------------------------------------------- May 29 15:00:59 volumio-hc volumio[990]: info: ----- MyVolumio plugins startup ---- May 29 15:00:59 volumio-hc volumio[990]: info: ------------------------------------------- May 29 15:00:59 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Fetching plans data.... May 29 15:00:59 volumio-hc volumio[990]: error: MPD error: The expression evaluated to a falsy value: May 29 15:00:59 volumio-hc volumio[990]: assert.ok(self.idling) May 29 15:00:59 volumio-hc volumio[990]: error: The expression evaluated to a falsy value: May 29 15:00:59 volumio-hc volumio[990]: assert.ok(self.idling) May 29 15:00:59 volumio-hc volumio[990]: error: MPD error: The expression evaluated to a falsy value: May 29 15:00:59 volumio-hc volumio[990]: assert.ok(self.idling) May 29 15:00:59 volumio-hc volumio[990]: error: The expression evaluated to a falsy value: May 29 15:00:59 volumio-hc volumio[990]: assert.ok(self.idling) May 29 15:00:59 volumio-hc volumio[990]: info: MPD running with PID1379 May 29 15:00:59 volumio-hc volumio[990]: ,establishing connection May 29 15:00:59 volumio-hc volumio[990]: error: MPD error: The expression evaluated to a falsy value: May 29 15:00:59 volumio-hc volumio[990]: assert.ok(self.idling) May 29 15:00:59 volumio-hc volumio[990]: error: The expression evaluated to a falsy value: May 29 15:00:59 volumio-hc volumio[990]: assert.ok(self.idling) May 29 15:00:59 volumio-hc volumio[990]: error: updateQueue error: null May 29 15:00:59 volumio-hc ntpd[1048]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 29 15:00:59 volumio-hc sh[717]: eth0: soliciting an IPv6 router May 29 15:00:59 volumio-hc dhcpcd[753]: eth0: soliciting an IPv6 router May 29 15:00:59 volumio-hc ifplugd(eth0)[1043]: Link beat detected. May 29 15:00:59 volumio-hc ifplugd(eth0)[1043]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 29 15:00:59 volumio-hc sh[717]: eth0: soliciting a DHCP lease May 29 15:00:59 volumio-hc dhcpcd[753]: eth0: soliciting a DHCP lease May 29 15:00:59 volumio-hc ifplugd(eth0)[1043]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 29 15:00:59 volumio-hc sh[717]: eth0: offered 192.168.1.39 from 192.168.1.1 May 29 15:00:59 volumio-hc dhcpcd[753]: eth0: offered 192.168.1.39 from 192.168.1.1 May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: soliciting an IPv6 router May 29 15:00:59 volumio-hc dhcpcd[766]: eth0: soliciting an IPv6 router May 29 15:00:59 volumio-hc sh[717]: eth0: probing address 192.168.1.39/24 May 29 15:00:59 volumio-hc dhcpcd[753]: eth0: probing address 192.168.1.39/24 May 29 15:01:00 volumio-hc systemd[1]: systemd-rfkill.service: Succeeded. May 29 15:01:00 volumio-hc volumio[990]: info: camilladsp service started and running in background, instance 1 May 29 15:01:00 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 15:01:00 volumio-hc volumio[990]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA May 29 15:01:00 volumio-hc volumio[990]: info: FusionDsp loaded May 29 15:01:00 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 15:01:00 volumio-hc sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 29 15:01:00 volumio-hc sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:00 volumio-hc volumio[990]: info: FusionDsp - Reporting Fusion DSP Enabled May 29 15:01:00 volumio-hc volumio[990]: info: Adding Signal Path Element [object Object] May 29 15:01:00 volumio-hc volumio[990]: info: Adding fusiondspeq DSP Signal Path Element May 29 15:01:00 volumio-hc volumio[990]: info: FusionDsp - ---- installed callbackRead May 29 15:01:00 volumio-hc volumio[990]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 29 15:01:00 volumio-hc volumio[990]: info: FusionDsp - Nulleq applied May 29 15:01:00 volumio-hc ntpd[1048]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 29 15:01:00 volumio-hc volumio[990]: error: FusionDsp - WebSocket error: [object Object] May 29 15:01:00 volumio-hc volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 29 15:01:00 volumio-hc systemd[1]: Started FusionDsp Daemon. May 29 15:01:00 volumio-hc sudo[1406]: pam_unix(sudo:session): session closed for user root May 29 15:01:00 volumio-hc volumio-remote-updater[758]: [2025-05-29 15:01:00] [connect] Successful connection May 29 15:01:00 volumio-hc volumio-remote-updater[758]: [2025-05-29 15:01:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748523660 101 May 29 15:01:00 volumio-hc volumio[990]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 29 15:01:01 volumio-hc volumio[990]: 0+0 records in May 29 15:01:01 volumio-hc volumio[990]: 0+0 records out May 29 15:01:01 volumio-hc volumio[990]: 0 bytes copied, 2.7385e-05 s, 0.0 kB/s May 29 15:01:01 volumio-hc volumio[990]: info: go-librespot daemon successfully initialized May 29 15:01:01 volumio-hc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 29 15:01:01 volumio-hc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 29 15:01:01 volumio-hc systemd[1]: Stopped go-librespot Daemon. May 29 15:01:01 volumio-hc systemd[1]: Started go-librespot Daemon. May 29 15:01:01 volumio-hc go-librespot[1418]: Librespot-go daemon starting... May 29 15:01:01 volumio-hc go-librespot[1418]: time="2025-05-29T15:01:01+02:00" level=info msg="generated new device id: ebdd0eaaa668707c30000cded3b0915cfd5c25a6" May 29 15:01:01 volumio-hc go-librespot[1418]: time="2025-05-29T15:01:01+02:00" level=debug msg="stored credentials found for steph-hifi" May 29 15:01:01 volumio-hc go-librespot[1418]: time="2025-05-29T15:01:01+02:00" level=fatal msg="failed running with username and spotify token" 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" May 29 15:01:01 volumio-hc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 29 15:01:01 volumio-hc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 29 15:01:03 volumio-hc sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.33/Public/Musique /mnt/NAS/Musique May 29 15:01:03 volumio-hc sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:03 volumio-hc sudo[1426]: pam_unix(sudo:session): session closed for user root May 29 15:01:03 volumio-hc volumio[990]: info: Cannot mount NAS Musique at system boot, trial number 2 ,retrying in 5 seconds May 29 15:01:03 volumio-hc kernel: CIFS: Attempting to mount //192.168.1.33/Public/Musique May 29 15:01:03 volumio-hc kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 29 15:01:03 volumio-hc kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 29 15:01:03 volumio-hc dhcpcd[766]: eth0: leased 192.168.1.39 for 86400 seconds May 29 15:01:03 volumio-hc dhcpcd[766]: eth0: leased 192.168.1.39 for 86400 seconds May 29 15:01:03 volumio-hc avahi-daemon[772]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.39. May 29 15:01:03 volumio-hc dhcpcd[766]: eth0: adding route to 192.168.1.0/24 May 29 15:01:03 volumio-hc dhcpcd[766]: eth0: adding default route via 192.168.1.1 May 29 15:01:03 volumio-hc dhcpcd[766]: eth0: adding route to 192.168.1.0/24 May 29 15:01:03 volumio-hc dhcpcd[766]: eth0: adding default route via 192.168.1.1 May 29 15:01:03 volumio-hc avahi-daemon[772]: New relevant interface eth0.IPv4 for mDNS. May 29 15:01:03 volumio-hc avahi-daemon[772]: Registering new address record for 192.168.1.39 on eth0.IPv4. May 29 15:01:03 volumio-hc ntpd[1048]: ntpd exiting on signal 15 (Terminated) May 29 15:01:03 volumio-hc systemd[1]: Stopping Network Time Service... May 29 15:01:03 volumio-hc systemd[1]: ntp.service: Succeeded. May 29 15:01:03 volumio-hc systemd[1]: Stopped Network Time Service. May 29 15:01:03 volumio-hc systemd[1]: Starting Network Time Service... May 29 15:01:03 volumio-hc ntpd[1464]: ntpd 4.2.8p12@1.3728-o (1): Starting May 29 15:01:03 volumio-hc ntpd[1464]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 29 15:01:03 volumio-hc systemd[1]: Started Network Time Service. May 29 15:01:03 volumio-hc ntpd[1470]: proto: precision = 0.048 usec (-24) May 29 15:01:03 volumio-hc ntpd[1470]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 29 15:01:03 volumio-hc dhcpcd[766]: forked to background, child pid 1471 May 29 15:01:03 volumio-hc dhcpcd[766]: forked to background, child pid 1471 May 29 15:01:03 volumio-hc ntpd[1470]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 29 15:01:03 volumio-hc ntpd[1470]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 884 days ago May 29 15:01:03 volumio-hc systemd[1]: Started DHCP Client Daemon. May 29 15:01:03 volumio-hc ntpd[1470]: Listen and drop on 0 v6wildcard [::]:123 May 29 15:01:03 volumio-hc ntpd[1470]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 29 15:01:03 volumio-hc ntpd[1470]: Listen normally on 2 lo 127.0.0.1:123 May 29 15:01:03 volumio-hc ntpd[1470]: Listen normally on 3 eth0 192.168.1.39:123 May 29 15:01:03 volumio-hc ntpd[1470]: Listening on routing socket on fd #20 for interface updates May 29 15:01:03 volumio-hc ntpd[1470]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 29 15:01:03 volumio-hc ntpd[1470]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 29 15:01:03 volumio-hc volumio[990]: info: Discovery: adding 02084ab0-afac-4eda-a850-a05d47287023 May 29 15:01:03 volumio-hc volumio[990]: info: Discovery: Found device miniDSP SHD May 29 15:01:03 volumio-hc volumio[990]: info: Discovery: Connecting to remote: 192.168.1.29 May 29 15:01:03 volumio-hc volumio[990]: info: Discovery: Connected to remote: 192.168.1.29 May 29 15:01:04 volumio-hc volumio[990]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 29 15:01:04 volumio-hc ntpd[1470]: Soliciting pool server 51.68.44.27 May 29 15:01:04 volumio-hc volumio[990]: info: Initializing connection to go-librespot Websocket May 29 15:01:05 volumio-hc volumio[990]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 29 15:01:05 volumio-hc volumio[990]: info: Discovery: adding 6c160f18-f8ef-4160-a376-d2b021f881d7 May 29 15:01:05 volumio-hc volumio[990]: info: Discovery: Found device Volumio HC May 29 15:01:05 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:05 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:05 volumio-hc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 29 15:01:05 volumio-hc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 29 15:01:05 volumio-hc systemd[1]: Stopped go-librespot Daemon. May 29 15:01:05 volumio-hc systemd[1]: Started go-librespot Daemon. May 29 15:01:05 volumio-hc go-librespot[1475]: Librespot-go daemon starting... May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=info msg="generated new device id: 04a386eb4d3b705ff1bcc3ccbc1b659fb985685a" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="stored credentials found for steph-hifi" May 29 15:01:05 volumio-hc volumio[990]: verbose: New Socket.io Connection to 192.168.1.39:3000 from 192.168.1.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="zeroconf server listening on port 36427" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="obtained new client token: AACqRNG5e2i+6+FQXuvGO1+PigzAYSNNvaidh5HZyp5D2Eei8LN+Tvk3YgSrDQQkTb8icSuDiCuhz+a/f6dOFyq7xUQok8MQoUu/otasu4M4qbVit9nERxU/CHtImxFVl2tGvRnOYvi0in0VAx2Ed/DWVM0VdP+CODxqnGHRJV6aTfqcwmy7SZLZ5nVWh5RckqzXPVGgclXvKFbdLq7xsPWEw2/XDhIwRurpSs8A7nFAbFThwnfTx/1PEg==" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="completed keyexchange" May 29 15:01:05 volumio-hc volumio[990]: verbose: New Socket.io Connection to 192.168.1.39:3000 from 192.168.1.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 29 15:01:05 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 29 15:01:05 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:05 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:05 volumio-hc volumio[990]: verbose: New Socket.io Connection to 192.168.1.39 from 192.168.1.31 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 29 15:01:05 volumio-hc ntpd[1470]: Soliciting pool server 54.38.114.34 May 29 15:01:05 volumio-hc ntpd[1470]: Soliciting pool server 194.57.169.1 May 29 15:01:05 volumio-hc sh[717]: eth0: leased 192.168.1.39 for 86400 seconds May 29 15:01:05 volumio-hc dhcpcd[753]: eth0: leased 192.168.1.39 for 86400 seconds May 29 15:01:05 volumio-hc sh[717]: eth0: adding route to 192.168.1.0/24 May 29 15:01:05 volumio-hc sh[717]: eth0: adding default route via 192.168.1.1 May 29 15:01:05 volumio-hc dhcpcd[753]: eth0: adding route to 192.168.1.0/24 May 29 15:01:05 volumio-hc dhcpcd[753]: eth0: adding default route via 192.168.1.1 May 29 15:01:05 volumio-hc ntpd[1470]: ntpd exiting on signal 15 (Terminated) May 29 15:01:05 volumio-hc ntpd[1470]: 51.68.44.27 local addr 192.168.1.39 -> May 29 15:01:05 volumio-hc systemd[1]: Stopping Network Time Service... May 29 15:01:05 volumio-hc ntpd[1470]: 54.38.114.34 local addr 192.168.1.39 -> May 29 15:01:05 volumio-hc ntpd[1470]: 194.57.169.1 local addr 192.168.1.39 -> May 29 15:01:05 volumio-hc systemd[1]: ntp.service: Succeeded. May 29 15:01:05 volumio-hc systemd[1]: Stopped Network Time Service. May 29 15:01:05 volumio-hc systemd[1]: Starting Network Time Service... May 29 15:01:05 volumio-hc ntpd[1517]: ntpd 4.2.8p12@1.3728-o (1): Starting May 29 15:01:05 volumio-hc ntpd[1517]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 29 15:01:05 volumio-hc systemd[1]: Started Network Time Service. May 29 15:01:05 volumio-hc ntpd[1523]: proto: precision = 0.047 usec (-24) May 29 15:01:05 volumio-hc ntpd[1523]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 29 15:01:05 volumio-hc sh[717]: forked to background, child pid 1524 May 29 15:01:05 volumio-hc dhcpcd[753]: forked to background, child pid 1524 May 29 15:01:05 volumio-hc ntpd[1523]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 29 15:01:05 volumio-hc ntpd[1523]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 884 days ago May 29 15:01:05 volumio-hc ntpd[1523]: Listen and drop on 0 v6wildcard [::]:123 May 29 15:01:05 volumio-hc ntpd[1523]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 29 15:01:05 volumio-hc ntpd[1523]: Listen normally on 2 lo 127.0.0.1:123 May 29 15:01:05 volumio-hc ntpd[1523]: Listen normally on 3 eth0 192.168.1.39:123 May 29 15:01:05 volumio-hc ntpd[1523]: Listening on routing socket on fd #20 for interface updates May 29 15:01:05 volumio-hc ntpd[1523]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 29 15:01:05 volumio-hc ntpd[1523]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 29 15:01:05 volumio-hc ifplugd(eth0)[1043]: client: ifup: interface eth0 already configured May 29 15:01:05 volumio-hc sh[717]: eth0=eth0 May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="completed challenge" May 29 15:01:05 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:05+02:00" level=debug msg="authenticated as steph-hifi" May 29 15:01:06 volumio-hc ifplugd(eth0)[1043]: Program executed successfully. May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="authenticated as steph-hifi" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="dealer connection opened" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="initializing zeroconf session, username: steph-hifi" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="autoplay enabled: false" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="received connection id: NDkzZjcyOTktYzdhNC00NDJjLTk2MWItMTNiNjQ4NWU5MmQxK2RlYWxlcit0Y3A6Ly8wYWNhNDIyNS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMUVEQUIwOTdGRjA0NjNGRDlFQTExMTU0QTY2MDU5QkI5QjhGQTYzNUExRTg1MDcyRkNCMkI5NkM1QkI5NzA3MA==" May 29 15:01:06 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:06+02:00" level=debug msg="put connect state because NEW_DEVICE" May 29 15:01:06 volumio-hc sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 15:01:06 volumio-hc sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:06 volumio-hc sudo[1552]: pam_unix(sudo:session): session closed for user root May 29 15:01:06 volumio-hc sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 15:01:06 volumio-hc sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:06 volumio-hc sudo[1555]: pam_unix(sudo:session): session closed for user root May 29 15:01:06 volumio-hc volumio[990]: verbose: New Socket.io Connection to 192.168.1.39 from 192.168.1.31 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 29 15:01:06 volumio-hc sudo[1558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 15:01:06 volumio-hc sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:06 volumio-hc sudo[1558]: pam_unix(sudo:session): session closed for user root May 29 15:01:06 volumio-hc sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 15:01:06 volumio-hc sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:06 volumio-hc sudo[1561]: pam_unix(sudo:session): session closed for user root May 29 15:01:06 volumio-hc volumio[990]: verbose: New Socket.io Connection to 192.168.1.39 from 192.168.1.31 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetVisibleSources May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:06 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 15:01:06 volumio-hc volumio[990]: info: Received Get System Info May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 15:01:06 volumio-hc volumio[990]: info: Discovery: Getting this device information May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:06 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:06 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:06 volumio-hc volumio[990]: info: Listing playlists May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 15:01:06 volumio-hc volumio[990]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 15:01:06 volumio-hc nmbd[1017]: [2025/05/29 15:01:06.805333, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 29 15:01:06 volumio-hc nmbd[1017]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 29 15:01:06 volumio-hc systemd[1]: Started Samba NMB Daemon. May 29 15:01:06 volumio-hc volumio[990]: info: FusionDsp - May 29 15:01:06 volumio-hc systemd[1]: Starting Samba Winbind Daemon... May 29 15:01:06 volumio-hc volumio[990]: info: FusionDsp - May 29 15:01:06 volumio-hc winbindd[1563]: [2025/05/29 15:01:06.882647, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 29 15:01:06 volumio-hc winbindd[1563]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 29 15:01:06 volumio-hc winbindd[1563]: [2025/05/29 15:01:06.883999, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 29 15:01:06 volumio-hc winbindd[1563]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 29 15:01:06 volumio-hc systemd[1]: Started Samba Winbind Daemon. May 29 15:01:06 volumio-hc systemd[1]: Starting Samba SMB Daemon... May 29 15:01:06 volumio-hc ntpd[1523]: Soliciting pool server 82.64.84.116 May 29 15:01:06 volumio-hc smbd[1568]: [2025/05/29 15:01:06.988310, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 29 15:01:06 volumio-hc smbd[1568]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 29 15:01:06 volumio-hc systemd[1]: Started Samba SMB Daemon. May 29 15:01:06 volumio-hc systemd[1]: Reached target Multi-User System. May 29 15:01:06 volumio-hc systemd[1]: Reached target Graphical Interface. May 29 15:01:07 volumio-hc systemd[1]: Starting Update UTMP about System Runlevel Changes... May 29 15:01:07 volumio-hc systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 29 15:01:07 volumio-hc systemd[1]: Started Update UTMP about System Runlevel Changes. May 29 15:01:07 volumio-hc systemd[1]: Startup finished in 3.775s (firmware) + 2.181s (loader) + 5.026s (kernel) + 12.793s (userspace) = 23.777s. May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 29 15:01:07 volumio-hc volumio[990]: info: Adding plugin bluetooth to MyMusic Plugins May 29 15:01:07 volumio-hc volumio[990]: info: Adding plugin multiroom to MyMusic Plugins May 29 15:01:07 volumio-hc volumio[990]: info: Adding plugin metavolumio to MyMusic Plugins May 29 15:01:07 volumio-hc volumio[990]: info: Adding plugin cd_controller to MyMusic Plugins May 29 15:01:07 volumio-hc volumio[990]: info: Adding plugin smart_inputs to MyMusic Plugins May 29 15:01:07 volumio-hc volumio[990]: info: Adding plugin tidalconnect to MyMusic Plugins May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 15:01:07 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:01:07 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:01:07 volumio-hc volumio[990]: info: Starting MyVolumio Remote Streaming Endpoints May 29 15:01:07 volumio-hc volumio[990]: info: MyVolumio login type: Token May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 15:01:07 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 15:01:07 volumio-hc ntpd[1523]: Soliciting pool server 45.13.105.44 May 29 15:01:07 volumio-hc ntpd[1523]: Soliciting pool server 141.95.158.40 May 29 15:01:08 volumio-hc volumio[990]: info: Starting Streaming Service Transparent Proxy May 29 15:01:08 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 15:01:08 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 15:01:08 volumio-hc volumio[990]: info: Streaming services startup May 29 15:01:08 volumio-hc volumio[990]: info: Starting Streaming Daemon May 29 15:01:08 volumio-hc sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 15:01:08 volumio-hc sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:08 volumio-hc sudo[1585]: pam_unix(sudo:session): session closed for user root May 29 15:01:08 volumio-hc volumio[990]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 15:01:08 volumio-hc sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 15:01:08 volumio-hc sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:08 volumio-hc sudo[1592]: pam_unix(sudo:session): session closed for user root May 29 15:01:08 volumio-hc volumio[990]: info: Initializing connection to go-librespot Websocket May 29 15:01:08 volumio-hc sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 15:01:08 volumio-hc sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:08 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:08 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:08 volumio-hc sudo[1595]: pam_unix(sudo:session): session closed for user root May 29 15:01:08 volumio-hc volumio[990]: error: Cannot start Volumio Streaming Daemon May 29 15:01:08 volumio-hc volumio[990]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 15:01:08 volumio-hc volumio[990]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 15:01:08 volumio-hc go-librespot[1475]: time="2025-05-29T15:01:08+02:00" level=debug msg="new websocket client" May 29 15:01:08 volumio-hc volumio[990]: info: Connection to go-librespot Websocket established May 29 15:01:08 volumio-hc sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 15:01:08 volumio-hc sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:08 volumio-hc systemd[1]: Started UPnP Renderer front-end to MPD. May 29 15:01:08 volumio-hc sudo[1598]: pam_unix(sudo:session): session closed for user root May 29 15:01:08 volumio-hc volumio[990]: info: Upmpdcli Daemon Started May 29 15:01:08 volumio-hc volumio[1600]: Generating RSA private key, 4096 bit long modulus (2 primes) May 29 15:01:08 volumio-hc volumio[990]: STREAMING PROXY: Starting server on port 3245 May 29 15:01:08 volumio-hc volumio[990]: Node JS runtime: 14 May 29 15:01:08 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 29 15:01:08 volumio-hc volumio[990]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 15:01:08 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 15:01:08 volumio-hc volumio[990]: info: Received Get System Info May 29 15:01:08 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 15:01:08 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 15:01:08 volumio-hc volumio[990]: info: Discovery: Getting this device information May 29 15:01:08 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:08 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:08 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 15:01:08 volumio-hc sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.33/Public/Musique /mnt/NAS/Musique May 29 15:01:08 volumio-hc sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:08 volumio-hc kernel: CIFS: Attempting to mount //192.168.1.33/Public/Musique May 29 15:01:08 volumio-hc sudo[1617]: pam_unix(sudo:session): session closed for user root May 29 15:01:08 volumio-hc volumio[990]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.757&uuid=38c9ad8d20fd89a50dbe2b5171a9f0e7" http://updates.volumio.org/downloader-v1/track-device May 29 15:01:08 volumio-hc volumio[990]: % Total % Received % Xferd Average Speed Time Time Time Current May 29 15:01:08 volumio-hc volumio[990]: Dload Upload Total Spent Left Speed May 29 15:01:08 volumio-hc volumio[990]: [132B blob data] May 29 15:01:08 volumio-hc volumio[990]: retrying in 5 seconds, trial 0 May 29 15:01:08 volumio-hc volumio[990]: info: Volumio Calling Home May 29 15:01:08 volumio-hc volumio[990]: info: Volumio called home May 29 15:01:08 volumio-hc volumio[990]: info: MyVolumio token set successfully May 29 15:01:08 volumio-hc volumio[990]: info: MYVOLUMIO: Adding device May 29 15:01:08 volumio-hc volumio[990]: info: MYVOLUMIO: Evaluating Server May 29 15:01:08 volumio-hc ntpd[1523]: Soliciting pool server 82.67.126.242 May 29 15:01:08 volumio-hc ntpd[1523]: Soliciting pool server 54.38.114.34 May 29 15:01:08 volumio-hc ntpd[1523]: Soliciting pool server 162.159.200.123 May 29 15:01:09 volumio-hc volumio[990]: info: MyVolumio status changed May 29 15:01:09 volumio-hc volumio[990]: info: Streaming services startup May 29 15:01:09 volumio-hc volumio[990]: info: Starting Streaming Daemon May 29 15:01:09 volumio-hc volumio[990]: info: Removing browser output: myVolumio user plan is not superstar May 29 15:01:09 volumio-hc volumio[990]: info: Removing audio output: May 29 15:01:09 volumio-hc volumio[990]: info: Stoppping Tunnel 1 May 29 15:01:09 volumio-hc volumio[1600]: ...................................................................................................................................++++ May 29 15:01:09 volumio-hc sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 15:01:09 volumio-hc sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:09 volumio-hc sudo[1648]: pam_unix(sudo:session): session closed for user root May 29 15:01:09 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 15:01:09 volumio-hc volumio[990]: info: Received Get System Info May 29 15:01:09 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 15:01:09 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 15:01:09 volumio-hc volumio[990]: info: Discovery: Getting this device information May 29 15:01:09 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:09 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:09 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 15:01:09 volumio-hc sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 15:01:09 volumio-hc sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 15:01:09 volumio-hc volumio[990]: error: Cannot start Volumio Streaming Daemon May 29 15:01:09 volumio-hc volumio[990]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 15:01:09 volumio-hc volumio[990]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 15:01:09 volumio-hc sudo[1651]: pam_unix(sudo:session): session closed for user root May 29 15:01:09 volumio-hc volumio[990]: info: Remote SSH Stopped May 29 15:01:09 volumio-hc volumio[1600]: .................................................................................................++++ May 29 15:01:09 volumio-hc volumio[1600]: e is 65537 (0x010001) May 29 15:01:09 volumio-hc volumio[1600]: writing RSA key May 29 15:01:09 volumio-hc ntpd[1523]: Soliciting pool server 5.39.80.51 May 29 15:01:09 volumio-hc ntpd[1523]: Soliciting pool server 129.250.35.251 May 29 15:01:09 volumio-hc ntpd[1523]: Soliciting pool server 51.68.44.27 May 29 15:01:09 volumio-hc ntpd[1523]: Soliciting pool server 37.59.63.125 May 29 15:01:10 volumio-hc volumio[990]: info: Setting Geolocation for MyVolumio to eu4 May 29 15:01:10 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:01:10 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:01:10 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:01:10 volumio-hc volumio[990]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 29 15:01:10 volumio-hc ntpd[1523]: Soliciting pool server 212.85.158.10 May 29 15:01:10 volumio-hc ntpd[1523]: Soliciting pool server 82.66.40.79 May 29 15:01:10 volumio-hc ntpd[1523]: Soliciting pool server 194.57.169.1 May 29 15:01:11 volumio-hc volumio[990]: info: Getting Spotify volume May 29 15:01:11 volumio-hc volumio[990]: info: Spotify volume: 100 May 29 15:01:11 volumio-hc volumio[990]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 29 15:01:11 volumio-hc volumio[990]: info: CoreCommandRouter::volumioGetState May 29 15:01:11 volumio-hc volumio[990]: info: CorePlayQueue::getTrack 0 May 29 15:01:11 volumio-hc volumio[990]: info: Updating MyVolumio device info May 29 15:01:11 volumio-hc volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 15:01:11 volumio-hc volumio[990]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 29 15:01:11 volumio-hc ntpd[1523]: Soliciting pool server 82.64.81.218 May 29 15:01:11 volumio-hc ntpd[1523]: Soliciting pool server 92.222.227.225 May 29 15:01:12 volumio-hc ntpd[1523]: Soliciting pool server 82.67.41.119 May 29 15:01:12 volumio-hc ntpd[1523]: Soliciting pool server 2001:41d0:8:7a7d::1 May 29 15:01:14 volumio-hc volumio[990]: info: MYVOLUMIO: Adding device May 29 15:01:14 volumio-hc volumio[990]: info: MYVOLUMIO: Evaluating Server May 29 15:01:14 volumio-hc volumio[990]: error: Failed to ping endpoint us3.myvolumio.org : unknown error May 29 15:01:14 volumio-hc volumio[990]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 15:01:14 volumio-hc ntpd[1523]: receive: Unexpected origin timestamp 0xebe2d91a.e59cbb7a does not match aorg 0000000000.00000000 from server@45.13.105.44 xmt 0xebe2d91a.bdbace32 May 29 15:01:14 volumio-hc ntpd[1523]: receive: Unexpected origin timestamp 0xebe2d91a.e5940b74 does not match aorg 0000000000.00000000 from server@82.64.81.218 xmt 0xebe2d91a.be23d141 May 29 15:01:14 volumio-hc volumio[990]: Error: Unable to resolve or reject the same promise twice May 29 15:01:14 volumio-hc volumio[990]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 29 15:01:14 volumio-hc volumio[990]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) May 29 15:01:14 volumio-hc volumio[990]: at Socket.emit (events.js:412:35) May 29 15:01:14 volumio-hc volumio[990]: at endReadableNT (internal/streams/readable.js:1333:12) May 29 15:01:14 volumio-hc volumio[990]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 29 15:01:14 volumio-hc volumio[990]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 15:01:14 volumio-hc sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 15:00 May 29 15:01:14 volumio-hc sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"