-- Logs begin at Tue 2025-01-21 17:38:12 +07, end at Tue 2025-01-21 17:38:56 +07. -- Jan 21 17:38:12 v 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 Jan 21 17:38:12 v kernel: Command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=5d6a5e08-117c-42c4-b43b-04eb7d4d7a2f bootpart=UUID=DC0B-9C7A datapart=UUID=956f79d0-cc34-4920-bdf9-3223d9863e1a 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 Jan 21 17:38:12 v kernel: KERNEL supported cpus: Jan 21 17:38:12 v kernel: Intel GenuineIntel Jan 21 17:38:12 v kernel: AMD AuthenticAMD Jan 21 17:38:12 v kernel: Hygon HygonGenuine Jan 21 17:38:12 v kernel: Centaur CentaurHauls Jan 21 17:38:12 v kernel: zhaoxin Shanghai Jan 21 17:38:12 v kernel: BIOS-provided physical RAM map: Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000005efff] usable Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x000000000005f000-0x000000000005ffff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x0000000000060000-0x000000000009ffff] usable Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x00000000000a0000-0x00000000000fffff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007968dfff] usable Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x000000007968e000-0x0000000079eaefff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x0000000079eaf000-0x0000000079f2bfff] ACPI data Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x0000000079f2c000-0x000000007a373fff] ACPI NVS Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x000000007a374000-0x000000007ac51fff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x000000007ac52000-0x000000007ad0dfff] type 20 Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x000000007ad0e000-0x000000007ad0efff] usable Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x000000007ad0f000-0x000000007fffffff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x00000000fe000000-0x00000000fe010fff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x00000000fed00000-0x00000000fed03fff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved Jan 21 17:38:12 v kernel: BIOS-e820: [mem 0x0000000100000000-0x000000027dffffff] usable Jan 21 17:38:12 v kernel: NX (Execute Disable) protection: active Jan 21 17:38:12 v kernel: APIC: Static calls initialized Jan 21 17:38:12 v kernel: efi: EFI v2.7 by American Megatrends Jan 21 17:38:12 v kernel: efi: ACPI 2.0=0x79ec8000 ACPI=0x79ec8000 TPMFinalLog=0x7a31f000 SMBIOS=0x7aaa4000 SMBIOS 3.0=0x7aaa3000 MEMATTR=0x776a2418 ESRT=0x7aaa9598 Jan 21 17:38:12 v kernel: efi: Remove mem42: MMIO range=[0xe0000000-0xefffffff] (256MB) from e820 map Jan 21 17:38:12 v kernel: e820: remove [mem 0xe0000000-0xefffffff] reserved Jan 21 17:38:12 v kernel: efi: Not removing mem43: MMIO range=[0xfe000000-0xfe010fff] (68KB) from e820 map Jan 21 17:38:12 v kernel: efi: Not removing mem44: MMIO range=[0xfec00000-0xfec00fff] (4KB) from e820 map Jan 21 17:38:12 v kernel: efi: Not removing mem45: MMIO range=[0xfed00000-0xfed03fff] (16KB) from e820 map Jan 21 17:38:12 v kernel: efi: Not removing mem46: MMIO range=[0xfee00000-0xfee00fff] (4KB) from e820 map Jan 21 17:38:12 v kernel: efi: Remove mem47: MMIO range=[0xff000000-0xffffffff] (16MB) from e820 map Jan 21 17:38:12 v kernel: e820: remove [mem 0xff000000-0xffffffff] reserved Jan 21 17:38:12 v kernel: SMBIOS 3.2.1 present. Jan 21 17:38:12 v kernel: DMI: Intel(R) Client Systems NUC8i3BEH/NUC8BEB, BIOS BECFL357.86A.0094.2023.0612.1527 06/12/2023 Jan 21 17:38:12 v kernel: tsc: Detected 3000.000 MHz processor Jan 21 17:38:12 v kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jan 21 17:38:12 v kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jan 21 17:38:12 v kernel: last_pfn = 0x27e000 max_arch_pfn = 0x400000000 Jan 21 17:38:12 v kernel: MTRR map: 5 entries (3 fixed + 2 variable; max 23), built from 10 variable MTRRs Jan 21 17:38:12 v kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jan 21 17:38:12 v kernel: last_pfn = 0x7ad0f max_arch_pfn = 0x400000000 Jan 21 17:38:12 v kernel: found SMP MP-table at [mem 0x000fcd90-0x000fcd9f] Jan 21 17:38:12 v kernel: esrt: Reserving ESRT space from 0x000000007aaa9598 to 0x000000007aaa95d0. Jan 21 17:38:12 v kernel: Using GB pages for direct mapping Jan 21 17:38:12 v kernel: Secure boot could not be determined Jan 21 17:38:12 v kernel: RAMDISK: [mem 0x2fae7000-0x33d6afff] Jan 21 17:38:12 v kernel: ACPI: Early table checksum verification disabled Jan 21 17:38:12 v kernel: ACPI: RSDP 0x0000000079EC8000 000024 (v02 INTEL ) Jan 21 17:38:12 v kernel: ACPI: XSDT 0x0000000079EC80A8 0000D4 (v01 INTEL NUC8i3BE 0000005E AMI 00010013) Jan 21 17:38:12 v kernel: ACPI: FACP 0x0000000079F06C00 000114 (v06 INTEL NUC8i3BE 0000005E AMI 00010013) Jan 21 17:38:12 v kernel: ACPI: DSDT 0x0000000079EC8210 03E9F0 (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: FACS 0x000000007A373080 000040 Jan 21 17:38:12 v kernel: ACPI: APIC 0x0000000079F06D18 000084 (v04 INTEL NUC8i3BE 0000005E AMI 00010013) Jan 21 17:38:12 v kernel: ACPI: FPDT 0x0000000079F06DA0 000044 (v01 INTEL NUC8i3BE 0000005E AMI 00010013) Jan 21 17:38:12 v kernel: ACPI: FIDT 0x0000000079F06DE8 00009C (v01 INTEL NUC8i3BE 0000005E AMI 00010013) Jan 21 17:38:12 v kernel: ACPI: MCFG 0x0000000079F06E88 00003C (v01 INTEL NUC8i3BE 0000005E MSFT 00000097) Jan 21 17:38:12 v kernel: ACPI: SSDT 0x0000000079F06EC8 001B1C (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: SSDT 0x0000000079F089E8 0031C6 (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: HPET 0x0000000079F0BBB0 000038 (v01 INTEL NUC8i3BE 0000005E 01000013) Jan 21 17:38:12 v kernel: ACPI: SSDT 0x0000000079F0BBE8 000FAE (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: SSDT 0x0000000079F0CB98 003048 (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: UEFI 0x0000000079F0FBE0 000042 (v01 INTEL NUC8i3BE 0000005E 01000013) Jan 21 17:38:12 v kernel: ACPI: LPIT 0x0000000079F0FC28 000094 (v01 INTEL NUC8i3BE 0000005E 01000013) Jan 21 17:38:12 v kernel: ACPI: SSDT 0x0000000079F0FCC0 0027DE (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: SSDT 0x0000000079F124A0 0008B8 (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: DBGP 0x0000000079F12D58 000034 (v01 INTEL NUC8i3BE 0000005E 01000013) Jan 21 17:38:12 v kernel: ACPI: DBG2 0x0000000079F12D90 000054 (v00 INTEL NUC8i3BE 0000005E 01000013) Jan 21 17:38:12 v kernel: ACPI: DMAR 0x0000000079F12DE8 0000A8 (v01 INTEL NUC8i3BE 0000005E 01000013) Jan 21 17:38:12 v kernel: ACPI: SSDT 0x0000000079F12E90 000144 (v02 INTEL NUC8i3BE 0000005E INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: NHLT 0x0000000079F12FD8 00002D (v00 INTEL NUC8i3BE 0000005E 01000013) Jan 21 17:38:12 v kernel: ACPI: BGRT 0x0000000079F13008 000038 (v01 INTEL NUC8i3BE 0000005E AMI 00010013) Jan 21 17:38:12 v kernel: ACPI: TPM2 0x0000000079F13040 000034 (v04 INTEL NUC8i3BE 0000005E AMI 00000000) Jan 21 17:38:12 v kernel: ACPI: WSMT 0x0000000079F13078 000028 (v01 INTEL NUC8i3BE 0000005E AMI 00010013) Jan 21 17:38:12 v kernel: ACPI: Reserving FACP table memory at [mem 0x79f06c00-0x79f06d13] Jan 21 17:38:12 v kernel: ACPI: Reserving DSDT table memory at [mem 0x79ec8210-0x79f06bff] Jan 21 17:38:12 v kernel: ACPI: Reserving FACS table memory at [mem 0x7a373080-0x7a3730bf] Jan 21 17:38:12 v kernel: ACPI: Reserving APIC table memory at [mem 0x79f06d18-0x79f06d9b] Jan 21 17:38:12 v kernel: ACPI: Reserving FPDT table memory at [mem 0x79f06da0-0x79f06de3] Jan 21 17:38:12 v kernel: ACPI: Reserving FIDT table memory at [mem 0x79f06de8-0x79f06e83] Jan 21 17:38:12 v kernel: ACPI: Reserving MCFG table memory at [mem 0x79f06e88-0x79f06ec3] Jan 21 17:38:12 v kernel: ACPI: Reserving SSDT table memory at [mem 0x79f06ec8-0x79f089e3] Jan 21 17:38:12 v kernel: ACPI: Reserving SSDT table memory at [mem 0x79f089e8-0x79f0bbad] Jan 21 17:38:12 v kernel: ACPI: Reserving HPET table memory at [mem 0x79f0bbb0-0x79f0bbe7] Jan 21 17:38:12 v kernel: ACPI: Reserving SSDT table memory at [mem 0x79f0bbe8-0x79f0cb95] Jan 21 17:38:12 v kernel: ACPI: Reserving SSDT table memory at [mem 0x79f0cb98-0x79f0fbdf] Jan 21 17:38:12 v kernel: ACPI: Reserving UEFI table memory at [mem 0x79f0fbe0-0x79f0fc21] Jan 21 17:38:12 v kernel: ACPI: Reserving LPIT table memory at [mem 0x79f0fc28-0x79f0fcbb] Jan 21 17:38:12 v kernel: ACPI: Reserving SSDT table memory at [mem 0x79f0fcc0-0x79f1249d] Jan 21 17:38:12 v kernel: ACPI: Reserving SSDT table memory at [mem 0x79f124a0-0x79f12d57] Jan 21 17:38:12 v kernel: ACPI: Reserving DBGP table memory at [mem 0x79f12d58-0x79f12d8b] Jan 21 17:38:12 v kernel: ACPI: Reserving DBG2 table memory at [mem 0x79f12d90-0x79f12de3] Jan 21 17:38:12 v kernel: ACPI: Reserving DMAR table memory at [mem 0x79f12de8-0x79f12e8f] Jan 21 17:38:12 v kernel: ACPI: Reserving SSDT table memory at [mem 0x79f12e90-0x79f12fd3] Jan 21 17:38:12 v kernel: ACPI: Reserving NHLT table memory at [mem 0x79f12fd8-0x79f13004] Jan 21 17:38:12 v kernel: ACPI: Reserving BGRT table memory at [mem 0x79f13008-0x79f1303f] Jan 21 17:38:12 v kernel: ACPI: Reserving TPM2 table memory at [mem 0x79f13040-0x79f13073] Jan 21 17:38:12 v kernel: ACPI: Reserving WSMT table memory at [mem 0x79f13078-0x79f1309f] Jan 21 17:38:12 v kernel: Zone ranges: Jan 21 17:38:12 v kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jan 21 17:38:12 v kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jan 21 17:38:12 v kernel: Normal [mem 0x0000000100000000-0x000000027dffffff] Jan 21 17:38:12 v kernel: Movable zone start for each node Jan 21 17:38:12 v kernel: Early memory node ranges Jan 21 17:38:12 v kernel: node 0: [mem 0x0000000000001000-0x000000000005efff] Jan 21 17:38:12 v kernel: node 0: [mem 0x0000000000060000-0x000000000009ffff] Jan 21 17:38:12 v kernel: node 0: [mem 0x0000000000100000-0x000000007968dfff] Jan 21 17:38:12 v kernel: node 0: [mem 0x000000007ad0e000-0x000000007ad0efff] Jan 21 17:38:12 v kernel: node 0: [mem 0x0000000100000000-0x000000027dffffff] Jan 21 17:38:12 v kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000027dffffff] Jan 21 17:38:12 v kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 21 17:38:12 v kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 21 17:38:12 v kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jan 21 17:38:12 v kernel: On node 0, zone DMA32: 5760 pages in unavailable ranges Jan 21 17:38:12 v kernel: On node 0, zone Normal: 21233 pages in unavailable ranges Jan 21 17:38:12 v kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Jan 21 17:38:12 v kernel: Reserving Intel graphics memory at [mem 0x7c000000-0x7fffffff] Jan 21 17:38:12 v kernel: ACPI: PM-Timer IO Port: 0x1808 Jan 21 17:38:12 v kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1]) Jan 21 17:38:12 v kernel: ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1]) Jan 21 17:38:12 v kernel: ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1]) Jan 21 17:38:12 v kernel: ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1]) Jan 21 17:38:12 v kernel: IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-119 Jan 21 17:38:12 v kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jan 21 17:38:12 v kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jan 21 17:38:12 v kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jan 21 17:38:12 v kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Jan 21 17:38:12 v kernel: e820: update [mem 0x75617000-0x756b0fff] usable ==> reserved Jan 21 17:38:12 v kernel: TSC deadline timer available Jan 21 17:38:12 v kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x0005f000-0x0005ffff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000fffff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x75617000-0x756b0fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x7968e000-0x79eaefff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x79eaf000-0x79f2bfff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x79f2c000-0x7a373fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x7a374000-0x7ac51fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x7ac52000-0x7ad0dfff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x7ad0f000-0x7fffffff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0x80000000-0xfdffffff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfe000000-0xfe010fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfe011000-0xfebfffff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfec00000-0xfec00fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfec01000-0xfecfffff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfed00000-0xfed03fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfed04000-0xfedfffff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfee00000-0xfee00fff] Jan 21 17:38:12 v kernel: PM: hibernation: Registered nosave memory: [mem 0xfee01000-0xffffffff] Jan 21 17:38:12 v kernel: [mem 0x80000000-0xfdffffff] available for PCI devices Jan 21 17:38:12 v kernel: Booting paravirtualized kernel on bare hardware Jan 21 17:38:12 v kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Jan 21 17:38:12 v kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Jan 21 17:38:12 v kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u524288 Jan 21 17:38:12 v kernel: pcpu-alloc: s191400 r8192 d29784 u524288 alloc=1*2097152 Jan 21 17:38:12 v kernel: pcpu-alloc: [0] 0 1 2 3 Jan 21 17:38:12 v kernel: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=5d6a5e08-117c-42c4-b43b-04eb7d4d7a2f bootpart=UUID=DC0B-9C7A datapart=UUID=956f79d0-cc34-4920-bdf9-3223d9863e1a 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 Jan 21 17:38:12 v kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=/vmlinuz biosdevname=0 imgpart=UUID=5d6a5e08-117c-42c4-b43b-04eb7d4d7a2f bootpart=UUID=DC0B-9C7A datapart=UUID=956f79d0-cc34-4920-bdf9-3223d9863e1a hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Jan 21 17:38:12 v kernel: random: crng init done Jan 21 17:38:12 v kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Jan 21 17:38:12 v kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jan 21 17:38:12 v kernel: Built 1 zonelists, mobility grouping on. Total pages: 2029492 Jan 21 17:38:12 v kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off Jan 21 17:38:12 v kernel: software IO TLB: area num 4. Jan 21 17:38:12 v kernel: Memory: 7895484K/8247476K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 351736K reserved, 0K cma-reserved) Jan 21 17:38:12 v kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jan 21 17:38:12 v kernel: Kernel/User page tables isolation: enabled Jan 21 17:38:12 v kernel: ftrace: allocating 40433 entries in 158 pages Jan 21 17:38:12 v kernel: ftrace: allocated 158 pages with 5 groups Jan 21 17:38:12 v kernel: Dynamic Preempt: voluntary Jan 21 17:38:12 v kernel: rcu: Preemptible hierarchical RCU implementation. Jan 21 17:38:12 v kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Jan 21 17:38:12 v kernel: Trampoline variant of Tasks RCU enabled. Jan 21 17:38:12 v kernel: Rude variant of Tasks RCU enabled. Jan 21 17:38:12 v kernel: Tracing variant of Tasks RCU enabled. Jan 21 17:38:12 v kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Jan 21 17:38:12 v kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jan 21 17:38:12 v kernel: NR_IRQS: 4352, nr_irqs: 1024, preallocated irqs: 16 Jan 21 17:38:12 v kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 21 17:38:12 v kernel: Console: colour dummy device 80x25 Jan 21 17:38:12 v kernel: printk: console [tty0] enabled Jan 21 17:38:12 v kernel: ACPI: Core revision 20230628 Jan 21 17:38:12 v kernel: hpet: HPET dysfunctional in PC10. Force disabled. Jan 21 17:38:12 v kernel: APIC: Switch to symmetric I/O mode setup Jan 21 17:38:12 v kernel: x2apic: IRQ remapping doesn't support X2APIC mode Jan 21 17:38:12 v kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2b3e459bf4c, max_idle_ns: 440795289890 ns Jan 21 17:38:12 v kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 6000.00 BogoMIPS (lpj=12000000) Jan 21 17:38:12 v kernel: CPU0: Thermal monitoring enabled (TM1) Jan 21 17:38:12 v kernel: process: using mwait in idle threads Jan 21 17:38:12 v kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 Jan 21 17:38:12 v kernel: Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4 Jan 21 17:38:12 v kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jan 21 17:38:12 v kernel: Spectre V2 : Mitigation: IBRS Jan 21 17:38:12 v kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Jan 21 17:38:12 v kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Jan 21 17:38:12 v kernel: RETBleed: Mitigation: IBRS Jan 21 17:38:12 v kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Jan 21 17:38:12 v kernel: Spectre V2 : User space: Mitigation: STIBP via prctl Jan 21 17:38:12 v kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Jan 21 17:38:12 v kernel: MDS: Mitigation: Clear CPU buffers Jan 21 17:38:12 v kernel: MMIO Stale Data: Mitigation: Clear CPU buffers Jan 21 17:38:12 v kernel: SRBDS: Mitigation: Microcode Jan 21 17:38:12 v kernel: GDS: Mitigation: Microcode Jan 21 17:38:12 v kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jan 21 17:38:12 v kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jan 21 17:38:12 v kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jan 21 17:38:12 v kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' Jan 21 17:38:12 v kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' Jan 21 17:38:12 v kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jan 21 17:38:12 v kernel: x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 Jan 21 17:38:12 v kernel: x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 Jan 21 17:38:12 v kernel: x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format. Jan 21 17:38:12 v kernel: Freeing SMP alternatives memory: 32K Jan 21 17:38:12 v kernel: pid_max: default: 32768 minimum: 301 Jan 21 17:38:12 v kernel: LSM: initializing lsm=capability,yama,integrity Jan 21 17:38:12 v kernel: Yama: becoming mindful. Jan 21 17:38:12 v kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jan 21 17:38:12 v kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jan 21 17:38:12 v kernel: smpboot: CPU0: Intel(R) Core(TM) i3-8109U CPU @ 3.00GHz (family: 0x6, model: 0x8e, stepping: 0xa) Jan 21 17:38:12 v kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Jan 21 17:38:12 v kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Jan 21 17:38:12 v kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Jan 21 17:38:12 v kernel: Performance Events: PEBS fmt3+, Skylake events, 32-deep LBR, full-width counters, Intel PMU driver. Jan 21 17:38:12 v kernel: ... version: 4 Jan 21 17:38:12 v kernel: ... bit width: 48 Jan 21 17:38:12 v kernel: ... generic registers: 4 Jan 21 17:38:12 v kernel: ... value mask: 0000ffffffffffff Jan 21 17:38:12 v kernel: ... max period: 00007fffffffffff Jan 21 17:38:12 v kernel: ... fixed-purpose events: 3 Jan 21 17:38:12 v kernel: ... event mask: 000000070000000f Jan 21 17:38:12 v kernel: signal: max sigframe size: 2032 Jan 21 17:38:12 v kernel: Estimated ratio of average max frequency by base frequency (times 1024): 1228 Jan 21 17:38:12 v kernel: rcu: Hierarchical SRCU implementation. Jan 21 17:38:12 v kernel: rcu: Max phase no-delay instances is 1000. Jan 21 17:38:12 v kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. Jan 21 17:38:12 v kernel: smp: Bringing up secondary CPUs ... Jan 21 17:38:12 v kernel: smpboot: x86: Booting SMP configuration: Jan 21 17:38:12 v kernel: .... node #0, CPUs: #1 #2 #3 Jan 21 17:38:12 v kernel: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. Jan 21 17:38:12 v kernel: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details. Jan 21 17:38:12 v kernel: smp: Brought up 1 node, 4 CPUs Jan 21 17:38:12 v kernel: smpboot: Max logical packages: 1 Jan 21 17:38:12 v kernel: smpboot: Total of 4 processors activated (24000.00 BogoMIPS) Jan 21 17:38:12 v kernel: devtmpfs: initialized Jan 21 17:38:12 v kernel: x86/mm: Memory block size: 128MB Jan 21 17:38:12 v kernel: ACPI: PM: Registering ACPI NVS region [mem 0x79f2c000-0x7a373fff] (4489216 bytes) Jan 21 17:38:12 v kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jan 21 17:38:12 v kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jan 21 17:38:12 v kernel: pinctrl core: initialized pinctrl subsystem Jan 21 17:38:12 v kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 21 17:38:12 v kernel: audit: initializing netlink subsys (disabled) Jan 21 17:38:12 v kernel: audit: type=2000 audit(1737455886.004:1): state=initialized audit_enabled=0 res=1 Jan 21 17:38:12 v kernel: thermal_sys: Registered thermal governor 'fair_share' Jan 21 17:38:12 v kernel: thermal_sys: Registered thermal governor 'bang_bang' Jan 21 17:38:12 v kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 21 17:38:12 v kernel: thermal_sys: Registered thermal governor 'user_space' Jan 21 17:38:12 v kernel: EISA bus registered Jan 21 17:38:12 v kernel: cpuidle: using governor ladder Jan 21 17:38:12 v kernel: cpuidle: using governor menu Jan 21 17:38:12 v kernel: ACPI FADT declares the system doesn't support PCIe ASPM, so disable it Jan 21 17:38:12 v kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 21 17:38:12 v kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Jan 21 17:38:12 v kernel: PCI: not using MMCONFIG Jan 21 17:38:12 v kernel: PCI: Using configuration type 1 for base access Jan 21 17:38:12 v kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance' Jan 21 17:38:12 v kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 21 17:38:12 v kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jan 21 17:38:12 v kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jan 21 17:38:12 v kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 21 17:38:12 v kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jan 21 17:38:12 v kernel: ACPI: Added _OSI(Module Device) Jan 21 17:38:12 v kernel: ACPI: Added _OSI(Processor Device) Jan 21 17:38:12 v kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jan 21 17:38:12 v kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 21 17:38:12 v kernel: ACPI: 8 ACPI AML tables successfully acquired and loaded Jan 21 17:38:12 v kernel: ACPI: Dynamic OEM Table Load: Jan 21 17:38:12 v kernel: ACPI: SSDT 0xFFFF888100B1DC00 000400 (v02 PmRef Cpu0Cst 00003001 INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: Dynamic OEM Table Load: Jan 21 17:38:12 v kernel: ACPI: SSDT 0xFFFF888100B63000 000689 (v02 PmRef Cpu0Ist 00003000 INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: Dynamic OEM Table Load: Jan 21 17:38:12 v kernel: ACPI: SSDT 0xFFFF888100961600 0000F4 (v02 PmRef Cpu0Psd 00003000 INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: Dynamic OEM Table Load: Jan 21 17:38:12 v kernel: ACPI: SSDT 0xFFFF888100B64000 0005FC (v02 PmRef ApIst 00003000 INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: Dynamic OEM Table Load: Jan 21 17:38:12 v kernel: ACPI: SSDT 0xFFFF888100B1E000 000317 (v02 PmRef ApHwp 00003000 INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: Dynamic OEM Table Load: Jan 21 17:38:12 v kernel: ACPI: SSDT 0xFFFF888100991000 000AB0 (v02 PmRef ApPsd 00003000 INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: Dynamic OEM Table Load: Jan 21 17:38:12 v kernel: ACPI: SSDT 0xFFFF888100B1E800 00030A (v02 PmRef ApCst 00003000 INTL 20160527) Jan 21 17:38:12 v kernel: ACPI: _OSC evaluated successfully for all CPUs Jan 21 17:38:12 v kernel: ACPI: EC: EC started Jan 21 17:38:12 v kernel: ACPI: EC: interrupt blocked Jan 21 17:38:12 v kernel: ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.LPCB.H_EC: Boot DSDT EC used to handle transactions Jan 21 17:38:12 v kernel: ACPI: Interpreter enabled Jan 21 17:38:12 v kernel: ACPI: PM: (supports S0 S3 S4 S5) Jan 21 17:38:12 v kernel: ACPI: Using IOAPIC for interrupt routing Jan 21 17:38:12 v kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Jan 21 17:38:12 v kernel: PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved as ACPI motherboard resource Jan 21 17:38:12 v kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jan 21 17:38:12 v kernel: PCI: Ignoring E820 reservations for host bridge windows Jan 21 17:38:12 v kernel: ACPI: Enabled 8 GPEs in block 00 to 7F Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.XDCI.USBC: New power resource Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.SAT0.VOL0.V0PR: New power resource Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.SAT0.VOL1.V1PR: New power resource Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.SAT0.VOL2.V2PR: New power resource Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.CNVW.WRST: New power resource Jan 21 17:38:12 v kernel: ACPI: \_TZ_.FN00: New power resource Jan 21 17:38:12 v kernel: ACPI: \_TZ_.FN01: New power resource Jan 21 17:38:12 v kernel: ACPI: \_TZ_.FN02: New power resource Jan 21 17:38:12 v kernel: ACPI: \_TZ_.FN03: New power resource Jan 21 17:38:12 v kernel: ACPI: \_TZ_.FN04: New power resource Jan 21 17:38:12 v kernel: ACPI: \PIN_: New power resource Jan 21 17:38:12 v kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-fe]) Jan 21 17:38:12 v kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jan 21 17:38:12 v kernel: acpi PNP0A08:00: _OSC: platform does not support [AER] Jan 21 17:38:12 v kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME PCIeCapability LTR] Jan 21 17:38:12 v kernel: acpi PNP0A08:00: FADT indicates ASPM is unsupported, using BIOS configuration Jan 21 17:38:12 v kernel: PCI host bridge to bus 0000:00 Jan 21 17:38:12 v kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xdfffffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: root bus resource [mem 0x4000000000-0x7fffffffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: root bus resource [mem 0xfc800000-0xfe7fffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: root bus resource [bus 00-fe] Jan 21 17:38:12 v kernel: pci 0000:00:00.0: [8086:3ecc] type 00 class 0x060000 Jan 21 17:38:12 v kernel: pci 0000:00:02.0: [8086:3ea5] type 00 class 0x030000 Jan 21 17:38:12 v kernel: pci 0000:00:02.0: reg 0x10: [mem 0xbf000000-0xbfffffff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:02.0: reg 0x18: [mem 0x80000000-0x8fffffff 64bit pref] Jan 21 17:38:12 v kernel: pci 0000:00:02.0: reg 0x20: [io 0x4000-0x403f] Jan 21 17:38:12 v kernel: pci 0000:00:02.0: BAR 2: assigned to efifb Jan 21 17:38:12 v kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jan 21 17:38:12 v kernel: pci 0000:00:08.0: [8086:1911] type 00 class 0x088000 Jan 21 17:38:12 v kernel: pci 0000:00:08.0: reg 0x10: [mem 0x404ac1a000-0x404ac1afff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:12.0: [8086:9df9] type 00 class 0x118000 Jan 21 17:38:12 v kernel: pci 0000:00:12.0: reg 0x10: [mem 0x404ac19000-0x404ac19fff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:14.0: [8086:9ded] type 00 class 0x0c0330 Jan 21 17:38:12 v kernel: pci 0000:00:14.0: reg 0x10: [mem 0x404ac00000-0x404ac0ffff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:14.0: PME# supported from D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:14.2: [8086:9def] type 00 class 0x050000 Jan 21 17:38:12 v kernel: pci 0000:00:14.2: reg 0x10: [mem 0x404ac14000-0x404ac15fff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:14.2: reg 0x18: [mem 0x404ac18000-0x404ac18fff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:14.3: [8086:9df0] type 00 class 0x028000 Jan 21 17:38:12 v kernel: pci 0000:00:14.3: reg 0x10: [mem 0x404ac10000-0x404ac13fff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:14.3: PME# supported from D0 D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:16.0: [8086:9de0] type 00 class 0x078000 Jan 21 17:38:12 v kernel: pci 0000:00:16.0: reg 0x10: [mem 0x404ac17000-0x404ac17fff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:16.0: PME# supported from D3hot Jan 21 17:38:12 v kernel: pci 0000:00:17.0: [8086:9dd3] type 00 class 0x010601 Jan 21 17:38:12 v kernel: pci 0000:00:17.0: reg 0x10: [mem 0xc0b24000-0xc0b25fff] Jan 21 17:38:12 v kernel: pci 0000:00:17.0: reg 0x14: [mem 0xc0b27000-0xc0b270ff] Jan 21 17:38:12 v kernel: pci 0000:00:17.0: reg 0x18: [io 0x4090-0x4097] Jan 21 17:38:12 v kernel: pci 0000:00:17.0: reg 0x1c: [io 0x4080-0x4083] Jan 21 17:38:12 v kernel: pci 0000:00:17.0: reg 0x20: [io 0x4060-0x407f] Jan 21 17:38:12 v kernel: pci 0000:00:17.0: reg 0x24: [mem 0xc0b26000-0xc0b267ff] Jan 21 17:38:12 v kernel: pci 0000:00:17.0: PME# supported from D3hot Jan 21 17:38:12 v kernel: pci 0000:00:1c.0: [8086:9db8] type 01 class 0x060400 Jan 21 17:38:12 v kernel: pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: [8086:9dbc] type 01 class 0x060400 Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: PME# supported from D0 D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:1d.0: [8086:9db0] type 01 class 0x060400 Jan 21 17:38:12 v kernel: pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: [8086:9db6] type 01 class 0x060400 Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: PME# supported from D0 D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:1f.0: [8086:9d84] type 00 class 0x060100 Jan 21 17:38:12 v kernel: pci 0000:00:1f.3: [8086:9dc8] type 00 class 0x040380 Jan 21 17:38:12 v kernel: pci 0000:00:1f.3: reg 0x10: [mem 0xc0b20000-0xc0b23fff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:1f.3: reg 0x20: [mem 0x404ab00000-0x404abfffff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:1f.3: PME# supported from D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:1f.4: [8086:9da3] type 00 class 0x0c0500 Jan 21 17:38:12 v kernel: pci 0000:00:1f.4: reg 0x10: [mem 0x404ac16000-0x404ac160ff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:1f.4: reg 0x20: [io 0xefa0-0xefbf] Jan 21 17:38:12 v kernel: pci 0000:00:1f.5: [8086:9da4] type 00 class 0x0c8000 Jan 21 17:38:12 v kernel: pci 0000:00:1f.5: reg 0x10: [mem 0xfe010000-0xfe010fff] Jan 21 17:38:12 v kernel: pci 0000:00:1f.6: [8086:15be] type 00 class 0x020000 Jan 21 17:38:12 v kernel: pci 0000:00:1f.6: reg 0x10: [mem 0xc0b00000-0xc0b1ffff] Jan 21 17:38:12 v kernel: pci 0000:00:1f.6: PME# supported from D0 D3hot D3cold Jan 21 17:38:12 v kernel: acpiphp: Slot [1] registered Jan 21 17:38:12 v kernel: pci 0000:00:1c.0: PCI bridge to [bus 01] Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: PCI bridge to [bus 02-6c] Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: bridge window [mem 0x90000000-0xbe0fffff] Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: bridge window [mem 0x4000000000-0x4049ffffff 64bit pref] Jan 21 17:38:12 v kernel: pci 0000:6d:00.0: [144d:a809] type 00 class 0x010802 Jan 21 17:38:12 v kernel: pci 0000:6d:00.0: reg 0x10: [mem 0xc0a00000-0xc0a03fff 64bit] Jan 21 17:38:12 v kernel: pci 0000:00:1d.0: PCI bridge to [bus 6d] Jan 21 17:38:12 v kernel: pci 0000:00:1d.0: bridge window [mem 0xc0a00000-0xc0afffff] Jan 21 17:38:12 v kernel: pci 0000:6e:00.0: [10ec:522a] type 00 class 0xff0000 Jan 21 17:38:12 v kernel: pci 0000:6e:00.0: reg 0x10: [mem 0xc0000000-0xc0000fff] Jan 21 17:38:12 v kernel: pci 0000:6e:00.0: supports D1 D2 Jan 21 17:38:12 v kernel: pci 0000:6e:00.0: PME# supported from D1 D2 D3hot D3cold Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: ASPM: current common clock configuration is inconsistent, reconfiguring Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: PCI bridge to [bus 6e] Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: bridge window [io 0x3000-0x3fff] Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: bridge window [mem 0xc0000000-0xc09fffff] Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: bridge window [mem 0x404a100000-0x404aafffff 64bit pref] Jan 21 17:38:12 v kernel: pci_bus 0000:00: on NUMA node 0 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 0 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 1 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 0 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 0 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 0 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 0 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 0 Jan 21 17:38:12 v kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 0 Jan 21 17:38:12 v kernel: ACPI: EC: interrupt unblocked Jan 21 17:38:12 v kernel: ACPI: EC: event unblocked Jan 21 17:38:12 v kernel: ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 Jan 21 17:38:12 v kernel: ACPI: EC: GPE=0x14 Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.LPCB.H_EC: Boot DSDT EC initialization complete Jan 21 17:38:12 v kernel: ACPI: \_SB_.PCI0.LPCB.H_EC: EC: Used to handle transactions and events Jan 21 17:38:12 v kernel: iommu: Default domain type: Translated Jan 21 17:38:12 v kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jan 21 17:38:12 v kernel: SCSI subsystem initialized Jan 21 17:38:12 v kernel: libata version 3.00 loaded. Jan 21 17:38:12 v kernel: ACPI: bus type USB registered Jan 21 17:38:12 v kernel: usbcore: registered new interface driver usbfs Jan 21 17:38:12 v kernel: usbcore: registered new interface driver hub Jan 21 17:38:12 v kernel: usbcore: registered new device driver usb Jan 21 17:38:12 v kernel: efivars: Registered efivars operations Jan 21 17:38:12 v kernel: PCI: Using ACPI for IRQ routing Jan 21 17:38:12 v kernel: PCI: pci_cache_line_size set to 64 bytes Jan 21 17:38:12 v kernel: e820: reserve RAM buffer [mem 0x0005f000-0x0005ffff] Jan 21 17:38:12 v kernel: e820: reserve RAM buffer [mem 0x75617000-0x77ffffff] Jan 21 17:38:12 v kernel: e820: reserve RAM buffer [mem 0x7968e000-0x7bffffff] Jan 21 17:38:12 v kernel: e820: reserve RAM buffer [mem 0x7ad0f000-0x7bffffff] Jan 21 17:38:12 v kernel: e820: reserve RAM buffer [mem 0x27e000000-0x27fffffff] Jan 21 17:38:12 v kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Jan 21 17:38:12 v kernel: pci 0000:00:02.0: vgaarb: bridge control possible Jan 21 17:38:12 v kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jan 21 17:38:12 v kernel: vgaarb: loaded Jan 21 17:38:12 v kernel: clocksource: Switched to clocksource tsc-early Jan 21 17:38:12 v kernel: VFS: Disk quotas dquot_6.6.0 Jan 21 17:38:12 v kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 21 17:38:12 v kernel: pnp: PnP ACPI init Jan 21 17:38:12 v kernel: system 00:00: [mem 0x40000000-0x403fffff] could not be reserved Jan 21 17:38:12 v kernel: system 00:01: [io 0x0a00-0x0a1f] has been reserved Jan 21 17:38:12 v kernel: system 00:01: [io 0x0a20-0x0a2f] has been reserved Jan 21 17:38:12 v kernel: system 00:01: [io 0x0a30-0x0a3f] has been reserved Jan 21 17:38:12 v kernel: system 00:01: [io 0x0a40-0x0a4f] has been reserved Jan 21 17:38:12 v kernel: system 00:01: [io 0x0a50-0x0a5f] has been reserved Jan 21 17:38:12 v kernel: system 00:01: [io 0x0a60-0x0a6f] has been reserved Jan 21 17:38:12 v kernel: system 00:02: [io 0x0680-0x069f] has been reserved Jan 21 17:38:12 v kernel: system 00:02: [io 0x164e-0x164f] has been reserved Jan 21 17:38:12 v kernel: system 00:03: [io 0x1854-0x1857] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xfed10000-0xfed17fff] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xfed18000-0xfed18fff] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xfed19000-0xfed19fff] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xe0000000-0xefffffff] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xfed20000-0xfed3ffff] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xfed90000-0xfed93fff] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xfed45000-0xfed8ffff] has been reserved Jan 21 17:38:12 v kernel: system 00:04: [mem 0xfee00000-0xfeefffff] could not be reserved Jan 21 17:38:12 v kernel: system 00:05: [io 0x1800-0x18fe] could not be reserved Jan 21 17:38:12 v kernel: system 00:05: [mem 0xfd000000-0xfd69ffff] has been reserved Jan 21 17:38:12 v kernel: system 00:05: [mem 0xfd6b0000-0xfd6cffff] has been reserved Jan 21 17:38:12 v kernel: system 00:05: [mem 0xfd6f0000-0xfdffffff] has been reserved Jan 21 17:38:12 v kernel: system 00:05: [mem 0xfe000000-0xfe01ffff] could not be reserved Jan 21 17:38:12 v kernel: system 00:05: [mem 0xfe200000-0xfe7fffff] has been reserved Jan 21 17:38:12 v kernel: system 00:05: [mem 0xff000000-0xffffffff] has been reserved Jan 21 17:38:12 v kernel: system 00:06: [io 0x2000-0x20fe] has been reserved Jan 21 17:38:12 v kernel: pnp: PnP ACPI: found 8 devices Jan 21 17:38:12 v kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jan 21 17:38:12 v kernel: NET: Registered PF_INET protocol family Jan 21 17:38:12 v kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 21 17:38:12 v kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Jan 21 17:38:12 v kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 21 17:38:12 v kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Jan 21 17:38:12 v kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 21 17:38:12 v kernel: TCP: Hash tables configured (established 65536 bind 65536) Jan 21 17:38:12 v kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Jan 21 17:38:12 v kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Jan 21 17:38:12 v kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: bridge window [io 0x1000-0x0fff] to [bus 02-6c] add_size 1000 Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: BAR 13: assigned [io 0x5000-0x5fff] Jan 21 17:38:12 v kernel: pci 0000:00:1c.0: PCI bridge to [bus 01] Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: PCI bridge to [bus 02-6c] Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: bridge window [io 0x5000-0x5fff] Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: bridge window [mem 0x90000000-0xbe0fffff] Jan 21 17:38:12 v kernel: pci 0000:00:1c.4: bridge window [mem 0x4000000000-0x4049ffffff 64bit pref] Jan 21 17:38:12 v kernel: pci 0000:00:1d.0: PCI bridge to [bus 6d] Jan 21 17:38:12 v kernel: pci 0000:00:1d.0: bridge window [mem 0xc0a00000-0xc0afffff] Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: PCI bridge to [bus 6e] Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: bridge window [io 0x3000-0x3fff] Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: bridge window [mem 0xc0000000-0xc09fffff] Jan 21 17:38:12 v kernel: pci 0000:00:1d.6: bridge window [mem 0x404a100000-0x404aafffff 64bit pref] Jan 21 17:38:12 v kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xdfffffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: resource 8 [mem 0x4000000000-0x7fffffffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:00: resource 9 [mem 0xfc800000-0xfe7fffff window] Jan 21 17:38:12 v kernel: pci_bus 0000:02: resource 0 [io 0x5000-0x5fff] Jan 21 17:38:12 v kernel: pci_bus 0000:02: resource 1 [mem 0x90000000-0xbe0fffff] Jan 21 17:38:12 v kernel: pci_bus 0000:02: resource 2 [mem 0x4000000000-0x4049ffffff 64bit pref] Jan 21 17:38:12 v kernel: pci_bus 0000:6d: resource 1 [mem 0xc0a00000-0xc0afffff] Jan 21 17:38:12 v kernel: pci_bus 0000:6e: resource 0 [io 0x3000-0x3fff] Jan 21 17:38:12 v kernel: pci_bus 0000:6e: resource 1 [mem 0xc0000000-0xc09fffff] Jan 21 17:38:12 v kernel: pci_bus 0000:6e: resource 2 [mem 0x404a100000-0x404aafffff 64bit pref] Jan 21 17:38:12 v kernel: PCI: CLS 64 bytes, default 64 Jan 21 17:38:12 v kernel: DMAR: Host address width 39 Jan 21 17:38:12 v kernel: DMAR: DRHD base: 0x000000fed90000 flags: 0x0 Jan 21 17:38:12 v kernel: DMAR: dmar0: reg_base_addr fed90000 ver 1:0 cap 1c0000c40660462 ecap 19e2ff0505e Jan 21 17:38:12 v kernel: DMAR: DRHD base: 0x000000fed91000 flags: 0x1 Jan 21 17:38:12 v kernel: DMAR: dmar1: reg_base_addr fed91000 ver 1:0 cap d2008c40660462 ecap f050da Jan 21 17:38:12 v kernel: DMAR: RMRR base: 0x00000079e62000 end: 0x00000079e81fff Jan 21 17:38:12 v kernel: DMAR: RMRR base: 0x0000007b800000 end: 0x0000007fffffff Jan 21 17:38:12 v kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jan 21 17:38:12 v kernel: Trying to unpack rootfs image as initramfs... Jan 21 17:38:12 v kernel: software IO TLB: mapped [mem 0x0000000071617000-0x0000000075617000] (64MB) Jan 21 17:38:12 v kernel: RAPL PMU: API unit is 2^-32 Joules, 5 fixed counters, 655360 ms ovfl timer Jan 21 17:38:12 v kernel: RAPL PMU: hw unit of domain pp0-core 2^-14 Joules Jan 21 17:38:12 v kernel: RAPL PMU: hw unit of domain package 2^-14 Joules Jan 21 17:38:12 v kernel: RAPL PMU: hw unit of domain dram 2^-14 Joules Jan 21 17:38:12 v kernel: RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules Jan 21 17:38:12 v kernel: RAPL PMU: hw unit of domain psys 2^-14 Joules Jan 21 17:38:12 v kernel: platform rtc_cmos: registered platform RTC device (no PNP device found) Jan 21 17:38:12 v kernel: Initialise system trusted keyrings Jan 21 17:38:12 v kernel: workingset: timestamp_bits=46 max_order=21 bucket_order=0 Jan 21 17:38:12 v kernel: zbud: loaded Jan 21 17:38:12 v kernel: Key type asymmetric registered Jan 21 17:38:12 v kernel: Asymmetric key parser 'x509' registered Jan 21 17:38:12 v kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Jan 21 17:38:12 v kernel: io scheduler mq-deadline registered Jan 21 17:38:12 v kernel: io scheduler kyber registered Jan 21 17:38:12 v kernel: pcieport 0000:00:1c.0: PME: Signaling with IRQ 120 Jan 21 17:38:12 v kernel: pcieport 0000:00:1c.4: PME: Signaling with IRQ 121 Jan 21 17:38:12 v kernel: pcieport 0000:00:1c.4: pciehp: Slot #8 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl+ IbPresDis- LLActRep+ Jan 21 17:38:12 v kernel: pcieport 0000:00:1d.0: PME: Signaling with IRQ 122 Jan 21 17:38:12 v kernel: pcieport 0000:00:1d.6: PME: Signaling with IRQ 123 Jan 21 17:38:12 v kernel: pcieport 0000:00:1d.6: pciehp: Slot #18 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl+ IbPresDis- LLActRep+ Jan 21 17:38:12 v kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Jan 21 17:38:12 v kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input0 Jan 21 17:38:12 v kernel: ACPI: button: Sleep Button [SLPB] Jan 21 17:38:12 v kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 Jan 21 17:38:12 v kernel: ACPI: button: Power Button [PWRB] Jan 21 17:38:12 v kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Jan 21 17:38:12 v kernel: ACPI: button: Power Button [PWRF] Jan 21 17:38:12 v kernel: thermal LNXTHERM:00: registered as thermal_zone0 Jan 21 17:38:12 v kernel: ACPI: thermal: Thermal Zone [TZ0] (-263 C) Jan 21 17:38:12 v kernel: thermal LNXTHERM:01: registered as thermal_zone1 Jan 21 17:38:12 v kernel: ACPI: thermal: Thermal Zone [TZ00] (28 C) Jan 21 17:38:12 v kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Jan 21 17:38:12 v kernel: hpet_acpi_add: no address or irqs in _CRS Jan 21 17:38:12 v kernel: Linux agpgart interface v0.103 Jan 21 17:38:12 v kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Jan 21 17:38:12 v kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 Jan 21 17:38:12 v kernel: xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x110 quirks 0x0000000000009810 Jan 21 17:38:12 v kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Jan 21 17:38:12 v kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2 Jan 21 17:38:12 v kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.1 Enhanced SuperSpeed Jan 21 17:38:12 v kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Jan 21 17:38:12 v kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 21 17:38:12 v kernel: usb usb1: Product: xHCI Host Controller Jan 21 17:38:12 v kernel: usb usb1: Manufacturer: Linux 6.6.32-volumio xhci-hcd Jan 21 17:38:12 v kernel: usb usb1: SerialNumber: 0000:00:14.0 Jan 21 17:38:12 v kernel: hub 1-0:1.0: USB hub found Jan 21 17:38:12 v kernel: hub 1-0:1.0: 12 ports detected Jan 21 17:38:12 v kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Jan 21 17:38:12 v kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 21 17:38:12 v kernel: usb usb2: Product: xHCI Host Controller Jan 21 17:38:12 v kernel: usb usb2: Manufacturer: Linux 6.6.32-volumio xhci-hcd Jan 21 17:38:12 v kernel: usb usb2: SerialNumber: 0000:00:14.0 Jan 21 17:38:12 v kernel: hub 2-0:1.0: USB hub found Jan 21 17:38:12 v kernel: hub 2-0:1.0: 6 ports detected Jan 21 17:38:12 v kernel: i8042: PNP: No PS/2 controller found. Jan 21 17:38:12 v kernel: i8042: Probing ports directly. Jan 21 17:38:12 v kernel: i8042: No controller found Jan 21 17:38:12 v kernel: mousedev: PS/2 mouse device common for all mice Jan 21 17:38:12 v kernel: rtc_cmos rtc_cmos: RTC can wake from S4 Jan 21 17:38:12 v kernel: rtc_cmos rtc_cmos: registered as rtc0 Jan 21 17:38:12 v kernel: rtc_cmos rtc_cmos: setting system clock to 2025-01-21T10:38:06 UTC (1737455886) Jan 21 17:38:12 v kernel: rtc_cmos rtc_cmos: alarms up to one month, y3k, 114 bytes nvram Jan 21 17:38:12 v kernel: i2c_dev: i2c /dev entries driver Jan 21 17:38:12 v kernel: platform eisa.0: Probing EISA bus 0 Jan 21 17:38:12 v kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Jan 21 17:38:12 v kernel: intel_pstate: Intel P-state driver initializing Jan 21 17:38:12 v kernel: intel_pstate: HWP enabled Jan 21 17:38:12 v kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 21 17:38:12 v kernel: efifb: probing for efifb Jan 21 17:38:12 v kernel: efifb: framebuffer at 0x80000000, using 1920k, total 1920k Jan 21 17:38:12 v kernel: efifb: mode is 800x600x32, linelength=3200, pages=1 Jan 21 17:38:12 v kernel: efifb: scrolling: redraw Jan 21 17:38:12 v kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jan 21 17:38:12 v kernel: Console: switching to colour frame buffer device 100x37 Jan 21 17:38:12 v kernel: fb0: EFI VGA frame buffer device Jan 21 17:38:12 v kernel: intel_pmc_core INT33A1:00: initialized Jan 21 17:38:12 v kernel: NET: Registered PF_INET6 protocol family Jan 21 17:38:12 v kernel: Segment Routing with IPv6 Jan 21 17:38:12 v kernel: In-situ OAM (IOAM) with IPv6 Jan 21 17:38:12 v kernel: mip6: Mobile IPv6 Jan 21 17:38:12 v kernel: NET: Registered PF_PACKET protocol family Jan 21 17:38:12 v kernel: mpls_gso: MPLS GSO support Jan 21 17:38:12 v kernel: microcode: Microcode Update Driver: v2.2. Jan 21 17:38:12 v kernel: IPI shorthand broadcast: enabled Jan 21 17:38:12 v kernel: sched_clock: Marking stable (273049356, 7833267)->(309389493, -28506870) Jan 21 17:38:12 v kernel: Loading compiled-in X.509 certificates Jan 21 17:38:12 v kernel: clk: Disabling unused clocks Jan 21 17:38:12 v kernel: usb 1-10: new full-speed USB device number 2 using xhci_hcd Jan 21 17:38:12 v kernel: usb 1-10: New USB device found, idVendor=8087, idProduct=0aaa, bcdDevice= 0.02 Jan 21 17:38:12 v kernel: usb 1-10: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 21 17:38:12 v kernel: Freeing initrd memory: 68112K Jan 21 17:38:12 v kernel: Freeing unused kernel image (initmem) memory: 2988K Jan 21 17:38:12 v kernel: Write protecting the kernel read-only data: 20480k Jan 21 17:38:12 v kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K Jan 21 17:38:12 v kernel: Run /init as init process Jan 21 17:38:12 v kernel: with arguments: Jan 21 17:38:12 v kernel: /init Jan 21 17:38:12 v kernel: splash Jan 21 17:38:12 v kernel: with environment: Jan 21 17:38:12 v kernel: HOME=/ Jan 21 17:38:12 v kernel: TERM=linux Jan 21 17:38:12 v kernel: BOOT_IMAGE=/vmlinuz Jan 21 17:38:12 v kernel: biosdevname=0 Jan 21 17:38:12 v kernel: imgpart=UUID=5d6a5e08-117c-42c4-b43b-04eb7d4d7a2f Jan 21 17:38:12 v kernel: bootpart=UUID=DC0B-9C7A Jan 21 17:38:12 v kernel: datapart=UUID=956f79d0-cc34-4920-bdf9-3223d9863e1a Jan 21 17:38:12 v kernel: hwdevice=x86 Jan 21 17:38:12 v kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Jan 21 17:38:12 v kernel: imgfile=/volumio_current.sqsh Jan 21 17:38:12 v kernel: use_kmsg=no Jan 21 17:38:12 v kernel: i801_smbus 0000:00:1f.4: SPD Write Disable is set Jan 21 17:38:12 v kernel: i801_smbus 0000:00:1f.4: SMBus using PCI interrupt Jan 21 17:38:12 v kernel: i2c i2c-0: 2/2 memory slots populated (from DMI) Jan 21 17:38:12 v kernel: i2c i2c-0: Successfully instantiated SPD at 0x50 Jan 21 17:38:12 v kernel: ACPI: bus type drm_connector registered Jan 21 17:38:12 v kernel: e1000e: Intel(R) PRO/1000 Network Driver Jan 21 17:38:12 v kernel: e1000e: Copyright(c) 1999 - 2015 Intel Corporation. Jan 21 17:38:12 v kernel: e1000e 0000:00:1f.6: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode Jan 21 17:38:12 v kernel: ahci 0000:00:17.0: version 3.0 Jan 21 17:38:12 v kernel: ahci 0000:00:17.0: AHCI 0001.0301 32 slots 1 ports 6 Gbps 0x4 impl SATA mode Jan 21 17:38:12 v kernel: ahci 0000:00:17.0: flags: 64bit ncq sntf pm clo only pio slum part deso sadm sds apst Jan 21 17:38:12 v kernel: nvme nvme0: pci function 0000:6d:00.0 Jan 21 17:38:12 v kernel: scsi host0: ahci Jan 21 17:38:12 v kernel: scsi host1: ahci Jan 21 17:38:12 v kernel: scsi host2: ahci Jan 21 17:38:12 v kernel: ata1: DUMMY Jan 21 17:38:12 v kernel: ata2: DUMMY Jan 21 17:38:12 v kernel: ata3: SATA max UDMA/133 abar m2048@0xc0b26000 port 0xc0b26200 irq 127 Jan 21 17:38:12 v kernel: nvme nvme0: Shutdown timeout set to 8 seconds Jan 21 17:38:12 v kernel: nvme nvme0: allocated 64 MiB host memory buffer. Jan 21 17:38:12 v kernel: nvme nvme0: 4/0/0 default/read/poll queues Jan 21 17:38:12 v kernel: nvme0n1: p1 p2 p3 Jan 21 17:38:12 v kernel: tsc: Refined TSC clocksource calibration: 2999.999 MHz Jan 21 17:38:12 v kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2b3e44b2357, max_idle_ns: 440795324996 ns Jan 21 17:38:12 v kernel: clocksource: Switched to clocksource tsc Jan 21 17:38:12 v kernel: e1000e 0000:00:1f.6 eth0: (PCI Express:2.5GT/s:Width x1) 94:c6:91:ad:f6:72 Jan 21 17:38:12 v kernel: e1000e 0000:00:1f.6 eth0: Intel(R) PRO/1000 Network Connection Jan 21 17:38:12 v kernel: e1000e 0000:00:1f.6 eth0: MAC: 13, PHY: 12, PBA No: FFFFFF-0FF Jan 21 17:38:12 v kernel: ata3: SATA link down (SStatus 4 SControl 300) Jan 21 17:38:12 v kernel: i915 0000:00:02.0: [drm] Found 128MB of eDRAM Jan 21 17:38:12 v kernel: Console: switching to colour dummy device 80x25 Jan 21 17:38:12 v kernel: i915 0000:00:02.0: vgaarb: deactivate vga console Jan 21 17:38:12 v kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem Jan 21 17:38:12 v kernel: i915 0000:00:02.0: [drm] Finished loading DMC firmware i915/kbl_dmc_ver1_04.bin (v1.4) Jan 21 17:38:12 v kernel: i915 0000:00:02.0: [drm] [ENCODER:94:DDI A/PHY A] failed to retrieve link info, disabling eDP Jan 21 17:38:12 v kernel: [drm] Initialized i915 1.6.0 20201103 for 0000:00:02.0 on minor 0 Jan 21 17:38:12 v kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Jan 21 17:38:12 v kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input3 Jan 21 17:38:12 v kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Jan 21 17:38:12 v kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 21 17:38:12 v kernel: sdhci: Secure Digital Host Controller Interface driver Jan 21 17:38:12 v kernel: sdhci: Copyright(c) Pierre Ossman Jan 21 17:38:12 v kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Jan 21 17:38:12 v kernel: hid: raw HID events driver (C) Jiri Kosina Jan 21 17:38:12 v kernel: usbcore: registered new interface driver usbhid Jan 21 17:38:12 v kernel: usbhid: USB HID core driver Jan 21 17:38:12 v kernel: fuse: init (API version 7.39) Jan 21 17:38:12 v kernel: usbcore: registered new interface driver usb-storage Jan 21 17:38:12 v kernel: usbcore: registered new interface driver uas Jan 21 17:38:12 v kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Jan 21 17:38:12 v kernel: [drm] radeon kernel modesetting enabled. Jan 21 17:38:12 v kernel: genirq: Flags mismatch irq 14. 00000008 (platform[pata_legacy.0]) vs. 00010088 (INT34BB:00) Jan 21 17:38:12 v kernel: scsi host3: pata_legacy Jan 21 17:38:12 v kernel: ata4: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 Jan 21 17:38:12 v kernel: FAT-fs (nvme0n1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jan 21 17:38:12 v kernel: EXT4-fs (nvme0n1p2): recovery complete Jan 21 17:38:12 v kernel: EXT4-fs (nvme0n1p2): mounted filesystem 5d6a5e08-117c-42c4-b43b-04eb7d4d7a2f r/w with ordered data mode. Quota mode: none. Jan 21 17:38:12 v kernel: loop: module loaded Jan 21 17:38:12 v kernel: block device autoloading is deprecated and will be removed. Jan 21 17:38:12 v kernel: loop0: detected capacity change from 0 to 1725144 Jan 21 17:38:12 v kernel: EXT4-fs (nvme0n1p3): recovery complete Jan 21 17:38:12 v kernel: EXT4-fs (nvme0n1p3): mounted filesystem 956f79d0-cc34-4920-bdf9-3223d9863e1a r/w with ordered data mode. Quota mode: none. Jan 21 17:38:12 v systemd[1]: Inserted module 'autofs4' Jan 21 17:38:12 v 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) Jan 21 17:38:12 v systemd[1]: Detected architecture x86-64. Jan 21 17:38:12 v systemd[1]: Set hostname to . Jan 21 17:38:12 v 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. Jan 21 17:38:12 v systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Jan 21 17:38:12 v 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. Jan 21 17:38:12 v systemd[1]: Listening on Journal Socket. Jan 21 17:38:12 v systemd[1]: Started dynamicswap service. Jan 21 17:38:12 v systemd[1]: Starting Remount Root and Kernel File Systems... Jan 21 17:38:12 v systemd[1]: Created slice system-getty.slice. Jan 21 17:38:12 v systemd-journald[471]: Journal started Jan 21 17:38:12 v systemd-journald[471]: Runtime journal (/run/log/journal/724bcbf01eebf9d6210f389b672e577a) is 3.7M, max 30.0M, 26.2M free. Jan 21 17:38:12 v systemd[1]: Started Load/Save Random Seed. Jan 21 17:38:12 v systemd[1]: Mounted Kernel Configuration File System. Jan 21 17:38:12 v fake-hwclock[477]: Current system time: 2025-01-21 10:38:12 Jan 21 17:38:12 v fake-hwclock[477]: fake-hwclock saved clock information is in the past: 2025-01-21 10:37:57 Jan 21 17:38:12 v fake-hwclock[477]: To set system time to this saved clock anyway, use "force" Jan 21 17:38:12 v systemd[1]: Started Restore / save the current clock. Jan 21 17:38:12 v systemd[1]: Started Apply Kernel Variables. Jan 21 17:38:12 v systemd[1]: Started Create System Users. Jan 21 17:38:12 v kernel: RPC: Registered named UNIX socket transport module. Jan 21 17:38:12 v kernel: RPC: Registered udp transport module. Jan 21 17:38:12 v kernel: RPC: Registered tcp transport module. Jan 21 17:38:12 v kernel: RPC: Registered tcp-with-tls transport module. Jan 21 17:38:12 v kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 21 17:38:12 v systemd[1]: Starting Create Static Device Nodes in /dev... Jan 21 17:38:12 v systemd[1]: dynamicswap.service: Succeeded. Jan 21 17:38:12 v systemd[1]: Mounted RPC Pipe File System. Jan 21 17:38:12 v systemd[1]: Started Create Static Device Nodes in /dev. Jan 21 17:38:12 v systemd[1]: Reached target Local File Systems (Pre). Jan 21 17:38:12 v systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 21 17:38:12 v systemd[1]: Mounting /var/log... Jan 21 17:38:12 v systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 21 17:38:12 v systemd[1]: Mounting /tmp... Jan 21 17:38:12 v systemd[1]: Mounting /var/spool/cups... Jan 21 17:38:12 v systemd[1]: Starting udev Kernel Device Manager... Jan 21 17:38:12 v systemd[1]: Mounted /var/log. Jan 21 17:38:12 v systemd[1]: Mounted /tmp. Jan 21 17:38:12 v systemd[1]: Mounted /var/spool/cups. Jan 21 17:38:12 v systemd[1]: Mounting /var/spool/cups/tmp... Jan 21 17:38:12 v systemd[1]: Starting Flush Journal to Persistent Storage... Jan 21 17:38:12 v systemd[1]: Started udev Coldplug all Devices. Jan 21 17:38:12 v systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 21 17:38:12 v systemd[1]: Mounted /var/spool/cups/tmp. Jan 21 17:38:12 v systemd-udevd[502]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 21 17:38:12 v systemd-journald[471]: Runtime journal (/run/log/journal/724bcbf01eebf9d6210f389b672e577a) is 3.7M, max 30.0M, 26.2M free. Jan 21 17:38:12 v systemd[1]: Started Flush Journal to Persistent Storage. Jan 21 17:38:12 v systemd[1]: Started udev Kernel Device Manager. Jan 21 17:38:12 v systemd[1]: Starting Show Plymouth Boot Screen... Jan 21 17:38:12 v systemd[1]: plymouth-start.service: Succeeded. Jan 21 17:38:12 v systemd[1]: Started Show Plymouth Boot Screen. Jan 21 17:38:12 v systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 21 17:38:12 v systemd[1]: Reached target Local Encrypted Volumes. Jan 21 17:38:12 v systemd[1]: Reached target Paths. Jan 21 17:38:12 v systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 21 17:38:12 v systemd-udevd[542]: Using default interface naming scheme 'v240'. Jan 21 17:38:12 v systemd-udevd[542]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 21 17:38:12 v kernel: mei_me 0000:00:16.0: enabling device (0000 -> 0002) Jan 21 17:38:12 v systemd-udevd[537]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 21 17:38:12 v kernel: pstore: Using crash dump compression: deflate Jan 21 17:38:12 v kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 21 17:38:12 v kernel: cryptd: max_cpu_qlen set to 1000 Jan 21 17:38:12 v kernel: pstore: Registered efi_pstore as persistent store backend Jan 21 17:38:12 v kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 21 17:38:12 v kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 21 17:38:12 v kernel: AVX2 version of gcm_enc/dec engaged. Jan 21 17:38:12 v kernel: AES CTR mode by8 optimization enabled Jan 21 17:38:12 v kernel: Intel(R) Wireless WiFi driver for Linux Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: enabling device (0000 -> 0002) Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: Detected crf-id 0x2816, cnv-id 0x1000100 wfpm id 0x80000000 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: PCI dev 9df0/0034, rev=0x312, rfid=0x105110 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 0 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 1 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 2 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 3 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 4 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 6 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 8 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 9 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 10 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 11 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 15 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 16 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 18 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 19 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 20 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 21 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: WRT: Overriding region id 28 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: loaded firmware version 46.ff18e32a.0 9000-pu-b0-jf-b0-46.ucode op_mode iwlmvm Jan 21 17:38:12 v kernel: snd_hda_intel 0000:00:1f.3: DSP detected with PCI class/subclass/prog-if info 0x040380 Jan 21 17:38:12 v kernel: snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops __SCT__tp_func_intel_frontbuffer_flush [i915]) Jan 21 17:38:12 v systemd-udevd[517]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd-udevd[524]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd-udevd[549]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd-udevd[508]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd[1]: Found device Samsung SSD 980 500GB boot. Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: Detected Intel(R) Wireless-AC 9560 160MHz, REV=0x312 Jan 21 17:38:12 v kernel: thermal thermal_zone3: failed to read out thermal zone (-61) Jan 21 17:38:12 v kernel: snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC233: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp Jan 21 17:38:12 v kernel: snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Jan 21 17:38:12 v kernel: snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) Jan 21 17:38:12 v kernel: snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 Jan 21 17:38:12 v kernel: snd_hda_codec_realtek hdaudioC0D0: inputs: Jan 21 17:38:12 v kernel: snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 Jan 21 17:38:12 v kernel: snd_hda_codec_realtek hdaudioC0D0: Internal Mic=0x12 Jan 21 17:38:12 v kernel: iwlwifi 0000:00:14.3: base HW address: f4:d1:08:a5:b2:5c, OTP minor version: 0x4 Jan 21 17:38:12 v systemd[1]: Condition check resulted in Ethernet Connection (6) I219-V being skipped. Jan 21 17:38:12 v systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 21 17:38:12 v kernel: Bluetooth: Core ver 2.22 Jan 21 17:38:12 v kernel: NET: Registered PF_BLUETOOTH protocol family Jan 21 17:38:12 v kernel: Bluetooth: HCI device and connection manager initialized Jan 21 17:38:12 v kernel: Bluetooth: HCI socket layer initialized Jan 21 17:38:12 v kernel: Bluetooth: L2CAP socket layer initialized Jan 21 17:38:12 v kernel: Bluetooth: SCO socket layer initialized Jan 21 17:38:12 v kernel: input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input4 Jan 21 17:38:12 v kernel: input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input5 Jan 21 17:38:12 v kernel: input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input6 Jan 21 17:38:12 v kernel: input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input7 Jan 21 17:38:12 v kernel: input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input8 Jan 21 17:38:12 v kernel: usbcore: registered new interface driver btusb Jan 21 17:38:12 v systemd[1]: Starting File System Check on /dev/disk/by-uuid/DC0B-9C7A... Jan 21 17:38:12 v kernel: Bluetooth: hci0: Found device firmware: intel/ibt-17-16-1.sfi Jan 21 17:38:12 v kernel: Bluetooth: hci0: Boot Address: 0x40800 Jan 21 17:38:12 v kernel: Bluetooth: hci0: Firmware Version: 108-45.22 Jan 21 17:38:12 v kernel: Bluetooth: hci0: Firmware already loaded Jan 21 17:38:12 v kernel: Bluetooth: hci0: HCI LE Coded PHY feature bit is set, but its usage is not supported. Jan 21 17:38:12 v systemd-fsck[677]: fsck.fat 4.1 (2017-01-24) Jan 21 17:38:12 v systemd-fsck[677]: There are differences between boot sector and its backup. Jan 21 17:38:12 v systemd-fsck[677]: This is mostly harmless. Differences: (offset:original/backup) Jan 21 17:38:12 v systemd-fsck[677]: 3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74 Jan 21 17:38:12 v systemd-fsck[677]: , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22 Jan 21 17:38:12 v systemd-fsck[677]: , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb Jan 21 17:38:12 v systemd-fsck[677]: , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb Jan 21 17:38:12 v systemd-fsck[677]: , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd Jan 21 17:38:12 v systemd-fsck[677]: , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69 Jan 21 17:38:12 v systemd-fsck[677]: , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e Jan 21 17:38:12 v systemd-fsck[677]: , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62 Jan 21 17:38:12 v systemd-fsck[677]: , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c Jan 21 17:38:12 v systemd-fsck[677]: , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b Jan 21 17:38:12 v systemd-fsck[677]: , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61 Jan 21 17:38:12 v systemd-fsck[677]: , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73 Jan 21 17:38:12 v systemd-fsck[677]: , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20 Jan 21 17:38:12 v systemd-fsck[677]: , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62 Jan 21 17:38:12 v systemd-fsck[677]: , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f Jan 21 17:38:12 v systemd-fsck[677]: , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e Jan 21 17:38:12 v systemd-fsck[677]: , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65 Jan 21 17:38:12 v systemd-fsck[677]: , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79 Jan 21 17:38:12 v systemd-fsck[677]: , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74 Jan 21 17:38:12 v systemd-fsck[677]: , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20 Jan 21 17:38:12 v systemd-fsck[677]: , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20 Jan 21 17:38:12 v systemd-fsck[677]: , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a Jan 21 17:38:12 v systemd-fsck[677]: , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00 Jan 21 17:38:12 v systemd-fsck[677]: , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00 Jan 21 17:38:12 v systemd-fsck[677]: , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00 Jan 21 17:38:12 v systemd-fsck[677]: , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00 Jan 21 17:38:12 v systemd-fsck[677]: , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00 Jan 21 17:38:12 v kernel: ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs' Jan 21 17:38:12 v systemd-fsck[677]: , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00 Jan 21 17:38:12 v systemd-fsck[677]: , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00 Jan 21 17:38:12 v systemd-fsck[677]: , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00 Jan 21 17:38:12 v systemd-fsck[677]: , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00 Jan 21 17:38:12 v systemd-fsck[677]: , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00 Jan 21 17:38:12 v systemd-fsck[677]: , 282:2d/00, 283:15/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00 Jan 21 17:38:12 v systemd-fsck[677]: , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00 Jan 21 17:38:12 v systemd-fsck[677]: , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00 Jan 21 17:38:12 v systemd-fsck[677]: , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00 Jan 21 17:38:12 v systemd-fsck[677]: , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00 Jan 21 17:38:12 v systemd-fsck[677]: , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00 Jan 21 17:38:12 v systemd-fsck[677]: , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00 Jan 21 17:38:12 v systemd-fsck[677]: , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00 Jan 21 17:38:12 v systemd-fsck[677]: , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00 Jan 21 17:38:12 v systemd-fsck[677]: , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00 Jan 21 17:38:12 v systemd-fsck[677]: , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00 Jan 21 17:38:12 v systemd-fsck[677]: , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00 Jan 21 17:38:12 v systemd-fsck[677]: , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00 Jan 21 17:38:12 v systemd-fsck[677]: , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00 Jan 21 17:38:12 v systemd-fsck[677]: , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00 Jan 21 17:38:12 v systemd-fsck[677]: , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00 Jan 21 17:38:12 v systemd-fsck[677]: , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00 Jan 21 17:38:12 v systemd-fsck[677]: , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00 Jan 21 17:38:12 v systemd-fsck[677]: , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00 Jan 21 17:38:12 v systemd-fsck[677]: , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00 Jan 21 17:38:12 v systemd-fsck[677]: , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00 Jan 21 17:38:12 v systemd-fsck[677]: , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00 Jan 21 17:38:12 v systemd-fsck[677]: , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00 Jan 21 17:38:12 v systemd-fsck[677]: , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00 Jan 21 17:38:12 v systemd-udevd[517]: Using default interface naming scheme 'v240'. Jan 21 17:38:12 v systemd-udevd[517]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 21 17:38:12 v systemd-fsck[677]: , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00 Jan 21 17:38:12 v systemd-fsck[677]: , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00 Jan 21 17:38:12 v systemd-fsck[677]: , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00 Jan 21 17:38:12 v systemd-fsck[677]: , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00 Jan 21 17:38:12 v systemd-fsck[677]: , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00 Jan 21 17:38:12 v systemd-fsck[677]: , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00 Jan 21 17:38:12 v systemd-fsck[677]: , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00 Jan 21 17:38:12 v systemd-fsck[677]: , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00 Jan 21 17:38:12 v systemd-fsck[677]: , 508:18/00, 509:37/00 Jan 21 17:38:12 v systemd-fsck[677]: Not automatically fixing this. Jan 21 17:38:12 v systemd-fsck[677]: /dev/nvme0n1p1: 20 files, 159623/344788 clusters Jan 21 17:38:12 v systemd-udevd[508]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd[1]: Started File System Check on /dev/disk/by-uuid/DC0B-9C7A. Jan 21 17:38:12 v kernel: alsactl[683]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jan 21 17:38:12 v systemd-udevd[549]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd[1]: Started File System Check Daemon to report status. Jan 21 17:38:12 v systemd[1]: Mounting /boot... Jan 21 17:38:12 v systemd[1]: Starting Show Plymouth Boot Screen... Jan 21 17:38:12 v systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 21 17:38:12 v systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 21 17:38:12 v systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 21 17:38:12 v systemd-udevd[515]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd-udevd[540]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v systemd-udevd[544]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 21 17:38:12 v kernel: FAT-fs (nvme0n1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jan 21 17:38:12 v systemd[1]: Mounted /boot. Jan 21 17:38:12 v systemd[1]: Started Load/Save RF Kill Switch Status. Jan 21 17:38:12 v systemd[1]: Reached target Local File Systems. Jan 21 17:38:12 v systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 21 17:38:12 v systemd[1]: Starting Create Volatile Files and Directories... Jan 21 17:38:12 v systemd[1]: Starting Preprocess NFS configuration... Jan 21 17:38:12 v systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 21 17:38:12 v systemd[1]: Started ifup for eth0. Jan 21 17:38:12 v systemd[1]: nfs-config.service: Succeeded. Jan 21 17:38:12 v systemd[1]: Started Preprocess NFS configuration. Jan 21 17:38:12 v systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 21 17:38:12 v systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 21 17:38:12 v systemd[1]: Reached target NFS client services. Jan 21 17:38:12 v systemd[1]: plymouth-start.service: Succeeded. Jan 21 17:38:12 v systemd[1]: Started Show Plymouth Boot Screen. Jan 21 17:38:12 v systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 21 17:38:12 v systemd[1]: Received SIGRTMIN+20 from PID 366 (plymouthd). Jan 21 17:38:12 v systemd[1]: plymouth-read-write.service: Succeeded. Jan 21 17:38:12 v systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 21 17:38:12 v systemd[1]: Found device Cannon Point-LP CNVi [Wireless-AC]. Jan 21 17:38:12 v systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 21 17:38:12 v systemd[1]: Started Create Volatile Files and Directories. Jan 21 17:38:12 v kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 21 17:38:12 v systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 21 17:38:12 v systemd[1]: Starting RPC bind portmap service... Jan 21 17:38:12 v systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 21 17:38:12 v systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 21 17:38:12 v systemd[1]: Starting Raise network interfaces... Jan 21 17:38:12 v systemd[1]: Started ifup for wlan0. Jan 21 17:38:12 v systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 21 17:38:12 v systemd[1]: Reached target System Initialization. Jan 21 17:38:12 v systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 21 17:38:12 v systemd[1]: Started Daily man-db regeneration. Jan 21 17:38:12 v systemd[1]: Started Daily apt download activities. Jan 21 17:38:12 v systemd[1]: Started Daily apt upgrade and clean activities. Jan 21 17:38:12 v systemd[1]: Listening on triggerhappy.socket. Jan 21 17:38:12 v systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 21 17:38:12 v systemd[1]: Reached target Sockets. Jan 21 17:38:12 v systemd[1]: Reached target Basic System. Jan 21 17:38:12 v systemd[1]: Starting triggerhappy global hotkey daemon... Jan 21 17:38:12 v systemd[1]: Started UPnP Renderer front-end to MPD. Jan 21 17:38:12 v systemd[1]: Starting Login Service... Jan 21 17:38:12 v systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 21 17:38:12 v systemd[1]: Started Manage Sound Card State (restore and store). Jan 21 17:38:12 v systemd[1]: Starting Save/Restore Sound Card State... Jan 21 17:38:12 v systemd[1]: Started D-Bus System Message Bus. Jan 21 17:38:12 v thd[763]: Unable to parse trigger line: Jan 21 17:38:12 v thd[763]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 21 17:38:12 v thd[763]: Unable to parse trigger line: Jan 21 17:38:12 v thd[763]: Found socket passed from systemd Jan 21 17:38:12 v systemd[1]: Starting WPA supplicant... Jan 21 17:38:12 v alsactl[766]: alsactl 1.1.8 daemon started Jan 21 17:38:12 v systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 21 17:38:12 v systemd[1]: Starting DHCP Client Daemon... Jan 21 17:38:12 v systemd[1]: Started Volumio Log Rotation Service. Jan 21 17:38:12 v systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 21 17:38:12 v systemd[1]: Started Volumio Iptables Module. Jan 21 17:38:12 v systemd[1]: Started MPD Monitor Service. Jan 21 17:38:12 v systemd[1]: Started volumio-remote-updater.service. Jan 21 17:38:12 v systemd[1]: Starting Bluetooth service... Jan 21 17:38:12 v systemd[1]: Starting Volumio Time Update Utility... Jan 21 17:38:12 v systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 21 17:38:12 v systemd[1]: Reached target Timers. Jan 21 17:38:12 v systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 21 17:38:12 v systemd[1]: Starting Wireless Services... Jan 21 17:38:12 v systemd[1]: Started triggerhappy global hotkey daemon. Jan 21 17:38:12 v volumio-time-update[776]: volumio-time-update-util: Fetching time from Volumio... Jan 21 17:38:12 v systemd-logind[765]: New seat seat0. Jan 21 17:38:13 v systemd-logind[765]: Watching system buttons on /dev/input/event2 (Power Button) Jan 21 17:38:13 v mpd_monitor.sh[773]: MPD error: Connection refused Jan 21 17:38:13 v systemd-logind[765]: Watching system buttons on /dev/input/event1 (Power Button) Jan 21 17:38:13 v systemd-logind[765]: Watching system buttons on /dev/input/event0 (Sleep Button) Jan 21 17:38:13 v mpd_monitor.sh[773]: mpd: no process found Jan 21 17:38:13 v avahi-daemon[777]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Jan 21 17:38:13 v avahi-daemon[777]: Successfully dropped root privileges. Jan 21 17:38:13 v avahi-daemon[777]: avahi-daemon 0.7 starting up. Jan 21 17:38:13 v bluetoothd[775]: Bluetooth daemon 5.50 Jan 21 17:38:13 v bluetoothd[775]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 21 17:38:13 v sh[715]: eth0: waiting for carrier Jan 21 17:38:13 v dhcpcd[734]: eth0: waiting for carrier Jan 21 17:38:13 v kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 21 17:38:13 v dhcpcd[770]: no interfaces have a carrier Jan 21 17:38:13 v dhcpcd[770]: no interfaces have a carrier Jan 21 17:38:13 v dhcpcd[839]: eth0: waiting for carrier Jan 21 17:38:13 v dhcpcd[770]: forked to background, child pid 839 Jan 21 17:38:13 v dhcpcd[770]: forked to background, child pid 839 Jan 21 17:38:13 v wpa_supplicant[769]: Successfully initialized wpa_supplicant Jan 21 17:38:13 v systemd[1]: Started Login Service. Jan 21 17:38:13 v systemd[1]: Started RPC bind portmap service. Jan 21 17:38:13 v systemd[1]: Started Save/Restore Sound Card State. Jan 21 17:38:13 v systemd[1]: Started DHCP Client Daemon. Jan 21 17:38:13 v bluetoothd[775]: Starting SDP server Jan 21 17:38:13 v bluetoothd[775]: Excluding (cli) sap Jan 21 17:38:13 v avahi-daemon[777]: Successfully called chroot(). Jan 21 17:38:13 v avahi-daemon[777]: Successfully dropped remaining capabilities. Jan 21 17:38:13 v systemd[1]: Started WPA supplicant. Jan 21 17:38:13 v systemd[1]: Started Bluetooth service. Jan 21 17:38:13 v systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 21 17:38:13 v systemd[1]: Reached target Bluetooth. Jan 21 17:38:13 v avahi-daemon[777]: Loading service file /services/volumio.service. Jan 21 17:38:13 v systemd[1]: Listening on mpd.socket. Jan 21 17:38:13 v systemd[1]: Reached target Sound Card. Jan 21 17:38:13 v systemd[1]: Reached target Remote File Systems (Pre). Jan 21 17:38:13 v systemd[1]: Reached target Remote File Systems. Jan 21 17:38:13 v volumio-remote-updater[774]: Error: No active session Jan 21 17:38:13 v kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 21 17:38:13 v kernel: Bluetooth: BNEP filters: protocol multicast Jan 21 17:38:13 v kernel: Bluetooth: BNEP socket layer initialized Jan 21 17:38:13 v kernel: Bluetooth: MGMT ver 1.22 Jan 21 17:38:13 v bluetoothd[775]: Bluetooth management interface 1.22 initialized Jan 21 17:38:13 v haveged[744]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Jan 21 17:38:13 v haveged[744]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 31410/52825 Jan 21 17:38:13 v haveged[744]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00576 Jan 21 17:38:13 v haveged[744]: haveged: fills: 0, generated: 0 Jan 21 17:38:13 v dbus-daemon[768]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.3' (uid=0 pid=775 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 21 17:38:13 v systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 21 17:38:13 v systemd[1]: Reached target RPC Port Mapper. Jan 21 17:38:13 v volumio[764]: Could not open config: /tmp/upmpdcli.conf Jan 21 17:38:13 v volumio-time-update[776]: volumio-time-update-util: Date not found in response Jan 21 17:38:13 v volumio-time-update[776]: volumio-time-update-util: Retrying in 5 seconds... Jan 21 17:38:13 v systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 21 17:38:13 v systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 21 17:38:13 v systemd[1]: Starting Hostname Service... Jan 21 17:38:13 v loadcpufreq[848]: Loading cpufreq kernel modules...done (acpi-cpufreq). Jan 21 17:38:13 v systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 21 17:38:13 v systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 21 17:38:13 v dhcpcd[839]: wlan0: waiting for carrier Jan 21 17:38:13 v dhcpcd[839]: wlan0: carrier acquired Jan 21 17:38:13 v avahi-daemon[777]: Network interface enumeration completed. Jan 21 17:38:13 v volumio-remote-updater[774]: [2025-01-21 17:38:13] [info] asio async_connect error: system:111 (Connection refused) Jan 21 17:38:13 v volumio-remote-updater[774]: [2025-01-21 17:38:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 21 17:38:13 v dhcpcd[839]: DUID 00:04:6e:2f:48:49:f9:68:6d:86:1e:1d:94:c6:91:ad:f6:72 Jan 21 17:38:13 v dhcpcd[839]: wlan0: IAID 08:a5:b2:5c Jan 21 17:38:13 v dhcpcd[839]: wlan0: adding address fe80::f6d1:8ff:fea5:b25c Jan 21 17:38:13 v dhcpcd[839]: ipv6_addaddr1: Permission denied Jan 21 17:38:13 v dhcpcd[839]: wlan0: carrier lost Jan 21 17:38:13 v avahi-daemon[777]: Server startup complete. Host name is v.local. Local service cookie is 3526558975. Jan 21 17:38:13 v avahi-daemon[777]: Service "V" (/services/volumio.service) successfully established. Jan 21 17:38:13 v volumio-remote-updater[774]: [2025-01-21 17:38:13] [error] handle_connect error: Underlying Transport Error Jan 21 17:38:13 v ifup[746]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 21 17:38:13 v cpufrequtils[897]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Jan 21 17:38:13 v systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 21 17:38:13 v sh[747]: wlan0=wlan0 Jan 21 17:38:13 v dbus-daemon[768]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 21 17:38:13 v systemd[1]: Started Hostname Service. Jan 21 17:38:13 v systemd[1]: Started Raise network interfaces. Jan 21 17:38:13 v systemd[1]: Reached target Network. Jan 21 17:38:13 v systemd[1]: Starting Music Player Daemon... Jan 21 17:38:13 v systemd[1]: Starting Network Time Service... Jan 21 17:38:13 v systemd[1]: Reached target Network is Online. Jan 21 17:38:13 v systemd[1]: Starting LSB: Brings up/down network automatically... Jan 21 17:38:13 v systemd[1]: Starting /etc/rc.local Compatibility... Jan 21 17:38:13 v systemd[1]: Starting Samba NMB Daemon... Jan 21 17:38:13 v systemd[1]: Starting Permit User Sessions... Jan 21 17:38:13 v systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 21 17:38:13 v systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 17:38:13 v systemd[1]: Started /etc/rc.local Compatibility. Jan 21 17:38:13 v systemd[1]: iptables.service: Succeeded. Jan 21 17:38:13 v systemd[1]: Started Permit User Sessions. Jan 21 17:38:13 v systemd[1]: Starting Hold until boot process finishes up... Jan 21 17:38:13 v systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 21 17:38:13 v ntpd[974]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 21 17:38:13 v ntpd[974]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 21 17:38:13 v systemd[1]: Started Network Time Service. Jan 21 17:38:13 v systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 21 17:38:13 v ntpd[995]: proto: precision = 0.091 usec (-23) Jan 21 17:38:13 v systemd[1]: Started Hold until boot process finishes up. Jan 21 17:38:13 v systemd[1]: plymouth-quit.service: Succeeded. Jan 21 17:38:13 v systemd[1]: Started Terminate Plymouth Boot Screen. Jan 21 17:38:13 v systemd[1]: Received SIGRTMIN+21 from PID 366 (n/a). Jan 21 17:38:13 v ifplugd(eth0)[1005]: ifplugd 0.28 initializing. Jan 21 17:38:13 v ifplugd(eth0)[1005]: Using interface eth0/94:C6:91:AD:F6:72 with driver (version: 6.6.32-volumio) Jan 21 17:38:13 v ifplugd(eth0)[1005]: Using detection mode: SIOCETHTOOL Jan 21 17:38:13 v ifplugd(eth0)[1005]: Initialization complete, link beat not detected. Jan 21 17:38:13 v systemd[1]: Started Getty on tty1. Jan 21 17:38:13 v systemd[1]: Reached target Login Prompts. Jan 21 17:38:13 v ntpd[995]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 21 17:38:13 v ntpd[995]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 21 17:38:13 v ntpd[995]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 756 days ago Jan 21 17:38:13 v wireless.js[779]: Cleaning previous... Jan 21 17:38:13 v ntpd[995]: Listen and drop on 0 v6wildcard [::]:123 Jan 21 17:38:13 v ntpd[995]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 21 17:38:13 v ntpd[995]: Listen normally on 2 lo 127.0.0.1:123 Jan 21 17:38:13 v ntpd[995]: Listening on routing socket on fd #19 for interface updates Jan 21 17:38:13 v ntpd[995]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 17:38:13 v ntpd[995]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 17:38:13 v sudo[972]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 17:38:13 v sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:13 v sudo[972]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 21 17:38:13 v sudo[972]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:13 v sudo[1016]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 21 17:38:13 v sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:13 v sudo[1016]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:13 v systemd[1]: Started LSB: Brings up/down network automatically. Jan 21 17:38:13 v ifplugd[975]: Network Interface Plugging Daemon...start eth0...done. Jan 21 17:38:13 v sudo[1018]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 21 17:38:13 v sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:13 v nmbd[988]: [2025/01/21 17:38:13.655505, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 21 17:38:13 v nmbd[988]: started asyncdns process 1020 Jan 21 17:38:13 v nmbd[988]: [2025/01/21 17:38:13.664887, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 21 17:38:13 v nmbd[988]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 21 17:38:13 v nmbd[988]: [2025/01/21 17:38:13.664935, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 21 17:38:13 v nmbd[988]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 21 17:38:13 v sudo[1018]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:13 v wireless.js[779]: Stopped aP Jan 21 17:38:13 v wireless.js[779]: Wireless Networking DISABLED, not starting wireless flow Jan 21 17:38:13 v systemd[1]: wireless.service: Succeeded. Jan 21 17:38:13 v systemd[1]: Started Wireless Services. Jan 21 17:38:13 v systemd[1]: Started Volumio Backend Module. Jan 21 17:38:13 v systemd[1]: Started Intel SST and HDA soundcard init service. Jan 21 17:38:13 v systemd[1]: Started Volumio Cpu Tweaker. Jan 21 17:38:13 v volumio-cpu-tweak[1029]: Setting RT Priority for mpd Jan 21 17:38:13 v volumio-cpu-tweak[1029]: Setting MPD Affinity Jan 21 17:38:13 v volumio-cpu-tweak[1029]: pid 1014's current affinity mask: f Jan 21 17:38:13 v volumio-cpu-tweak[1029]: pid 1014's new affinity mask: 3 Jan 21 17:38:13 v volumio-cpu-tweak[1029]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 21 17:38:13 v systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 21 17:38:14 v systemd[1]: soundcard-init.service: Succeeded. Jan 21 17:38:14 v mpd[1014]: Jan 21 17:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 17:38:14 v systemd[1]: Started Music Player Daemon. Jan 21 17:38:14 v mpd_monitor.sh[773]: MPD restarted due to no mpc output. Jan 21 17:38:14 v volumio[1027]: info: ------------------------------------------- Jan 21 17:38:14 v volumio[1027]: info: ----- Volumio3 ---- Jan 21 17:38:14 v volumio[1027]: info: ------------------------------------------- Jan 21 17:38:14 v volumio[1027]: info: ----- System startup ---- Jan 21 17:38:14 v volumio[1027]: info: ------------------------------------------- Jan 21 17:38:14 v ntpd[995]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 17:38:14 v volumio[1027]: info: MYVOLUMIO Environment detected Jan 21 17:38:14 v volumio[1027]: info: Plugin folders cleanup Jan 21 17:38:14 v volumio[1027]: info: Scanning into folder /volumio/app/plugins/ Jan 21 17:38:14 v volumio[1027]: info: Scanning category audio_interface Jan 21 17:38:14 v volumio[1027]: info: Scanning category miscellanea Jan 21 17:38:14 v volumio[1027]: info: Scanning category music_service Jan 21 17:38:14 v volumio[1027]: info: Scanning category plugins.json Jan 21 17:38:14 v volumio[1027]: info: Scanning category system_controller Jan 21 17:38:14 v volumio[1027]: info: Scanning category user_interface Jan 21 17:38:14 v volumio[1027]: info: Scanning into folder /data/plugins/ Jan 21 17:38:14 v volumio[1027]: info: Scanning category music_service Jan 21 17:38:14 v volumio[1027]: info: Scanning category system_hardware Jan 21 17:38:14 v volumio[1027]: info: Plugin folders cleanup completed Jan 21 17:38:14 v volumio[1027]: info: ------------------------------------------- Jan 21 17:38:14 v volumio[1027]: info: ----- Core plugins startup ---- Jan 21 17:38:14 v volumio[1027]: info: ------------------------------------------- Jan 21 17:38:14 v volumio[1027]: info: Loading plugins from folder /volumio/app/plugins/ Jan 21 17:38:14 v volumio[1027]: info: Adding plugin upnp to MyMusic Plugins Jan 21 17:38:14 v volumio[1027]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 21 17:38:14 v volumio[1027]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 21 17:38:14 v volumio[1027]: info: Loading plugins from folder /data/plugins/ Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "system"... Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "appearance"... Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "network"... Jan 21 17:38:14 v volumio[1027]: info: Refreshing Cached IP Addresses Jan 21 17:38:14 v sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:38:14 v sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:14 v sudo[1150]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:14 v sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "services"... Jan 21 17:38:14 v sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "alsa_controller"... Jan 21 17:38:14 v sudo[1152]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:14 v volumio[1027]: warn: Unable to locate the audio output device Combo384 Amanero. Please configure a valid output device. Jan 21 17:38:14 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "wizard"... Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "networkfs"... Jan 21 17:38:14 v volumio[1027]: info: Starting Udev Watcher for removable devices Jan 21 17:38:14 v sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mecubap,password=Bocubap1978,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //MediaCenter/MediaCenter/music/ /mnt/NAS/Nhạc_Lossless Jan 21 17:38:14 v volumio[1027]: info: Ignoring mount for partition: boot Jan 21 17:38:14 v volumio[1027]: info: Ignoring mount for partition: volumio Jan 21 17:38:14 v volumio[1027]: info: Ignoring mount for partition: volumio_data Jan 21 17:38:14 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 17:38:14 v sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "volumio_command_line_client"... Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "upnp"... Jan 21 17:38:14 v volumio[1027]: info: [1737455894892] Starting Upmpd Daemon Jan 21 17:38:14 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 17:38:14 v sudo[1173]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "my_music"... Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "mpd"... Jan 21 17:38:14 v volumio[1027]: info: Loading plugin "upnp_browser"... Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "alarm-clock"... Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "airplay_emulation"... Jan 21 17:38:15 v volumio[1027]: info: Starting Shairport Sync Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "last_100"... Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "webradio"... Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "i2s_dacs"... Jan 21 17:38:15 v volumio[1027]: info: I2S DAC not set, start Auto-detection Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "volumiodiscovery"... Jan 21 17:38:15 v volumio[1027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 17:38:15 v volumio[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 17:38:15 v volumio[1027]: *** WARNING *** For more information see Jan 21 17:38:15 v volumio[1027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 17:38:15 v volumio[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 17:38:15 v volumio[1027]: *** WARNING *** For more information see Jan 21 17:38:15 v node[1027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 17:38:15 v node[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 17:38:15 v node[1027]: *** WARNING *** For more information see Jan 21 17:38:15 v node[1027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 17:38:15 v node[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 17:38:15 v node[1027]: *** WARNING *** For more information see Jan 21 17:38:15 v volumio[1027]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 21 17:38:15 v volumio[1027]: info: Discovery: Started advertising with name: V Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "spop"... Jan 21 17:38:15 v kernel: e1000e 0000:00:1f.6 eth0: NIC Link is Up 100 Mbps Half Duplex, Flow Control: Rx/Tx Jan 21 17:38:15 v dhcpcd[839]: eth0: carrier acquired Jan 21 17:38:15 v sh[715]: eth0: carrier acquired Jan 21 17:38:15 v dhcpcd[734]: eth0: carrier acquired Jan 21 17:38:15 v dhcpcd[839]: eth0: IAID 91:ad:f6:72 Jan 21 17:38:15 v dhcpcd[839]: eth0: adding address fe80::96c6:91ff:fead:f672 Jan 21 17:38:15 v dhcpcd[839]: ipv6_addaddr1: Permission denied Jan 21 17:38:15 v sh[715]: DUID 00:04:6e:2f:48:49:f9:68:6d:86:1e:1d:94:c6:91:ad:f6:72 Jan 21 17:38:15 v sh[715]: eth0: IAID 91:ad:f6:72 Jan 21 17:38:15 v sh[715]: eth0: adding address fe80::96c6:91ff:fead:f672 Jan 21 17:38:15 v sh[715]: ipv6_addaddr1: Permission denied Jan 21 17:38:15 v dhcpcd[734]: DUID 00:04:6e:2f:48:49:f9:68:6d:86:1e:1d:94:c6:91:ad:f6:72 Jan 21 17:38:15 v dhcpcd[734]: eth0: IAID 91:ad:f6:72 Jan 21 17:38:15 v dhcpcd[734]: eth0: adding address fe80::96c6:91ff:fead:f672 Jan 21 17:38:15 v dhcpcd[734]: ipv6_addaddr1: Permission denied Jan 21 17:38:15 v volumio[1027]: info: Plugin youtube2 is not enabled Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "outputs"... Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "albumart"... Jan 21 17:38:15 v volumio[1027]: info: Plugin example_plugin is not enabled Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "inputs"... Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "updater_comm"... Jan 21 17:38:15 v volumio[1027]: Forking 3 albumart workers Jan 21 17:38:15 v volumio[1027]: info: Plugin mpdemulation is not enabled Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "rest_api"... Jan 21 17:38:15 v volumio[1027]: info: Loading plugin "websocket"... Jan 21 17:38:15 v volumio[1027]: info: Starting Socket.io Server version 2.3.0 Jan 21 17:38:15 v volumio[1027]: info: Plugin RoonBridge is not enabled Jan 21 17:38:15 v volumio[1027]: info: Loading i18n strings for locale en Jan 21 17:38:15 v volumio[1027]: Updating browse sources language Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::initPlayerControls Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:15 v volumio[1027]: Express server listening on port 3000 Jan 21 17:38:15 v volumio[1027]: [Metrics] WebUI: 1s 325.70ms Jan 21 17:38:15 v volumio[1027]: info: Setting Device type: x86 Jan 21 17:38:15 v volumio[1027]: info: CoreStateMachine::resetVolumioState Jan 21 17:38:15 v volumio[1027]: info: CoreStateMachine::getcurrentVolume Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 17:38:15 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:15 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:15 v volumio[1027]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 21 17:38:15 v volumio[1027]: info: Completed loading Core Plugins Jan 21 17:38:15 v volumio[1027]: info: Preparing to generate the ALSA configuration file Jan 21 17:38:15 v ntpd[995]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 17:38:15 v volumio[1027]: info: Volumio Network Manager: Network status updated: 0 Jan 21 17:38:15 v volumio[1027]: info: Cannot mount NAS Nhạc_Lossless at system boot, trial number 1 ,retrying in 5 seconds Jan 21 17:38:15 v volumio[1027]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 21 17:38:15 v volumio[1027]: Starting albumart workers Jan 21 17:38:15 v volumio[1027]: Starting albumart workers Jan 21 17:38:15 v volumio[1027]: Starting albumart workers Jan 21 17:38:15 v volumio[1027]: info: Reloading queue from file Jan 21 17:38:15 v volumio[1027]: info: Asound.conf file unchanged, so no further update is needed Jan 21 17:38:15 v volumio[1027]: info: Output device has changed, restarting MPD Jan 21 17:38:15 v volumio[1027]: info: Output device has changed, restarting Shairport Sync Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:15 v sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 17:38:15 v sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v sudo[1214]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 17:38:15 v sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 17:38:15 v volumio[1027]: info: ___________ START PLUGINS ___________ Jan 21 17:38:15 v volumio[1027]: info: ControllerMpd::onStart: Initializing MPD Jan 21 17:38:15 v volumio[1027]: info: Creating MPD Configuration file Jan 21 17:38:15 v systemd[1]: Stopping Music Player Daemon... Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 17:38:15 v volumio[1027]: info: [1737455895601] CoreMusicLibrary::Adding element Media Servers Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:38:15 v sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:15 v sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v ifplugd(eth0)[1005]: Link beat detected. Jan 21 17:38:15 v sudo[1223]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v systemd[1]: mpd.service: Succeeded. Jan 21 17:38:15 v systemd[1]: Stopped Music Player Daemon. Jan 21 17:38:15 v sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 17:38:15 v dhcpcd[839]: eth0: soliciting an IPv6 router Jan 21 17:38:15 v systemd[1]: Starting Music Player Daemon... Jan 21 17:38:15 v volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:15 v sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 17:38:15 v systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 21 17:38:15 v systemd[1]: mpd.service: Succeeded. Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 17:38:15 v systemd[1]: Stopped Music Player Daemon. Jan 21 17:38:15 v volumio[1027]: info: [1737455895674] CoreMusicLibrary::Adding element Last_100 Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 17:38:15 v volumio[1027]: info: [1737455895675] CoreMusicLibrary::Adding element Webradio Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:38:15 v systemd[1]: Starting Music Player Daemon... Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 17:38:15 v volumio[1027]: info: Initializing BBC Radios Jan 21 17:38:15 v sh[715]: eth0: soliciting an IPv6 router Jan 21 17:38:15 v dhcpcd[734]: eth0: soliciting an IPv6 router Jan 21 17:38:15 v sudo[1247]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 17:38:15 v sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v sudo[1247]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: Creating Spotify config file Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v ifplugd(eth0)[1005]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 21 17:38:15 v ifplugd(eth0)[1005]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 21 17:38:15 v volumio[1027]: info: Volumio Calling Home Jan 21 17:38:15 v volumio[1027]: info: CoreStateMachine::setRepeat null single undefined Jan 21 17:38:15 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:15 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:15 v volumio[1027]: info: CoreStateMachine::setRandom null Jan 21 17:38:15 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:15 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:15 v volumio[1027]: Unhandled rejection Error: No sockets available, cannot start. Jan 21 17:38:15 v volumio[1027]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 21 17:38:15 v volumio[1027]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 21 17:38:15 v volumio[1027]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 21 17:38:15 v volumio[1027]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 21 17:38:15 v volumio[1027]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 21 17:38:15 v volumio[1027]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 21 17:38:15 v volumio[1027]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 21 17:38:15 v volumio[1027]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 21 17:38:15 v volumio[1027]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 21 17:38:15 v volumio[1027]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 21 17:38:15 v volumio[1027]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 21 17:38:15 v volumio[1027]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 21 17:38:15 v volumio[1027]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 21 17:38:15 v volumio[1027]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 21 17:38:15 v volumio[1027]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 21 17:38:15 v volumio[1027]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 21 17:38:15 v volumio[1027]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jan 21 17:38:15 v volumio[1027]: info: MPD Permissions set Jan 21 17:38:15 v volumio[1027]: info: Spotify config file written Jan 21 17:38:15 v sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 21 17:38:15 v sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:15 v systemd[1]: Started go-librespot Daemon. Jan 21 17:38:15 v volumio[1027]: info: No need to fix Spotify hosts Jan 21 17:38:15 v go-librespot[1283]: Librespot-go daemon starting... Jan 21 17:38:15 v sudo[1281]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v volumio[1027]: info: Starting Shairport Sync Jan 21 17:38:15 v volumio[1027]: info: Starting Shairport Sync Jan 21 17:38:15 v go-librespot[1283]: time="2025-01-21T17:38:15+07:00" level=info msg="generated new device id: 6cb3541ea6e950f4a46286e1a97d9ff4983b9612" Jan 21 17:38:15 v go-librespot[1283]: time="2025-01-21T17:38:15+07:00" level=debug msg="stored credentials found for 31gamgu4bknwmyxx5tbkpyamfzau" Jan 21 17:38:15 v go-librespot[1283]: time="2025-01-21T17:38:15+07: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" Jan 21 17:38:15 v volumio[1027]: info: Starting Shairport Sync Jan 21 17:38:15 v systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 21 17:38:15 v sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 17:38:15 v systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 21 17:38:15 v sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 17:38:15 v sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 17:38:15 v systemd[1]: shairport-sync.service: Succeeded. Jan 21 17:38:15 v systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 17:38:15 v sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 17:38:15 v sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:15 v systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 17:38:15 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:15 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:15 v sudo[1303]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 17:38:15 v systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 21 17:38:15 v systemd[1]: shairport-sync.service: Succeeded. Jan 21 17:38:15 v systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 17:38:15 v systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 17:38:15 v sudo[1305]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v volumio[1027]: info: Shairport-Sync Started Jan 21 17:38:15 v sudo[1309]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v volumio[1027]: Error adding Membership: Error: addMembership EINVAL Jan 21 17:38:15 v volumio[1027]: info: Shairport-Sync Started Jan 21 17:38:15 v volumio[1027]: info: Shairport-Sync Started Jan 21 17:38:15 v mpd[1271]: Jan 21 17:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 17:38:15 v systemd[1]: Started Music Player Daemon. Jan 21 17:38:15 v sudo[1225]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v sudo[1216]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:15 v volumio[1027]: info: Completed starting Core Plugins Jan 21 17:38:15 v volumio[1027]: info: ------------------------------------------- Jan 21 17:38:15 v volumio[1027]: info: ----- MyVolumio plugins startup ---- Jan 21 17:38:15 v volumio[1027]: info: ------------------------------------------- Jan 21 17:38:15 v volumio[1027]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 21 17:38:15 v volumio[1027]: error: MPD error: The expression evaluated to a falsy value: Jan 21 17:38:15 v volumio[1027]: assert.ok(self.idling) Jan 21 17:38:15 v volumio[1027]: error: The expression evaluated to a falsy value: Jan 21 17:38:15 v volumio[1027]: assert.ok(self.idling) Jan 21 17:38:15 v volumio[1027]: error: updateQueue error: null Jan 21 17:38:15 v volumio[1027]: info: MPD running with PID1271 Jan 21 17:38:15 v volumio[1027]: ,establishing connection Jan 21 17:38:15 v volumio[1027]: error: updateQueue error: null Jan 21 17:38:16 v sh[715]: eth0: soliciting a DHCP lease Jan 21 17:38:16 v dhcpcd[734]: eth0: soliciting a DHCP lease Jan 21 17:38:16 v sh[715]: eth0: offered 10.10.10.26 from 10.10.10.1 Jan 21 17:38:16 v dhcpcd[734]: eth0: offered 10.10.10.26 from 10.10.10.1 Jan 21 17:38:16 v sh[715]: eth0: probing address 10.10.10.26/24 Jan 21 17:38:16 v dhcpcd[734]: eth0: probing address 10.10.10.26/24 Jan 21 17:38:16 v dhcpcd[839]: eth0: soliciting a DHCP lease Jan 21 17:38:16 v dhcpcd[839]: eth0: offered 10.10.10.26 from 10.10.10.1 Jan 21 17:38:16 v dhcpcd[839]: eth0: probing address 10.10.10.26/24 Jan 21 17:38:16 v ntpd[995]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 17:38:17 v ntpd[995]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 17:38:17 v systemd[1]: systemd-rfkill.service: Succeeded. Jan 21 17:38:18 v volumio-time-update[776]: volumio-time-update-util: Fetching time from Volumio... Jan 21 17:38:18 v volumio-time-update[776]: volumio-time-update-util: Date not found in response Jan 21 17:38:18 v volumio-time-update[776]: volumio-time-update-util: Retrying in 5 seconds... Jan 21 17:38:18 v volumio-remote-updater[774]: [2025-01-21 17:38:18] [connect] Successful connection Jan 21 17:38:18 v volumio-remote-updater[774]: [2025-01-21 17:38:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737455898 101 Jan 21 17:38:18 v volumio[1027]: 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: 2 Jan 21 17:38:18 v volumio[1027]: info: go-librespot daemon successfully initialized Jan 21 17:38:19 v systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 21 17:38:19 v systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 21 17:38:19 v systemd[1]: Stopped go-librespot Daemon. Jan 21 17:38:19 v systemd[1]: Started go-librespot Daemon. Jan 21 17:38:19 v go-librespot[1324]: Librespot-go daemon starting... Jan 21 17:38:19 v go-librespot[1324]: time="2025-01-21T17:38:19+07:00" level=info msg="generated new device id: d32311ad4f41588e7548a449ae9d959ada8a23b5" Jan 21 17:38:19 v go-librespot[1324]: time="2025-01-21T17:38:19+07:00" level=debug msg="stored credentials found for 31gamgu4bknwmyxx5tbkpyamfzau" Jan 21 17:38:19 v go-librespot[1324]: time="2025-01-21T17:38:19+07: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" Jan 21 17:38:19 v systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 21 17:38:19 v systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 21 17:38:20 v sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mecubap,password=Bocubap1978,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //MediaCenter/MediaCenter/music/ /mnt/NAS/Nhạc_Lossless Jan 21 17:38:20 v sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:20 v sudo[1332]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:20 v volumio[1027]: info: Cannot mount NAS Nhạc_Lossless at system boot, trial number 2 ,retrying in 5 seconds Jan 21 17:38:20 v dhcpcd[839]: eth0: leased 10.10.10.26 for 86400 seconds Jan 21 17:38:20 v avahi-daemon[777]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.10.10.26. Jan 21 17:38:20 v avahi-daemon[777]: New relevant interface eth0.IPv4 for mDNS. Jan 21 17:38:20 v avahi-daemon[777]: Registering new address record for 10.10.10.26 on eth0.IPv4. Jan 21 17:38:20 v dhcpcd[839]: eth0: adding route to 10.10.10.0/24 Jan 21 17:38:20 v dhcpcd[839]: eth0: adding default route via 10.10.10.1 Jan 21 17:38:20 v volumio[1027]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 21 17:38:20 v ntpd[995]: ntpd exiting on signal 15 (Terminated) Jan 21 17:38:20 v systemd[1]: Stopping Network Time Service... Jan 21 17:38:20 v systemd[1]: ntp.service: Succeeded. Jan 21 17:38:20 v systemd[1]: Stopped Network Time Service. Jan 21 17:38:20 v systemd[1]: Starting Network Time Service... Jan 21 17:38:20 v ntpd[1369]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 21 17:38:20 v ntpd[1369]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 21 17:38:20 v systemd[1]: Started Network Time Service. Jan 21 17:38:20 v ntpd[1375]: proto: precision = 0.039 usec (-25) Jan 21 17:38:20 v ntpd[1375]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 21 17:38:20 v ntpd[1375]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 21 17:38:20 v ntpd[1375]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 756 days ago Jan 21 17:38:20 v ntpd[1375]: Listen and drop on 0 v6wildcard [::]:123 Jan 21 17:38:20 v ntpd[1375]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 21 17:38:20 v ntpd[1375]: Listen normally on 2 lo 127.0.0.1:123 Jan 21 17:38:20 v ntpd[1375]: Listen normally on 3 eth0 10.10.10.26:123 Jan 21 17:38:20 v ntpd[1375]: Listening on routing socket on fd #20 for interface updates Jan 21 17:38:20 v ntpd[1375]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 17:38:20 v ntpd[1375]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 17:38:21 v volumio[1027]: verbose: New Socket.io Connection to 10.10.10.26 from 10.10.10.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Jan 21 17:38:21 v volumio[1027]: verbose: New Socket.io Connection to 10.10.10.26 from 10.10.10.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Jan 21 17:38:21 v volumio[1027]: info: Discovery: adding 2ab46846-d509-4a64-b53c-c85b149c16a5 Jan 21 17:38:21 v volumio[1027]: info: Discovery: Found device V Jan 21 17:38:21 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:21 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:21 v volumio[1027]: info: Initializing connection to go-librespot Websocket Jan 21 17:38:21 v volumio[1027]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 21 17:38:21 v ntpd[1375]: Soliciting pool server 27.71.27.209 Jan 21 17:38:22 v sh[715]: eth0: leased 10.10.10.26 for 86400 seconds Jan 21 17:38:22 v dhcpcd[734]: eth0: leased 10.10.10.26 for 86400 seconds Jan 21 17:38:22 v sh[715]: eth0: adding route to 10.10.10.0/24 Jan 21 17:38:22 v sh[715]: eth0: adding default route via 10.10.10.1 Jan 21 17:38:22 v dhcpcd[734]: eth0: adding route to 10.10.10.0/24 Jan 21 17:38:22 v dhcpcd[734]: eth0: adding default route via 10.10.10.1 Jan 21 17:38:22 v ntpd[1375]: ntpd exiting on signal 15 (Terminated) Jan 21 17:38:22 v ntpd[1375]: 27.71.27.209 local addr 10.10.10.26 -> Jan 21 17:38:22 v systemd[1]: Stopping Network Time Service... Jan 21 17:38:22 v systemd[1]: ntp.service: Succeeded. Jan 21 17:38:22 v systemd[1]: Stopped Network Time Service. Jan 21 17:38:22 v systemd[1]: Starting Network Time Service... Jan 21 17:38:22 v systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 21 17:38:22 v systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 21 17:38:22 v systemd[1]: Stopped go-librespot Daemon. Jan 21 17:38:22 v systemd[1]: Started go-librespot Daemon. Jan 21 17:38:22 v go-librespot[1414]: Librespot-go daemon starting... Jan 21 17:38:22 v ntpd[1410]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 21 17:38:22 v ntpd[1410]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 21 17:38:22 v systemd[1]: Started Network Time Service. Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=info msg="generated new device id: 714c9d432fa12eabaedc4b9ea7c067be06018e19" Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=debug msg="stored credentials found for 31gamgu4bknwmyxx5tbkpyamfzau" Jan 21 17:38:22 v ntpd[1423]: proto: precision = 0.087 usec (-23) Jan 21 17:38:22 v ntpd[1423]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 21 17:38:22 v ntpd[1423]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 21 17:38:22 v ntpd[1423]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 756 days ago Jan 21 17:38:22 v ntpd[1423]: Listen and drop on 0 v6wildcard [::]:123 Jan 21 17:38:22 v ntpd[1423]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 21 17:38:22 v sh[715]: forked to background, child pid 1424 Jan 21 17:38:22 v dhcpcd[734]: forked to background, child pid 1424 Jan 21 17:38:22 v ntpd[1423]: Listen normally on 2 lo 127.0.0.1:123 Jan 21 17:38:22 v ntpd[1423]: Listen normally on 3 eth0 10.10.10.26:123 Jan 21 17:38:22 v ntpd[1423]: Listening on routing socket on fd #20 for interface updates Jan 21 17:38:22 v ntpd[1423]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 17:38:22 v ntpd[1423]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 17:38:22 v ifplugd(eth0)[1005]: client: ifup: interface eth0 already configured Jan 21 17:38:22 v sh[715]: eth0=eth0 Jan 21 17:38:22 v ifplugd(eth0)[1005]: Program executed successfully. Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=debug msg="zeroconf server listening on port 40407" Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=debug msg="obtained new client token: AACP0HVkOZQZ8TItAhzBU0i0OmTDq0VwS3o+XwBLh1iKOD07hoa+TY52G3uJYEUOGIH8gjy5EU4kt3kX2ltYhjJqYtCe/O8bWGKeHN8kgK7MsvVxmoGRhMr3X73/UEmflBx6rs8h+Uyy/RJpq8I1U8Ep8NfAr037m1iO8Vm4XUMgNxAOk+rD1Y6thhb1rxDyuI3EtWYDT/P9NveQfZoK+oFQ9ghV5C4H5IaaD34zueNBTwPs7+Zcn9TrN84aAHc=" Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=info msg="connected to ap-gae2.spotify.com:4070" Jan 21 17:38:22 v go-librespot[1414]: time="2025-01-21T17:38:22+07:00" level=debug msg="completed keyexchange" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="completed challenge" Jan 21 17:38:23 v volumio-time-update[776]: volumio-time-update-util: Fetching time from Volumio... Jan 21 17:38:23 v ntpd[1423]: Soliciting pool server 103.199.19.135 Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="authenticated as 31gamgu4bknwmyxx5tbkpyamfzau" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="authenticated as 31gamgu4bknwmyxx5tbkpyamfzau" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="dealer connection opened" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="initializing zeroconf session, username: 31gamgu4bknwmyxx5tbkpyamfzau" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="autoplay enabled: false" Jan 21 17:38:23 v go-librespot[1414]: time="2025-01-21T17:38:23+07:00" level=debug msg="received connection id: Y2Y1NjdiNDctNDI2YS00ZTdmLWEyZjAtMGZkMjVkYThlYjBkK2RlYWxlcit0Y3A6Ly8wYWM5NDk5Yy5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArRDJBNkU0RjYwMDc0MzU2MDJFNEQ4ODY2RTM4REJDOUYyNzA2MEMwMjBFNzc1NDBGREMyNDQyRkFBQzI3NTgxQg==" Jan 21 17:38:23 v nmbd[988]: [2025/01/21 17:38:23.678223, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 21 17:38:23 v nmbd[988]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 21 17:38:23 v systemd[1]: Started Samba NMB Daemon. Jan 21 17:38:23 v systemd[1]: Starting Samba Winbind Daemon... Jan 21 17:38:23 v winbindd[1456]: [2025/01/21 17:38:23.760955, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 21 17:38:23 v winbindd[1456]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 21 17:38:23 v winbindd[1456]: [2025/01/21 17:38:23.762101, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 21 17:38:23 v winbindd[1456]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 21 17:38:23 v systemd[1]: Started Samba Winbind Daemon. Jan 21 17:38:23 v systemd[1]: Starting Samba SMB Daemon... Jan 21 17:38:23 v volumio-time-update[776]: volumio-time-update-util: Setting system time to: 2025-01-21 17:38:24 Jan 21 17:38:23 v sudo[1462]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-01-21 17:38:24 Jan 21 17:38:23 v sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:23 v dbus-daemon[768]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=1464 comm="timedatectl set-time 2025-01-21 17:38:24 ") Jan 21 17:38:23 v systemd[1]: Starting Time & Date Service... Jan 21 17:38:23 v dbus-daemon[768]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 21 17:38:23 v systemd[1]: Started Time & Date Service. Jan 21 17:38:24 v systemd-timedated[1465]: Changed local time to Tue Jan 21 17:38:24 2025 Jan 21 17:38:24 v sudo[1462]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:24 v volumio-time-update[776]: volumio-time-update-util: System time updated successfully. Jan 21 17:38:24 v systemd[1]: Started Volumio Time Update Utility. Jan 21 17:38:24 v go-librespot[1414]: time="2025-01-21T17:38:24+07:00" level=debug msg="put connect state because NEW_DEVICE" Jan 21 17:38:24 v smbd[1463]: [2025/01/21 17:38:24.039261, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 21 17:38:24 v smbd[1463]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 21 17:38:24 v systemd[1]: Started Samba SMB Daemon. Jan 21 17:38:24 v systemd[1]: Reached target Multi-User System. Jan 21 17:38:24 v systemd[1]: Reached target Graphical Interface. Jan 21 17:38:24 v systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 21 17:38:24 v systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 21 17:38:24 v systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 21 17:38:24 v systemd[1]: Startup finished in 7.429s (firmware) + 671ms (loader) + 5.533s (kernel) + 12.169s (userspace) = 25.804s. Jan 21 17:38:24 v ntpd[1423]: Soliciting pool server 115.165.161.155 Jan 21 17:38:24 v ntpd[1423]: Soliciting pool server 103.184.124.254 Jan 21 17:38:24 v volumio[1027]: verbose: New Socket.io Connection to 10.10.10.26 from 10.10.10.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:24 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::volumioGetQueue Jan 21 17:38:24 v volumio[1027]: info: CoreStateMachine::getQueue Jan 21 17:38:24 v volumio[1027]: info: CorePlayQueue::getQueue Jan 21 17:38:24 v volumio[1027]: info: Listing playlists Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 17:38:24 v volumio[1027]: info: Received Get System Info Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 17:38:24 v volumio[1027]: info: Discovery: Getting this device information Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:24 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 17:38:24 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:24 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:24 v volumio[1027]: info: Initializing connection to go-librespot Websocket Jan 21 17:38:24 v go-librespot[1414]: time="2025-01-21T17:38:24+07:00" level=debug msg="new websocket client" Jan 21 17:38:24 v volumio[1027]: info: Connection to go-librespot Websocket established Jan 21 17:38:25 v volumio[1027]: info: Volumio Network Manager: Network status updated: 1 Jan 21 17:38:25 v sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:38:25 v sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:25 v sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:38:25 v sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:25 v sudo[1492]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:25 v sudo[1494]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:25 v sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 21 17:38:25 v sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:25 v systemd[1]: Started UPnP Renderer front-end to MPD. Jan 21 17:38:25 v sudo[1498]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:25 v volumio[1027]: info: Upmpdcli Daemon Started Jan 21 17:38:25 v volumio[1500]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 21 17:38:25 v volumio[1500]: .++++ Jan 21 17:38:25 v ntpd[1423]: Soliciting pool server 103.184.124.254 Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 21 17:38:25 v volumio[1027]: info: Adding plugin bluetooth to MyMusic Plugins Jan 21 17:38:25 v volumio[1027]: info: Adding plugin multiroom to MyMusic Plugins Jan 21 17:38:25 v volumio[1027]: info: Adding plugin metavolumio to MyMusic Plugins Jan 21 17:38:25 v volumio[1027]: info: Adding plugin cd_controller to MyMusic Plugins Jan 21 17:38:25 v volumio[1027]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 21 17:38:25 v volumio[1027]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 21 17:38:25 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:25 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:25 v volumio[1027]: info: Starting MyVolumio Remote Streaming Endpoints Jan 21 17:38:25 v volumio[1027]: info: MyVolumio login type: Token Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 21 17:38:25 v volumio[1027]: info: Starting Streaming Service Transparent Proxy Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 21 17:38:25 v volumio[1027]: info: Streaming services startup Jan 21 17:38:25 v volumio[1027]: info: Starting Streaming Daemon Jan 21 17:38:25 v sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 17:38:25 v sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:25 v volumio[1027]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 21 17:38:25 v sudo[1523]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:25 v volumio[1027]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=f3fc95a79baf7759e23c89976133d10d" http://updates.volumio.org/downloader-v1/track-device Jan 21 17:38:25 v volumio[1027]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 21 17:38:25 v volumio[1027]: Dload Upload Total Spent Left Speed Jan 21 17:38:25 v volumio[1027]: [132B blob data] Jan 21 17:38:25 v volumio[1027]: retrying in 5 seconds, trial 0 Jan 21 17:38:25 v volumio[1027]: info: Volumio Calling Home Jan 21 17:38:26 v sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mecubap,password=Bocubap1978,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //MediaCenter/MediaCenter/music/ /mnt/NAS/Nhạc_Lossless Jan 21 17:38:26 v sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:26 v volumio[1027]: error: Cannot start Volumio Streaming Daemon Jan 21 17:38:26 v volumio[1027]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 17:38:26 v volumio[1027]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 17:38:26 v kernel: FS-Cache: Loaded Jan 21 17:38:26 v kernel: Key type dns_resolver registered Jan 21 17:38:26 v kernel: Key type cifs.spnego registered Jan 21 17:38:26 v kernel: Key type cifs.idmap registered Jan 21 17:38:26 v 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. Jan 21 17:38:26 v kernel: CIFS: Attempting to mount //MediaCenter/MediaCenter/music Jan 21 17:38:26 v volumio[1500]: ...........................................................................................................................................................................................................................................................................................++++ Jan 21 17:38:26 v volumio[1500]: e is 65537 (0x010001) Jan 21 17:38:26 v volumio[1500]: writing RSA key Jan 21 17:38:26 v volumio[1027]: STREAMING PROXY: Starting server on port 3245 Jan 21 17:38:26 v volumio[1027]: Node JS runtime: 14 Jan 21 17:38:26 v volumio[1027]: error: MyVolumio Custom Token format not valid, refreshing it Jan 21 17:38:26 v ntpd[1423]: Soliciting pool server 103.184.124.254 Jan 21 17:38:26 v volumio[1027]: info: Volumio called home Jan 21 17:38:27 v volumio[1027]: info: MyVolumio login type: Token Jan 21 17:38:27 v volumio[1027]: info: Getting Spotify volume Jan 21 17:38:27 v volumio[1027]: info: Spotify volume: 100 Jan 21 17:38:27 v volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 21 17:38:27 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:27 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:28 v volumio[1027]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 21 17:38:28 v sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:38:28 v sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:28 v sudo[1570]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:28 v sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:38:28 v sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:28 v sudo[1572]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:28 v volumio[1027]: info: MyVolumio token set successfully Jan 21 17:38:28 v volumio[1027]: info: MYVOLUMIO: Adding device Jan 21 17:38:28 v volumio[1027]: info: MYVOLUMIO: Evaluating Server Jan 21 17:38:28 v volumio[1027]: verbose: New Socket.io Connection to 10.10.10.26 from 10.10.10.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:28 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 17:38:28 v volumio[1027]: info: Received Get System Info Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 17:38:28 v volumio[1027]: info: Discovery: Getting this device information Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:28 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 17:38:28 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:28 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:28 v volumio[1027]: info: Listing playlists Jan 21 17:38:29 v volumio[1027]: info: MyVolumio status changed Jan 21 17:38:29 v volumio[1027]: info: Streaming services startup Jan 21 17:38:29 v volumio[1027]: info: Starting Streaming Daemon Jan 21 17:38:29 v volumio[1027]: info: Removing browser output: myVolumio user plan is not superstar Jan 21 17:38:29 v volumio[1027]: info: Removing audio output: Jan 21 17:38:29 v volumio[1027]: info: Stoppping Tunnel 1 Jan 21 17:38:29 v sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 17:38:29 v sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:29 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 17:38:29 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 17:38:29 v sudo[1593]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:29 v sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 21 17:38:29 v volumio[1027]: error: Cannot start Volumio Streaming Daemon Jan 21 17:38:29 v volumio[1027]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 17:38:29 v volumio[1027]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 17:38:29 v sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:29 v sudo[1595]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:29 v volumio[1027]: info: Remote SSH Stopped Jan 21 17:38:29 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 21 17:38:29 v volumio[1027]: info: Setting Geolocation for MyVolumio to as1 Jan 21 17:38:29 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:29 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:29 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:30 v volumio[1027]: info: Updating MyVolumio device info Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:30 v volumio[1027]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 17:38:30 v volumio[1027]: info: Received Get System Info Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 17:38:30 v volumio[1027]: info: Discovery: Getting this device information Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:30 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 17:38:30 v volumio[1027]: info: CoreCommandRouter::volumioPlay Jan 21 17:38:30 v volumio[1027]: info: CoreStateMachine::play index undefined Jan 21 17:38:30 v volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:38:30 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:30 v volumio[1027]: info: CoreStateMachine::startPlaybackTimer Jan 21 17:38:30 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:30 v volumio[1027]: info: [1737455910999] ControllerUPNPBrowser::clearAddPlayTrack Jan 21 17:38:30 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand stop took 1 milliseconds Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand clear took 0 milliseconds Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand load "http://10.10.10.17:50002/m/NDLNA/1320.wav" Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces system playlist update Jan 21 17:38:31 v volumio[1027]: info: Ignoring MPD Status Update Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces system playlist update Jan 21 17:38:31 v volumio[1027]: info: Ignoring MPD Status Update Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces system playlist update Jan 21 17:38:31 v volumio[1027]: info: Ignoring MPD Status Update Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand add "http://10.10.10.17:50002/m/NDLNA/1320.wav" Jan 21 17:38:31 v volumio[1027]: error: updateQueue error: null Jan 21 17:38:31 v volumio[1027]: error: updateQueue error: null Jan 21 17:38:31 v volumio[1027]: error: updateQueue error: null Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 9ms Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 8ms Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 8ms Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces system playlist update Jan 21 17:38:31 v volumio[1027]: info: Ignoring MPD Status Update Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand add "http://10.10.10.17:50002/m/NDLNA/1320.wav" took 1 milliseconds Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand play Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces system playlist update Jan 21 17:38:31 v volumio[1027]: info: Ignoring MPD Status Update Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces system playlist update Jan 21 17:38:31 v volumio[1027]: info: Ignoring MPD Status Update Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 2ms Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand play took 1 milliseconds Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 1ms Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 1ms Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces state update: player Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces state update: player Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces state update: player Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand status took 3 milliseconds Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces state update: player Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand status took 5 milliseconds Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand status took 5 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand clearerror took 2 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:31 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:31 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:31 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 11ms Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces state update: player Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:31 v volumio[1027]: info: Jan 21 17:38:31 v volumio[1027]: ---------------------------- MPD announces state update: player Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand status took 8 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand clearerror took 7 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand clearerror took 7 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand status took 2 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand status took 2 milliseconds Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:31 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:31 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:31 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:31 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:31 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:31 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 17ms Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 17ms Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 21 17:38:31 v volumio[1027]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:31 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:31 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:31 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:31 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:31 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:31 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:31 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:31 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:31 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:31 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:31 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:31 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:31 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 19ms Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 12ms Jan 21 17:38:31 v volumio[1027]: info: ------------------------------ 13ms Jan 21 17:38:31 v volumio[1027]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 21 17:38:32 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 17:38:32 v volumio[1027]: info: Received Get System Info Jan 21 17:38:32 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 17:38:32 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 17:38:32 v volumio[1027]: info: Discovery: Getting this device information Jan 21 17:38:32 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:32 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 17:38:34 v volumio[1027]: info: MYVOLUMIO: Adding device Jan 21 17:38:34 v volumio[1027]: info: MYVOLUMIO: Evaluating Server Jan 21 17:38:36 v sudo[1531]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:36 v volumio[1027]: info: Cannot mount NAS Nhạc_Lossless at system boot, trial number 3 ,retrying in 5 seconds Jan 21 17:38:36 v kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 21 17:38:36 v kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jan 21 17:38:38 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:39 v volumio[1027]: info: Retrieving Cloud Streaming UI Jan 21 17:38:39 v volumio[1027]: info: Getting Tidal Cloud Configuration Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:39 v volumio[1027]: info: Getting Qobuz Cloud Configuration Jan 21 17:38:39 v volumio[1027]: info: Asking plugin for UI Config Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:39 v volumio[1027]: info: Getting Spotify Cloud Configuration Jan 21 17:38:39 v volumio[1027]: info: Asking plugin for UI Config Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:39 v volumio[1027]: info: Saving Spotify Acccount Jan 21 17:38:39 v volumio[1027]: info: Got Tidal Cloud Configuration Jan 21 17:38:39 v volumio[1027]: info: Got it Jan 21 17:38:39 v volumio[1027]: info: Got it Jan 21 17:38:39 v volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:39 v volumio[1027]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 17:38:39 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 17:38:40 v volumio[1027]: info: Setting Geolocation for MyVolumio to eu3 Jan 21 17:38:40 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:40 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:40 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 17:38:40 v volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 21 17:38:40 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:41 v volumio[1027]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 21 17:38:41 v volumio[1027]: info: Updating MyVolumio device info Jan 21 17:38:41 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:41 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:41 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:41 v sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mecubap,password=Bocubap1978,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //MediaCenter/MediaCenter/music/ /mnt/NAS/Nhạc_Lossless Jan 21 17:38:41 v sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:41 v kernel: CIFS: Attempting to mount //MediaCenter/MediaCenter/music Jan 21 17:38:41 v volumio[1027]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 21 17:38:42 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 21 17:38:43 v systemd[1]: systemd-fsckd.service: Succeeded. Jan 21 17:38:43 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 17:38:43 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 17:38:43 v systemd[1]: systemd-hostnamed.service: Succeeded. Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand rescan Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: Jan 21 17:38:45 v volumio[1027]: ---------------------------- MPD announces state update: update Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand rescan took 1 milliseconds Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 1 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 1 milliseconds Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: info: Command Router : Notfying DB Updatetrue Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: Jan 21 17:38:45 v volumio[1027]: ---------------------------- MPD announces state update: update Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: Jan 21 17:38:45 v volumio[1027]: ---------------------------- MPD announces state update: update Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: Jan 21 17:38:45 v volumio[1027]: ---------------------------- MPD announces state update: update Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 2 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 2 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 1 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 1 milliseconds Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: info: Command Router : Notfying DB Updatefalse Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: info: Command Router : Notfying DB Updatefalse Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:45 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:45 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:45 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:45 v volumio[1027]: info: ------------------------------ 35ms Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: Jan 21 17:38:45 v volumio[1027]: ---------------------------- MPD announces state update: update Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: Jan 21 17:38:45 v volumio[1027]: ---------------------------- MPD announces state update: update Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::getState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 25 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 25 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 6 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 5 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 5 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand status took 5 milliseconds Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: info: Command Router : Notfying DB Updatefalse Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: info: Command Router : Notfying DB Updatefalse Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: info: Command Router : Notfying DB Updatefalse Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseState Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:45 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:45 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:45 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:45 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:45 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:45 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:45 v volumio[1027]: info: ------------------------------ 55ms Jan 21 17:38:45 v volumio[1027]: info: ------------------------------ 54ms Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 21 17:38:45 v volumio[1027]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:45 v volumio[1027]: verbose: ControllerMpd::parseTrackInfo Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:45 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:45 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:45 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:45 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:45 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:45 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:45 v volumio[1027]: info: ControllerMpd::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::servicePushState Jan 21 17:38:45 v volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 21 17:38:45 v volumio[1027]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1320.wav","artist":null,"album":null,"uri":"http://10.10.10.17:50002/m/NDLNA/1320.wav","trackType":"wav"} Jan 21 17:38:45 v volumio[1027]: verbose: CURRENT POSITION 0 Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState stateService pause Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 21 17:38:45 v volumio[1027]: info: CoreStateMachine::pushState Jan 21 17:38:45 v volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 21 17:38:45 v volumio[1027]: info: ------------------------------ 62ms Jan 21 17:38:45 v volumio[1027]: info: ------------------------------ 43ms Jan 21 17:38:45 v volumio[1027]: info: ------------------------------ 42ms Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 17:38:46 v volumio[1027]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 21 17:38:46 v volumio[1027]: info: Completed starting MyVolumio Plugin Jan 21 17:38:46 v volumio[1027]: [Metrics] CommandRouter: 31s 495.87ms Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::volumiosetStartupVolume Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:38:46 v volumio-remote-updater[774]: No test mode Jan 21 17:38:46 v volumio-remote-updater[774]: No alpha test mode Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 21 17:38:46 v volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 21 17:38:46 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 21 17:38:47 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 21 17:38:47 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 21 17:38:47 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 21 17:38:47 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 21 17:38:47 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 17:38:48 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 17:38:48 v volumio[1027]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 21 17:38:48 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 21 17:38:48 v volumio[1027]: info: Received Get System Version Jan 21 17:38:48 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 17:38:48 v volumio[1027]: info: Received Get System Info Jan 21 17:38:48 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 17:38:48 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 17:38:48 v volumio[1027]: info: Discovery: Getting this device information Jan 21 17:38:48 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:48 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 17:38:51 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 17:38:51 v sudo[1643]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:51 v volumio[1027]: info: Cannot mount NAS Nhạc_Lossless at system boot, trial number 4 ,retrying in 5 seconds Jan 21 17:38:51 v kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 21 17:38:51 v kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jan 21 17:38:53 v volumio[1027]: info: BOOT COMPLETED Jan 21 17:38:54 v systemd[1]: systemd-timedated.service: Succeeded. Jan 21 17:38:55 v sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:38:55 v sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:55 v sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:38:55 v sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:38:55 v sudo[1706]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:55 v sudo[1708]: pam_unix(sudo:session): session closed for user root Jan 21 17:38:55 v volumio[1027]: verbose: New Socket.io Connection to 10.10.10.26 from 10.10.10.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:55 v volumio[1027]: info: Retrieving Cloud Streaming UI Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 17:38:55 v volumio[1027]: info: Received Get System Info Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 17:38:55 v volumio[1027]: info: Discovery: Getting this device information Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 21 17:38:55 v volumio[1027]: info: Listing playlists Jan 21 17:38:55 v volumio[1027]: info: Getting Tidal Cloud Configuration Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:55 v volumio[1027]: info: Getting Qobuz Cloud Configuration Jan 21 17:38:55 v volumio[1027]: info: Asking plugin for UI Config Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:55 v volumio[1027]: info: Getting Spotify Cloud Configuration Jan 21 17:38:55 v volumio[1027]: info: Asking plugin for UI Config Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:55 v volumio[1027]: info: Saving Spotify Acccount Jan 21 17:38:55 v volumio[1027]: info: Got Tidal Cloud Configuration Jan 21 17:38:55 v volumio[1027]: info: Got it Jan 21 17:38:55 v volumio[1027]: info: Got it Jan 21 17:38:55 v volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:38:55 v volumio[1027]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 17:38:55 v volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 21 17:38:56 v volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 17:38:56 v volumio[1027]: TypeError: Cannot read property 'replace' of undefined Jan 21 17:38:56 v volumio[1027]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jan 21 17:38:56 v volumio[1027]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jan 21 17:38:56 v volumio[1027]: at listOnTimeout (internal/timers.js:557:17) Jan 21 17:38:56 v volumio[1027]: at processTimers (internal/timers.js:500:7) Jan 21 17:38:56 v volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 17:38:56 v sudo[1720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 17:37 Jan 21 17:38:56 v sudo[1720]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"