-- Logs begin at Thu 2024-08-29 11:07:03 CEST, end at Thu 2024-08-29 11:07:46 CEST. -- Aug 29 11:07:03 volumio1 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 Aug 29 11:07:03 volumio1 kernel: Command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=01702c47-0ef9-4ed1-a87a-8ab83b214a17 bootpart=UUID=5102-F1E1 datapart=UUID=7d68ad65-41f4-48d1-8886-6693f8079a92 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 Aug 29 11:07:03 volumio1 kernel: KERNEL supported cpus: Aug 29 11:07:03 volumio1 kernel: Intel GenuineIntel Aug 29 11:07:03 volumio1 kernel: AMD AuthenticAMD Aug 29 11:07:03 volumio1 kernel: Hygon HygonGenuine Aug 29 11:07:03 volumio1 kernel: Centaur CentaurHauls Aug 29 11:07:03 volumio1 kernel: zhaoxin Shanghai Aug 29 11:07:03 volumio1 kernel: BIOS-provided physical RAM map: Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x0000000000001000-0x000000000008efff] usable Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000000008f000-0x000000000008ffff] ACPI NVS Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x0000000000090000-0x000000000009dfff] usable Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000000009e000-0x000000000009ffff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000001effffff] usable Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000001f000000-0x00000000201fffff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x0000000020200000-0x000000007a6e9fff] usable Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000007a6ea000-0x000000007a719fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000007a71a000-0x000000007a746fff] ACPI data Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000007a747000-0x000000007a80efff] ACPI NVS Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000007a80f000-0x000000007ab76fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000007ab77000-0x000000007abdbfff] type 20 Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x000000007abdc000-0x000000007bffffff] usable Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000e3ffffff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fea00000-0x00000000feafffff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fed01000-0x00000000fed01fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fed03000-0x00000000fed03fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fed06000-0x00000000fed06fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fed08000-0x00000000fed09fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1cfff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fed80000-0x00000000fedbffff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved Aug 29 11:07:03 volumio1 kernel: BIOS-e820: [mem 0x00000000ffa00000-0x00000000ffffffff] reserved Aug 29 11:07:03 volumio1 kernel: NX (Execute Disable) protection: active Aug 29 11:07:03 volumio1 kernel: APIC: Static calls initialized Aug 29 11:07:03 volumio1 kernel: efi: EFI v2.4 by American Megatrends Aug 29 11:07:03 volumio1 kernel: efi: ACPI=0x7a723000 ACPI 2.0=0x7a723000 SMBIOS=0x7a9f7000 ESRT=0x7a9e7598 SMBIOS 3.0=0x7a9f6000 Aug 29 11:07:03 volumio1 kernel: efi: Remove mem28: MMIO range=[0xfea00000-0xfeafffff] (1MB) from e820 map Aug 29 11:07:03 volumio1 kernel: e820: remove [mem 0xfea00000-0xfeafffff] reserved Aug 29 11:07:03 volumio1 kernel: efi: Not removing mem29: MMIO range=[0xfec00000-0xfec00fff] (4KB) from e820 map Aug 29 11:07:03 volumio1 kernel: efi: Not removing mem30: MMIO range=[0xfed01000-0xfed01fff] (4KB) from e820 map Aug 29 11:07:03 volumio1 kernel: efi: Not removing mem31: MMIO range=[0xfed03000-0xfed03fff] (4KB) from e820 map Aug 29 11:07:03 volumio1 kernel: efi: Not removing mem32: MMIO range=[0xfed06000-0xfed06fff] (4KB) from e820 map Aug 29 11:07:03 volumio1 kernel: efi: Not removing mem33: MMIO range=[0xfed08000-0xfed09fff] (8KB) from e820 map Aug 29 11:07:03 volumio1 kernel: efi: Not removing mem34: MMIO range=[0xfed1c000-0xfed1cfff] (4KB) from e820 map Aug 29 11:07:03 volumio1 kernel: efi: Remove mem35: MMIO range=[0xfed80000-0xfedbffff] (0MB) from e820 map Aug 29 11:07:03 volumio1 kernel: e820: remove [mem 0xfed80000-0xfedbffff] reserved Aug 29 11:07:03 volumio1 kernel: efi: Not removing mem36: MMIO range=[0xfee00000-0xfee00fff] (4KB) from e820 map Aug 29 11:07:03 volumio1 kernel: efi: Remove mem37: MMIO range=[0xffa00000-0xffffffff] (6MB) from e820 map Aug 29 11:07:03 volumio1 kernel: e820: remove [mem 0xffa00000-0xffffffff] reserved Aug 29 11:07:03 volumio1 kernel: SMBIOS 3.0.0 present. Aug 29 11:07:03 volumio1 kernel: DMI: Dell Inc. Wyse 3040 Thin Client/0G56C0, BIOS 1.2.5 08/20/2018 Aug 29 11:07:03 volumio1 kernel: tsc: Detected 1440.000 MHz processor Aug 29 11:07:03 volumio1 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 29 11:07:03 volumio1 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 29 11:07:03 volumio1 kernel: last_pfn = 0x7c000 max_arch_pfn = 0x400000000 Aug 29 11:07:03 volumio1 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Aug 29 11:07:03 volumio1 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 29 11:07:03 volumio1 kernel: esrt: Reserving ESRT space from 0x000000007a9e7598 to 0x000000007a9e75d0. Aug 29 11:07:03 volumio1 kernel: Secure boot could not be determined Aug 29 11:07:03 volumio1 kernel: RAMDISK: [mem 0x2fae9000-0x33d6bfff] Aug 29 11:07:03 volumio1 kernel: ACPI: Early table checksum verification disabled Aug 29 11:07:03 volumio1 kernel: ACPI: RSDP 0x000000007A723000 000024 (v02 DELL ) Aug 29 11:07:03 volumio1 kernel: ACPI: XSDT 0x000000007A7230A0 0000BC (v01 DELL CBX3 01072009 AMI 00010013) Aug 29 11:07:03 volumio1 kernel: ACPI: FACP 0x000000007A740718 00010C (v05 DELL CBX3 01072009 AMI 00010013) Aug 29 11:07:03 volumio1 kernel: ACPI: DSDT 0x000000007A7231E8 01D52A (v02 DELL CBX3 01072009 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: FACS 0x000000007A80EF80 000040 Aug 29 11:07:03 volumio1 kernel: ACPI: APIC 0x000000007A740828 000084 (v03 DELL CBX3 01072009 AMI 00010013) Aug 29 11:07:03 volumio1 kernel: ACPI: FPDT 0x000000007A7408B0 000044 (v01 DELL CBX3 01072009 AMI 00010013) Aug 29 11:07:03 volumio1 kernel: ACPI: FIDT 0x000000007A7408F8 00009C (v01 DELL CBX3 01072009 AMI 00010013) Aug 29 11:07:03 volumio1 kernel: ACPI: MCFG 0x000000007A740998 00003C (v01 DELL CBX3 01072009 MSFT 00000097) Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0x000000007A7409D8 0041C5 (v01 DptfTb DptfTab 00001000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0x000000007A744BA0 000654 (v01 CpuDpf CpuDptf 00001000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0x000000007A7451F8 000058 (v01 LowPM LowPwrM 00001000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: UEFI 0x000000007A745250 000042 (v01 DELL CBX3 00000000 00000000) Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0x000000007A745298 000269 (v01 UsbCTb UsbCTab 00001000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: HPET 0x000000007A745508 000038 (v01 DELL CBX3 01072009 AMI. 00000005) Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0x000000007A745540 000763 (v01 PmRef CpuPm 00003000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0x000000007A745CA8 000290 (v01 PmRef Cpu0Tst 00003000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0x000000007A745F38 00017A (v01 PmRef ApTst 00003000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: LPIT 0x000000007A7460B8 000104 (v01 DELL CBX3 00000005 MSFT 0100000D) Aug 29 11:07:03 volumio1 kernel: ACPI: BCFG 0x000000007A7461C0 000139 (v01 INTEL BATTCONF 00000001 INTL 00000000) Aug 29 11:07:03 volumio1 kernel: ACPI: PRAM 0x000000007A746300 000030 (v01 00000001 00000000) Aug 29 11:07:03 volumio1 kernel: ACPI: CSRT 0x000000007A746330 00014C (v00 INTEL LANFORDC 00000005 MSFT 0100000D) Aug 29 11:07:03 volumio1 kernel: ACPI: WDAT 0x000000007A746480 000104 (v01 00000000 00000000) Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving FACP table memory at [mem 0x7a740718-0x7a740823] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving DSDT table memory at [mem 0x7a7231e8-0x7a740711] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving FACS table memory at [mem 0x7a80ef80-0x7a80efbf] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving APIC table memory at [mem 0x7a740828-0x7a7408ab] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving FPDT table memory at [mem 0x7a7408b0-0x7a7408f3] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving FIDT table memory at [mem 0x7a7408f8-0x7a740993] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving MCFG table memory at [mem 0x7a740998-0x7a7409d3] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving SSDT table memory at [mem 0x7a7409d8-0x7a744b9c] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving SSDT table memory at [mem 0x7a744ba0-0x7a7451f3] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving SSDT table memory at [mem 0x7a7451f8-0x7a74524f] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving UEFI table memory at [mem 0x7a745250-0x7a745291] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving SSDT table memory at [mem 0x7a745298-0x7a745500] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving HPET table memory at [mem 0x7a745508-0x7a74553f] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving SSDT table memory at [mem 0x7a745540-0x7a745ca2] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving SSDT table memory at [mem 0x7a745ca8-0x7a745f37] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving SSDT table memory at [mem 0x7a745f38-0x7a7460b1] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving LPIT table memory at [mem 0x7a7460b8-0x7a7461bb] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving BCFG table memory at [mem 0x7a7461c0-0x7a7462f8] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving PRAM table memory at [mem 0x7a746300-0x7a74632f] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving CSRT table memory at [mem 0x7a746330-0x7a74647b] Aug 29 11:07:03 volumio1 kernel: ACPI: Reserving WDAT table memory at [mem 0x7a746480-0x7a746583] Aug 29 11:07:03 volumio1 kernel: Zone ranges: Aug 29 11:07:03 volumio1 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 29 11:07:03 volumio1 kernel: DMA32 [mem 0x0000000001000000-0x000000007bffffff] Aug 29 11:07:03 volumio1 kernel: Normal empty Aug 29 11:07:03 volumio1 kernel: Movable zone start for each node Aug 29 11:07:03 volumio1 kernel: Early memory node ranges Aug 29 11:07:03 volumio1 kernel: node 0: [mem 0x0000000000001000-0x000000000008efff] Aug 29 11:07:03 volumio1 kernel: node 0: [mem 0x0000000000090000-0x000000000009dfff] Aug 29 11:07:03 volumio1 kernel: node 0: [mem 0x0000000000100000-0x000000001effffff] Aug 29 11:07:03 volumio1 kernel: node 0: [mem 0x0000000020200000-0x000000007a6e9fff] Aug 29 11:07:03 volumio1 kernel: node 0: [mem 0x000000007abdc000-0x000000007bffffff] Aug 29 11:07:03 volumio1 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007bffffff] Aug 29 11:07:03 volumio1 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 29 11:07:03 volumio1 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 29 11:07:03 volumio1 kernel: On node 0, zone DMA: 98 pages in unavailable ranges Aug 29 11:07:03 volumio1 kernel: On node 0, zone DMA32: 4608 pages in unavailable ranges Aug 29 11:07:03 volumio1 kernel: On node 0, zone DMA32: 1266 pages in unavailable ranges Aug 29 11:07:03 volumio1 kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges Aug 29 11:07:03 volumio1 kernel: Reserving Intel graphics memory at [mem 0x7cf00000-0x7eefffff] Aug 29 11:07:03 volumio1 kernel: ACPI: PM-Timer IO Port: 0x408 Aug 29 11:07:03 volumio1 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1]) Aug 29 11:07:03 volumio1 kernel: ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1]) Aug 29 11:07:03 volumio1 kernel: ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1]) Aug 29 11:07:03 volumio1 kernel: ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1]) Aug 29 11:07:03 volumio1 kernel: IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-114 Aug 29 11:07:03 volumio1 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Aug 29 11:07:03 volumio1 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 29 11:07:03 volumio1 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 29 11:07:03 volumio1 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Aug 29 11:07:03 volumio1 kernel: TSC deadline timer available Aug 29 11:07:03 volumio1 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x0008f000-0x0008ffff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x0009e000-0x0009ffff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000fffff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x1f000000-0x201fffff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x7a6ea000-0x7a719fff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x7a71a000-0x7a746fff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x7a747000-0x7a80efff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x7a80f000-0x7ab76fff] Aug 29 11:07:03 volumio1 kernel: PM: hibernation: Registered nosave memory: [mem 0x7ab77000-0x7abdbfff] Aug 29 11:07:03 volumio1 kernel: [mem 0x7ef00000-0xdfffffff] available for PCI devices Aug 29 11:07:03 volumio1 kernel: Booting paravirtualized kernel on bare hardware Aug 29 11:07:03 volumio1 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Aug 29 11:07:03 volumio1 kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Aug 29 11:07:03 volumio1 kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u524288 Aug 29 11:07:03 volumio1 kernel: pcpu-alloc: s191400 r8192 d29784 u524288 alloc=1*2097152 Aug 29 11:07:03 volumio1 kernel: pcpu-alloc: [0] 0 1 2 3 Aug 29 11:07:03 volumio1 kernel: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=01702c47-0ef9-4ed1-a87a-8ab83b214a17 bootpart=UUID=5102-F1E1 datapart=UUID=7d68ad65-41f4-48d1-8886-6693f8079a92 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 Aug 29 11:07:03 volumio1 kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=/vmlinuz biosdevname=0 imgpart=UUID=01702c47-0ef9-4ed1-a87a-8ab83b214a17 bootpart=UUID=5102-F1E1 datapart=UUID=7d68ad65-41f4-48d1-8886-6693f8079a92 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Aug 29 11:07:03 volumio1 kernel: random: crng init done Aug 29 11:07:03 volumio1 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 29 11:07:03 volumio1 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 29 11:07:03 volumio1 kernel: Built 1 zonelists, mobility grouping on. Total pages: 493838 Aug 29 11:07:03 volumio1 kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off Aug 29 11:07:03 volumio1 kernel: Memory: 1792324K/2007720K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 215140K reserved, 0K cma-reserved) Aug 29 11:07:03 volumio1 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 29 11:07:03 volumio1 kernel: Kernel/User page tables isolation: enabled Aug 29 11:07:03 volumio1 kernel: ftrace: allocating 40433 entries in 158 pages Aug 29 11:07:03 volumio1 kernel: ftrace: allocated 158 pages with 5 groups Aug 29 11:07:03 volumio1 kernel: Dynamic Preempt: voluntary Aug 29 11:07:03 volumio1 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 29 11:07:03 volumio1 kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Aug 29 11:07:03 volumio1 kernel: Trampoline variant of Tasks RCU enabled. Aug 29 11:07:03 volumio1 kernel: Rude variant of Tasks RCU enabled. Aug 29 11:07:03 volumio1 kernel: Tracing variant of Tasks RCU enabled. Aug 29 11:07:03 volumio1 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Aug 29 11:07:03 volumio1 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 29 11:07:03 volumio1 kernel: NR_IRQS: 4352, nr_irqs: 1024, preallocated irqs: 16 Aug 29 11:07:03 volumio1 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 29 11:07:03 volumio1 kernel: Console: colour dummy device 80x25 Aug 29 11:07:03 volumio1 kernel: printk: console [tty0] enabled Aug 29 11:07:03 volumio1 kernel: ACPI: Core revision 20230628 Aug 29 11:07:03 volumio1 kernel: hpet: HPET dysfunctional in PC10. Force disabled. Aug 29 11:07:03 volumio1 kernel: APIC: Switch to symmetric I/O mode setup Aug 29 11:07:03 volumio1 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x14c1baf3789, max_idle_ns: 440795266465 ns Aug 29 11:07:03 volumio1 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2880.00 BogoMIPS (lpj=5760000) Aug 29 11:07:03 volumio1 kernel: CPU0: Thermal monitoring enabled (TM1) Aug 29 11:07:03 volumio1 kernel: process: using mwait in idle threads Aug 29 11:07:03 volumio1 kernel: Last level iTLB entries: 4KB 48, 2MB 0, 4MB 0 Aug 29 11:07:03 volumio1 kernel: Last level dTLB entries: 4KB 256, 2MB 16, 4MB 16, 1GB 0 Aug 29 11:07:03 volumio1 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 29 11:07:03 volumio1 kernel: Spectre V2 : Mitigation: Retpolines Aug 29 11:07:03 volumio1 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Aug 29 11:07:03 volumio1 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Aug 29 11:07:03 volumio1 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Aug 29 11:07:03 volumio1 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Aug 29 11:07:03 volumio1 kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Aug 29 11:07:03 volumio1 kernel: MMIO Stale Data: Unknown: No mitigations Aug 29 11:07:03 volumio1 kernel: x86/fpu: x87 FPU will use FXSAVE Aug 29 11:07:03 volumio1 kernel: Freeing SMP alternatives memory: 32K Aug 29 11:07:03 volumio1 kernel: pid_max: default: 32768 minimum: 301 Aug 29 11:07:03 volumio1 kernel: LSM: initializing lsm=capability,yama,integrity Aug 29 11:07:03 volumio1 kernel: Yama: becoming mindful. Aug 29 11:07:03 volumio1 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 29 11:07:03 volumio1 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 29 11:07:03 volumio1 kernel: smpboot: CPU0: Intel(R) Atom(TM) x5-Z8350 CPU @ 1.44GHz (family: 0x6, model: 0x4c, stepping: 0x4) Aug 29 11:07:03 volumio1 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 29 11:07:03 volumio1 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 29 11:07:03 volumio1 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 29 11:07:03 volumio1 kernel: Performance Events: PEBS fmt2+, 8-deep LBR, Silvermont events, 8-deep LBR, full-width counters, Intel PMU driver. Aug 29 11:07:03 volumio1 kernel: ... version: 3 Aug 29 11:07:03 volumio1 kernel: ... bit width: 40 Aug 29 11:07:03 volumio1 kernel: ... generic registers: 2 Aug 29 11:07:03 volumio1 kernel: ... value mask: 000000ffffffffff Aug 29 11:07:03 volumio1 kernel: ... max period: 0000007fffffffff Aug 29 11:07:03 volumio1 kernel: ... fixed-purpose events: 3 Aug 29 11:07:03 volumio1 kernel: ... event mask: 0000000700000003 Aug 29 11:07:03 volumio1 kernel: signal: max sigframe size: 1440 Aug 29 11:07:03 volumio1 kernel: Estimated ratio of average max frequency by base frequency (times 1024): 1365 Aug 29 11:07:03 volumio1 kernel: rcu: Hierarchical SRCU implementation. Aug 29 11:07:03 volumio1 kernel: rcu: Max phase no-delay instances is 1000. Aug 29 11:07:03 volumio1 kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. Aug 29 11:07:03 volumio1 kernel: smp: Bringing up secondary CPUs ... Aug 29 11:07:03 volumio1 kernel: smpboot: x86: Booting SMP configuration: Aug 29 11:07:03 volumio1 kernel: .... node #0, CPUs: #1 #2 #3 Aug 29 11:07:03 volumio1 kernel: smp: Brought up 1 node, 4 CPUs Aug 29 11:07:03 volumio1 kernel: smpboot: Max logical packages: 1 Aug 29 11:07:03 volumio1 kernel: smpboot: Total of 4 processors activated (11520.00 BogoMIPS) Aug 29 11:07:03 volumio1 kernel: devtmpfs: initialized Aug 29 11:07:03 volumio1 kernel: x86/mm: Memory block size: 128MB Aug 29 11:07:03 volumio1 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0008f000-0x0008ffff] (4096 bytes) Aug 29 11:07:03 volumio1 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x7a747000-0x7a80efff] (819200 bytes) Aug 29 11:07:03 volumio1 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Aug 29 11:07:03 volumio1 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 29 11:07:03 volumio1 kernel: pinctrl core: initialized pinctrl subsystem Aug 29 11:07:03 volumio1 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 29 11:07:03 volumio1 kernel: audit: initializing netlink subsys (disabled) Aug 29 11:07:03 volumio1 kernel: audit: type=2000 audit(1724922401.020:1): state=initialized audit_enabled=0 res=1 Aug 29 11:07:03 volumio1 kernel: thermal_sys: Registered thermal governor 'fair_share' Aug 29 11:07:03 volumio1 kernel: thermal_sys: Registered thermal governor 'bang_bang' Aug 29 11:07:03 volumio1 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 29 11:07:03 volumio1 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 29 11:07:03 volumio1 kernel: EISA bus registered Aug 29 11:07:03 volumio1 kernel: cpuidle: using governor ladder Aug 29 11:07:03 volumio1 kernel: cpuidle: using governor menu Aug 29 11:07:03 volumio1 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 29 11:07:03 volumio1 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Aug 29 11:07:03 volumio1 kernel: PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved as E820 entry Aug 29 11:07:03 volumio1 kernel: PCI: MMCONFIG for 0000 [bus00-3f] at [mem 0xe0000000-0xe3ffffff] (base 0xe0000000) (size reduced!) Aug 29 11:07:03 volumio1 kernel: PCI: Using configuration type 1 for base access Aug 29 11:07:03 volumio1 kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance' Aug 29 11:07:03 volumio1 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 29 11:07:03 volumio1 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 29 11:07:03 volumio1 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 29 11:07:03 volumio1 kernel: ACPI: Added _OSI(Module Device) Aug 29 11:07:03 volumio1 kernel: ACPI: Added _OSI(Processor Device) Aug 29 11:07:03 volumio1 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 29 11:07:03 volumio1 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 29 11:07:03 volumio1 kernel: ACPI: 8 ACPI AML tables successfully acquired and loaded Aug 29 11:07:03 volumio1 kernel: ACPI: Dynamic OEM Table Load: Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0xFFFF888001961000 00059C (v01 PmRef Cpu0Ist 00003000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: Dynamic OEM Table Load: Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0xFFFF888001B74000 0003A5 (v01 PmRef Cpu0Cst 00003001 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: Dynamic OEM Table Load: Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0xFFFF888001B72000 00015F (v01 PmRef ApIst 00003000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: Dynamic OEM Table Load: Aug 29 11:07:03 volumio1 kernel: ACPI: SSDT 0xFFFF888001919180 00008D (v01 PmRef ApCst 00003000 INTL 20120913) Aug 29 11:07:03 volumio1 kernel: ACPI: _OSC evaluated successfully for all CPUs Aug 29 11:07:03 volumio1 kernel: ACPI: Interpreter enabled Aug 29 11:07:03 volumio1 kernel: ACPI: PM: (supports S0 S4 S5) Aug 29 11:07:03 volumio1 kernel: ACPI: Using IOAPIC for interrupt routing Aug 29 11:07:03 volumio1 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 29 11:07:03 volumio1 kernel: PCI: Using E820 reservations for host bridge windows Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P12T: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P12A: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.ISP3.ID3C: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.USBC: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.XHC1.RHUB.SSC1.WWPR: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.XHC1.RHUB.SSCW.WWPR: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.XHC1.RHUB.HSC1.WWPR: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.XHC1.RHUB.HSC3.WWPR: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.I2C2.CLK3: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.I2C2.CLK4: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.I2C3.CLK2: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.I2C3.CLK1: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P28X: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P18X: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P12X: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P28P: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P18P: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P19X: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P06X: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P28T: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P18D: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P18T: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P3P3: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P28W: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P18W: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P12W: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P33W: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P33X: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.P4BW: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 29 11:07:03 volumio1 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 29 11:07:03 volumio1 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR] Aug 29 11:07:03 volumio1 kernel: acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-3f] only partially covers this bridge Aug 29 11:07:03 volumio1 kernel: PCI host bridge to bus 0000:00 Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [io 0x0070-0x0077] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x006f window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [io 0x0078-0x0cf7 window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000fffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [mem 0x20000000-0x201fffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [mem 0x7cf00000-0x7eefffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xdfffffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:00.0: [8086:2280] type 00 class 0x060000 Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: [8086:22b0] type 00 class 0x030000 Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: reg 0x10: [mem 0x90000000-0x90ffffff 64bit] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: reg 0x18: [mem 0x80000000-0x8fffffff 64bit pref] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: reg 0x20: [io 0xf000-0xf03f] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:0b.0: [8086:22dc] type 00 class 0x118000 Aug 29 11:07:03 volumio1 kernel: pci 0000:00:0b.0: reg 0x10: [mem 0x9152c000-0x9152cfff 64bit] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:11.0: [8086:2295] type 00 class 0x080501 Aug 29 11:07:03 volumio1 kernel: pci 0000:00:11.0: reg 0x10: [mem 0x91529000-0x91529fff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:11.0: PME# supported from D0 D3hot Aug 29 11:07:03 volumio1 kernel: pci 0000:00:14.0: [8086:22b5] type 00 class 0x0c0330 Aug 29 11:07:03 volumio1 kernel: pci 0000:00:14.0: reg 0x10: [mem 0x91500000-0x9150ffff 64bit] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:14.0: PME# supported from D3hot D3cold Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1a.0: [8086:2298] type 00 class 0x108000 Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1a.0: reg 0x10: [mem 0x91300000-0x913fffff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1a.0: reg 0x14: [mem 0x91200000-0x912fffff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1a.0: PME# supported from D0 D3hot Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: [8086:22c8] type 01 class 0x060400 Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1f.0: [8086:229c] type 00 class 0x060100 Aug 29 11:07:03 volumio1 kernel: pci 0000:01:00.0: [10ec:8168] type 00 class 0x020000 Aug 29 11:07:03 volumio1 kernel: pci 0000:01:00.0: reg 0x10: [io 0xe000-0xe0ff] Aug 29 11:07:03 volumio1 kernel: pci 0000:01:00.0: reg 0x18: [mem 0x91404000-0x91404fff 64bit] Aug 29 11:07:03 volumio1 kernel: pci 0000:01:00.0: reg 0x20: [mem 0x91400000-0x91403fff 64bit] Aug 29 11:07:03 volumio1 kernel: pci 0000:01:00.0: supports D1 D2 Aug 29 11:07:03 volumio1 kernel: pci 0000:01:00.0: PME# supported from D0 D1 D2 D3hot D3cold Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: PCI bridge to [bus 01] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: bridge window [io 0xe000-0xefff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: bridge window [mem 0x91400000-0x914fffff] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: on NUMA node 0 Aug 29 11:07:03 volumio1 kernel: Low-power S0 idle used by default for system suspend Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKA disabled Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKB disabled Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKC disabled Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKD disabled Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKE disabled Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKF disabled Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKG disabled Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 0 Aug 29 11:07:03 volumio1 kernel: ACPI: PCI: Interrupt link LNKH disabled Aug 29 11:07:03 volumio1 kernel: gpio gpiochip1: (INT33FF:01): gpiochip_lock_as_irq: tried to flag a GPIO set as output for IRQ Aug 29 11:07:03 volumio1 kernel: cherryview-pinctrl INT33FF:01: Failed to lock GPIO pin 0x0005 as interrupt, err -5 Aug 29 11:07:03 volumio1 kernel: cherryview-pinctrl: probe of INT33FF:04 failed with error -61 Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.I2C4.CLK0: New power resource Aug 29 11:07:03 volumio1 kernel: ACPI: \_SB_.PCI0.I2C4.CLK1: New power resource Aug 29 11:07:03 volumio1 kernel: iommu: Default domain type: Translated Aug 29 11:07:03 volumio1 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 29 11:07:03 volumio1 kernel: SCSI subsystem initialized Aug 29 11:07:03 volumio1 kernel: libata version 3.00 loaded. Aug 29 11:07:03 volumio1 kernel: ACPI: bus type USB registered Aug 29 11:07:03 volumio1 kernel: usbcore: registered new interface driver usbfs Aug 29 11:07:03 volumio1 kernel: usbcore: registered new interface driver hub Aug 29 11:07:03 volumio1 kernel: usbcore: registered new device driver usb Aug 29 11:07:03 volumio1 kernel: efivars: Registered efivars operations Aug 29 11:07:03 volumio1 kernel: PCI: Using ACPI for IRQ routing Aug 29 11:07:03 volumio1 kernel: PCI: pci_cache_line_size set to 64 bytes Aug 29 11:07:03 volumio1 kernel: e820: reserve RAM buffer [mem 0x0008f000-0x0008ffff] Aug 29 11:07:03 volumio1 kernel: e820: reserve RAM buffer [mem 0x0009e000-0x0009ffff] Aug 29 11:07:03 volumio1 kernel: e820: reserve RAM buffer [mem 0x1f000000-0x1fffffff] Aug 29 11:07:03 volumio1 kernel: e820: reserve RAM buffer [mem 0x7a6ea000-0x7bffffff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Aug 29 11:07:03 volumio1 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Aug 29 11:07:03 volumio1 kernel: vgaarb: loaded Aug 29 11:07:03 volumio1 kernel: clocksource: Switched to clocksource tsc-early Aug 29 11:07:03 volumio1 kernel: VFS: Disk quotas dquot_6.6.0 Aug 29 11:07:03 volumio1 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 29 11:07:03 volumio1 kernel: pnp: PnP ACPI init Aug 29 11:07:03 volumio1 kernel: system 00:00: [io 0x0680-0x069f] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:00: [io 0x0400-0x047f] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:00: [io 0x0500-0x05fe] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x9152a000-0x9152afff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x9151c000-0x9151cfff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x9151a000-0x9151afff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x91518000-0x91518fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x91527000-0x91527fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x91525000-0x91525fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x91523000-0x91523fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x91521000-0x91521fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x9151f000-0x9151ffff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:02: [mem 0x9151d000-0x9151dfff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xe0000000-0xefffffff] could not be reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfea00000-0xfeafffff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfed01000-0xfed01fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfed03000-0xfed03fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfed06000-0xfed06fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfed08000-0xfed09fff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfed80000-0xfedbffff] could not be reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfed1c000-0xfed1cfff] has been reserved Aug 29 11:07:03 volumio1 kernel: system 00:03: [mem 0xfee00000-0xfeefffff] could not be reserved Aug 29 11:07:03 volumio1 kernel: pnp: PnP ACPI: found 5 devices Aug 29 11:07:03 volumio1 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 29 11:07:03 volumio1 kernel: NET: Registered PF_INET protocol family Aug 29 11:07:03 volumio1 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 29 11:07:03 volumio1 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Aug 29 11:07:03 volumio1 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 29 11:07:03 volumio1 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Aug 29 11:07:03 volumio1 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Aug 29 11:07:03 volumio1 kernel: TCP: Hash tables configured (established 16384 bind 16384) Aug 29 11:07:03 volumio1 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 29 11:07:03 volumio1 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 29 11:07:03 volumio1 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: PCI bridge to [bus 01] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: bridge window [io 0xe000-0xefff] Aug 29 11:07:03 volumio1 kernel: pci 0000:00:1c.0: bridge window [mem 0x91400000-0x914fffff] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 4 [io 0x0070-0x0077] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0x006f window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 6 [io 0x0078-0x0cf7 window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 7 [io 0x0d00-0xffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 8 [mem 0x000a0000-0x000fffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 9 [mem 0x20000000-0x201fffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 10 [mem 0x7cf00000-0x7eefffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:00: resource 11 [mem 0x80000000-0xdfffffff window] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:01: resource 0 [io 0xe000-0xefff] Aug 29 11:07:03 volumio1 kernel: pci_bus 0000:01: resource 1 [mem 0x91400000-0x914fffff] Aug 29 11:07:03 volumio1 kernel: PCI: CLS 64 bytes, default 64 Aug 29 11:07:03 volumio1 kernel: Trying to unpack rootfs image as initramfs... Aug 29 11:07:03 volumio1 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x14c1baf3789, max_idle_ns: 440795266465 ns Aug 29 11:07:03 volumio1 kernel: clocksource: Switched to clocksource tsc Aug 29 11:07:03 volumio1 kernel: Initialise system trusted keyrings Aug 29 11:07:03 volumio1 kernel: workingset: timestamp_bits=46 max_order=19 bucket_order=0 Aug 29 11:07:03 volumio1 kernel: zbud: loaded Aug 29 11:07:03 volumio1 kernel: Key type asymmetric registered Aug 29 11:07:03 volumio1 kernel: Asymmetric key parser 'x509' registered Aug 29 11:07:03 volumio1 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Aug 29 11:07:03 volumio1 kernel: io scheduler mq-deadline registered Aug 29 11:07:03 volumio1 kernel: io scheduler kyber registered Aug 29 11:07:03 volumio1 kernel: pcieport 0000:00:1c.0: PME: Signaling with IRQ 117 Aug 29 11:07:03 volumio1 kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Aug 29 11:07:03 volumio1 kernel: ACPI: AC: AC Adapter [ADP1] (on-line) Aug 29 11:07:03 volumio1 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 29 11:07:03 volumio1 kernel: ACPI: button: Power Button [PWRB] Aug 29 11:07:03 volumio1 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1 Aug 29 11:07:03 volumio1 kernel: ACPI: button: Power Button [PWRF] Aug 29 11:07:03 volumio1 kernel: thermal LNXTHERM:00: registered as thermal_zone0 Aug 29 11:07:03 volumio1 kernel: ACPI: thermal: Thermal Zone [TZ00] (0 C) Aug 29 11:07:03 volumio1 kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Aug 29 11:07:03 volumio1 kernel: 00:01: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 29 11:07:03 volumio1 kernel: hpet: number irqs doesn't agree with number of timers Aug 29 11:07:03 volumio1 kernel: Linux agpgart interface v0.103 Aug 29 11:07:03 volumio1 kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Aug 29 11:07:03 volumio1 kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 Aug 29 11:07:03 volumio1 kernel: xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x0000000081509810 Aug 29 11:07:03 volumio1 kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller Aug 29 11:07:03 volumio1 kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2 Aug 29 11:07:03 volumio1 kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.0 SuperSpeed Aug 29 11:07:03 volumio1 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Aug 29 11:07:03 volumio1 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 29 11:07:03 volumio1 kernel: usb usb1: Product: xHCI Host Controller Aug 29 11:07:03 volumio1 kernel: usb usb1: Manufacturer: Linux 6.6.32-volumio xhci-hcd Aug 29 11:07:03 volumio1 kernel: usb usb1: SerialNumber: 0000:00:14.0 Aug 29 11:07:03 volumio1 kernel: hub 1-0:1.0: USB hub found Aug 29 11:07:03 volumio1 kernel: hub 1-0:1.0: 7 ports detected Aug 29 11:07:03 volumio1 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Aug 29 11:07:03 volumio1 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 29 11:07:03 volumio1 kernel: usb usb2: Product: xHCI Host Controller Aug 29 11:07:03 volumio1 kernel: usb usb2: Manufacturer: Linux 6.6.32-volumio xhci-hcd Aug 29 11:07:03 volumio1 kernel: usb usb2: SerialNumber: 0000:00:14.0 Aug 29 11:07:03 volumio1 kernel: hub 2-0:1.0: USB hub found Aug 29 11:07:03 volumio1 kernel: hub 2-0:1.0: 6 ports detected Aug 29 11:07:03 volumio1 kernel: i8042: PNP: No PS/2 controller found. Aug 29 11:07:03 volumio1 kernel: mousedev: PS/2 mouse device common for all mice Aug 29 11:07:03 volumio1 kernel: rtc_cmos 00:04: registered as rtc0 Aug 29 11:07:03 volumio1 kernel: rtc_cmos 00:04: setting system clock to 2024-08-29T09:06:43 UTC (1724922403) Aug 29 11:07:03 volumio1 kernel: rtc_cmos 00:04: alarms up to one month, y3k, 242 bytes nvram Aug 29 11:07:03 volumio1 kernel: i2c_dev: i2c /dev entries driver Aug 29 11:07:03 volumio1 kernel: platform eisa.0: Probing EISA bus 0 Aug 29 11:07:03 volumio1 kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Aug 29 11:07:03 volumio1 kernel: intel_pstate: Intel P-state driver initializing Aug 29 11:07:03 volumio1 kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 29 11:07:03 volumio1 kernel: NET: Registered PF_INET6 protocol family Aug 29 11:07:03 volumio1 kernel: Segment Routing with IPv6 Aug 29 11:07:03 volumio1 kernel: In-situ OAM (IOAM) with IPv6 Aug 29 11:07:03 volumio1 kernel: mip6: Mobile IPv6 Aug 29 11:07:03 volumio1 kernel: NET: Registered PF_PACKET protocol family Aug 29 11:07:03 volumio1 kernel: mpls_gso: MPLS GSO support Aug 29 11:07:03 volumio1 kernel: microcode: Microcode Update Driver: v2.2. Aug 29 11:07:03 volumio1 kernel: IPI shorthand broadcast: enabled Aug 29 11:07:03 volumio1 kernel: sched_clock: Marking stable (1184015590, 3542338)->(1343792200, -156234272) Aug 29 11:07:03 volumio1 kernel: Loading compiled-in X.509 certificates Aug 29 11:07:03 volumio1 kernel: usb 1-3: new high-speed USB device number 2 using xhci_hcd Aug 29 11:07:03 volumio1 kernel: usb 1-3: New USB device found, idVendor=2357, idProduct=0138, bcdDevice= 2.10 Aug 29 11:07:03 volumio1 kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 29 11:07:03 volumio1 kernel: usb 1-3: Product: 802.11ac NIC Aug 29 11:07:03 volumio1 kernel: usb 1-3: Manufacturer: Realtek Aug 29 11:07:03 volumio1 kernel: usb 1-3: SerialNumber: 123456 Aug 29 11:07:03 volumio1 kernel: i2c_designware 808622C1:05: I2C bus managed by PUNIT Aug 29 11:07:03 volumio1 kernel: clk: Disabling unused clocks Aug 29 11:07:03 volumio1 kernel: Freeing initrd memory: 68108K Aug 29 11:07:03 volumio1 kernel: Freeing unused kernel image (initmem) memory: 2988K Aug 29 11:07:03 volumio1 kernel: Write protecting the kernel read-only data: 20480k Aug 29 11:07:03 volumio1 kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K Aug 29 11:07:03 volumio1 kernel: Run /init as init process Aug 29 11:07:03 volumio1 kernel: with arguments: Aug 29 11:07:03 volumio1 kernel: /init Aug 29 11:07:03 volumio1 kernel: splash Aug 29 11:07:03 volumio1 kernel: with environment: Aug 29 11:07:03 volumio1 kernel: HOME=/ Aug 29 11:07:03 volumio1 kernel: TERM=linux Aug 29 11:07:03 volumio1 kernel: BOOT_IMAGE=/vmlinuz Aug 29 11:07:03 volumio1 kernel: biosdevname=0 Aug 29 11:07:03 volumio1 kernel: imgpart=UUID=01702c47-0ef9-4ed1-a87a-8ab83b214a17 Aug 29 11:07:03 volumio1 kernel: bootpart=UUID=5102-F1E1 Aug 29 11:07:03 volumio1 kernel: datapart=UUID=7d68ad65-41f4-48d1-8886-6693f8079a92 Aug 29 11:07:03 volumio1 kernel: hwdevice=x86 Aug 29 11:07:03 volumio1 kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Aug 29 11:07:03 volumio1 kernel: imgfile=/volumio_current.sqsh Aug 29 11:07:03 volumio1 kernel: use_kmsg=no Aug 29 11:07:03 volumio1 kernel: sdhci: Secure Digital Host Controller Interface driver Aug 29 11:07:03 volumio1 kernel: sdhci: Copyright(c) Pierre Ossman Aug 29 11:07:03 volumio1 kernel: mmc0: SDHCI controller on ACPI [80860F14:00] using ADMA Aug 29 11:07:03 volumio1 kernel: wmi_bus wmi_bus-PNP0C14:00: WQBC data block query control method not found Aug 29 11:07:03 volumio1 kernel: dw_dmac INTL9C60:00: DesignWare DMA Controller, 8 channels Aug 29 11:07:03 volumio1 kernel: dw_dmac INTL9C60:01: DesignWare DMA Controller, 8 channels Aug 29 11:07:03 volumio1 kernel: mmc0: new HS200 MMC card at address 0001 Aug 29 11:07:03 volumio1 kernel: ACPI: bus type drm_connector registered Aug 29 11:07:03 volumio1 kernel: sdhci-pci 0000:00:11.0: SDHCI controller found [8086:2295] (rev 36) Aug 29 11:07:03 volumio1 kernel: mmc1: SDHCI controller on PCI [0000:00:11.0] using ADMA Aug 29 11:07:03 volumio1 kernel: r8169 0000:01:00.0 eth0: RTL8168h/8111h, e4:54:e8:18:d1:c3, XID 541, IRQ 126 Aug 29 11:07:03 volumio1 kernel: r8169 0000:01:00.0 eth0: jumbo features [frames: 9194 bytes, tx checksumming: ko] Aug 29 11:07:03 volumio1 kernel: mmc1: Failed to initialize a non-removable card Aug 29 11:07:03 volumio1 kernel: [34B blob data] Aug 29 11:07:03 volumio1 kernel: mmcblk0: p1 p2 p3 Aug 29 11:07:03 volumio1 kernel: [39B blob data] Aug 29 11:07:03 volumio1 kernel: [39B blob data] Aug 29 11:07:03 volumio1 kernel: [55B blob data] Aug 29 11:07:03 volumio1 kernel: i915 0000:00:02.0: vgaarb: deactivate vga console Aug 29 11:07:03 volumio1 kernel: [drm] Initialized i915 1.6.0 20201103 for 0000:00:02.0 on minor 0 Aug 29 11:07:03 volumio1 kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Aug 29 11:07:03 volumio1 kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input2 Aug 29 11:07:03 volumio1 kernel: i915 0000:00:02.0: [drm] HDaudio controller not detected, using LPE audio instead Aug 29 11:07:03 volumio1 kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem Aug 29 11:07:03 volumio1 kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Aug 29 11:07:03 volumio1 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 29 11:07:03 volumio1 kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Aug 29 11:07:03 volumio1 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 29 11:07:03 volumio1 kernel: usbcore: registered new interface driver usbhid Aug 29 11:07:03 volumio1 kernel: usbhid: USB HID core driver Aug 29 11:07:03 volumio1 kernel: fuse: init (API version 7.39) Aug 29 11:07:03 volumio1 kernel: usbcore: registered new interface driver usb-storage Aug 29 11:07:03 volumio1 kernel: usbcore: registered new interface driver uas Aug 29 11:07:03 volumio1 kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Aug 29 11:07:03 volumio1 kernel: [drm] radeon kernel modesetting enabled. Aug 29 11:07:03 volumio1 kernel: PDC20230-C/20630 VLB ATA controller detected. Aug 29 11:07:03 volumio1 kernel: scsi host0: pata_legacy Aug 29 11:07:03 volumio1 kernel: ata1: PATA max PIO2 cmd 0x1f0 ctl 0x3f6 irq 14 Aug 29 11:07:03 volumio1 kernel: scsi host0: pata_legacy Aug 29 11:07:03 volumio1 kernel: ata2: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 Aug 29 11:07:03 volumio1 kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 29 11:07:03 volumio1 kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 29 11:07:03 volumio1 kernel: EXT4-fs (mmcblk0p2): mounted filesystem 01702c47-0ef9-4ed1-a87a-8ab83b214a17 r/w with ordered data mode. Quota mode: none. Aug 29 11:07:03 volumio1 kernel: loop: module loaded Aug 29 11:07:03 volumio1 kernel: block device autoloading is deprecated and will be removed. Aug 29 11:07:03 volumio1 kernel: loop0: detected capacity change from 0 to 1724976 Aug 29 11:07:03 volumio1 kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 29 11:07:03 volumio1 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 7d68ad65-41f4-48d1-8886-6693f8079a92 r/w with ordered data mode. Quota mode: none. Aug 29 11:07:03 volumio1 systemd[1]: Inserted module 'autofs4' Aug 29 11:07:03 volumio1 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) Aug 29 11:07:03 volumio1 systemd[1]: Detected architecture x86-64. Aug 29 11:07:03 volumio1 systemd[1]: Set hostname to . Aug 29 11:07:03 volumio1 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. Aug 29 11:07:03 volumio1 systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Aug 29 11:07:03 volumio1 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. Aug 29 11:07:03 volumio1 systemd[1]: Listening on RPCbind Server Activation Socket. Aug 29 11:07:03 volumio1 systemd[1]: Listening on Journal Socket. Aug 29 11:07:03 volumio1 systemd[1]: Starting Remount Root and Kernel File Systems... Aug 29 11:07:03 volumio1 systemd[1]: Started dynamicswap service. Aug 29 11:07:03 volumio1 kernel: RPC: Registered named UNIX socket transport module. Aug 29 11:07:03 volumio1 kernel: RPC: Registered udp transport module. Aug 29 11:07:03 volumio1 kernel: RPC: Registered tcp transport module. Aug 29 11:07:03 volumio1 kernel: RPC: Registered tcp-with-tls transport module. Aug 29 11:07:03 volumio1 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 29 11:07:03 volumio1 systemd-journald[490]: Journal started Aug 29 11:07:03 volumio1 systemd-journald[490]: Runtime journal (/run/log/journal/8c0482b02cb11fe9ee24a8e2669e9b72) is 3.7M, max 30.0M, 26.2M free. Aug 29 11:07:03 volumio1 fake-hwclock[485]: Current system time: 2024-08-29 09:07:03 Aug 29 11:07:03 volumio1 fake-hwclock[485]: fake-hwclock saved clock information is in the past: 2024-08-28 15:03:40 Aug 29 11:07:03 volumio1 fake-hwclock[485]: To set system time to this saved clock anyway, use "force" Aug 29 11:07:03 volumio1 systemd[1]: Started Apply Kernel Variables. Aug 29 11:07:03 volumio1 systemd[1]: dynamicswap.service: Succeeded. Aug 29 11:07:03 volumio1 systemd[1]: Started Create System Users. Aug 29 11:07:03 volumio1 systemd[1]: Starting Create Static Device Nodes in /dev... Aug 29 11:07:03 volumio1 systemd[1]: Started Create Static Device Nodes in /dev. Aug 29 11:07:03 volumio1 systemd[1]: Reached target Local File Systems (Pre). Aug 29 11:07:03 volumio1 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 29 11:07:03 volumio1 systemd[1]: Mounting /var/log... Aug 29 11:07:03 volumio1 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 29 11:07:03 volumio1 systemd[1]: Mounting /tmp... Aug 29 11:07:03 volumio1 systemd[1]: Mounting /var/spool/cups... Aug 29 11:07:03 volumio1 systemd[1]: Starting udev Kernel Device Manager... Aug 29 11:07:03 volumio1 systemd[1]: Mounted /var/log. Aug 29 11:07:03 volumio1 systemd[1]: Mounted /tmp. Aug 29 11:07:03 volumio1 systemd[1]: Mounted /var/spool/cups. Aug 29 11:07:03 volumio1 systemd[1]: Mounting /var/spool/cups/tmp... Aug 29 11:07:03 volumio1 systemd[1]: Starting Flush Journal to Persistent Storage... Aug 29 11:07:03 volumio1 systemd[1]: Mounted /var/spool/cups/tmp. Aug 29 11:07:03 volumio1 systemd-udevd[515]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 29 11:07:03 volumio1 systemd-journald[490]: Runtime journal (/run/log/journal/8c0482b02cb11fe9ee24a8e2669e9b72) is 3.7M, max 30.0M, 26.2M free. Aug 29 11:07:03 volumio1 systemd[1]: Started Flush Journal to Persistent Storage. Aug 29 11:07:03 volumio1 systemd[1]: Started udev Kernel Device Manager. Aug 29 11:07:03 volumio1 systemd[1]: Started udev Coldplug all Devices. Aug 29 11:07:03 volumio1 systemd[1]: Starting Show Plymouth Boot Screen... Aug 29 11:07:03 volumio1 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 29 11:07:04 volumio1 systemd[1]: plymouth-start.service: Succeeded. Aug 29 11:07:04 volumio1 systemd[1]: Started Show Plymouth Boot Screen. Aug 29 11:07:04 volumio1 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 29 11:07:04 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 29 11:07:04 volumio1 systemd[1]: Reached target Local Encrypted Volumes. Aug 29 11:07:04 volumio1 systemd[1]: Reached target Paths. Aug 29 11:07:04 volumio1 kernel: Consider using thermal netlink events interface Aug 29 11:07:04 volumio1 kernel: gpio gpiochip4: (INT0002 Virtual GPIO): not an immutable chip, please consider fixing it! Aug 29 11:07:04 volumio1 kernel: 8086228A:00: ttyS4 at MMIO 0x91519000 (irq = 39, base_baud = 2764800) is a 16550A Aug 29 11:07:04 volumio1 kernel: input: Intel HDMI/DP LPE Audio HDMI/DP,pcm=0 as /devices/pci0000:00/0000:00:02.0/hdmi-lpe-audio/sound/card0/input3 Aug 29 11:07:04 volumio1 kernel: input: Intel HDMI/DP LPE Audio HDMI/DP,pcm=1 as /devices/pci0000:00/0000:00:02.0/hdmi-lpe-audio/sound/card0/input4 Aug 29 11:07:04 volumio1 kernel: input: Intel HDMI/DP LPE Audio HDMI/DP,pcm=2 as /devices/pci0000:00/0000:00:02.0/hdmi-lpe-audio/sound/card0/input5 Aug 29 11:07:04 volumio1 systemd-udevd[530]: Using default interface naming scheme 'v240'. Aug 29 11:07:04 volumio1 systemd-udevd[530]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 29 11:07:04 volumio1 kernel: input: chtdc_ti_pwrbtn as /devices/pci0000:00/808622C1:05/i2c-4/i2c-INT33F5:00/chtdc_ti_pwrbtn/input/input6 Aug 29 11:07:04 volumio1 kernel: rt5670 i2c-10EC5672:00: quirk GPIO1 is IRQ Aug 29 11:07:04 volumio1 kernel: rt5670 i2c-10EC5672:00: quirk DMIC enabled Aug 29 11:07:04 volumio1 kernel: rt5670 i2c-10EC5672:00: quirk DMIC1 on IN2P pin Aug 29 11:07:04 volumio1 kernel: rt5670 i2c-10EC5672:00: quirk JD mode 1 Aug 29 11:07:04 volumio1 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 29 11:07:04 volumio1 kernel: intel_sst_acpi 808622A8:00: LPE base: 0x91000000 size:0x200000 Aug 29 11:07:04 volumio1 kernel: intel_sst_acpi 808622A8:00: IRAM base: 0x910c0000 Aug 29 11:07:04 volumio1 kernel: intel_sst_acpi 808622A8:00: DRAM base: 0x91100000 Aug 29 11:07:04 volumio1 kernel: intel_sst_acpi 808622A8:00: SHIM base: 0x91140000 Aug 29 11:07:04 volumio1 kernel: intel_sst_acpi 808622A8:00: Mailbox base: 0x91144000 Aug 29 11:07:04 volumio1 kernel: intel_sst_acpi 808622A8:00: DDR base: 0x20000000 Aug 29 11:07:04 volumio1 kernel: intel_sst_acpi 808622A8:00: Got drv data max stream 25 Aug 29 11:07:04 volumio1 kernel: proc_thermal 0000:00:0b.0: enabling device (0000 -> 0002) Aug 29 11:07:04 volumio1 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 29 11:07:04 volumio1 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Aug 29 11:07:04 volumio1 kernel: pstore: Using crash dump compression: deflate Aug 29 11:07:04 volumio1 kernel: pstore: Registered efi_pstore as persistent store backend Aug 29 11:07:04 volumio1 kernel: dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.4) Aug 29 11:07:04 volumio1 kernel: input: Dell WMI hotkeys as /devices/platform/PNP0C14:00/wmi_bus/wmi_bus-PNP0C14:00/9DBB5994-A997-11DA-B012-B622A1EF5492/input/input7 Aug 29 11:07:04 volumio1 systemd-udevd[525]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:04 volumio1 systemd-udevd[528]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:04 volumio1 systemd[1]: Found device /dev/disk/by-uuid/5102-F1E1. Aug 29 11:07:04 volumio1 systemd-udevd[520]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:04 volumio1 systemd-udevd[520]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 29 11:07:05 volumio1 kernel: cryptd: max_cpu_qlen set to 1000 Aug 29 11:07:05 volumio1 kernel: SSE version of gcm_enc/dec engaged. Aug 29 11:07:05 volumio1 kernel: input: cht-bsw-rt5672 Headset as /devices/pci0000:00/808622A8:00/cht-bsw-rt5672/sound/card1/input8 Aug 29 11:07:05 volumio1 systemd-udevd[524]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:05 volumio1 systemd-udevd[520]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:05 volumio1 systemd-udevd[525]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:05 volumio1 systemd-udevd[522]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:05 volumio1 systemd-udevd[518]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:05 volumio1 systemd-udevd[519]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 29 11:07:05 volumio1 kernel: alsactl[643]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Aug 29 11:07:05 volumio1 kernel: alsactl[659]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Aug 29 11:07:05 volumio1 kernel: usbcore: registered new interface driver rtl88x2bu Aug 29 11:07:05 volumio1 systemd-udevd[520]: Using default interface naming scheme 'v240'. Aug 29 11:07:05 volumio1 systemd-udevd[520]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 29 11:07:06 volumio1 systemd[1]: Started Helper to synchronize boot up for ifupdown. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in 802.11ac_NIC being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Starting Show Plymouth Boot Screen... Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 29 11:07:06 volumio1 systemd[1]: Starting File System Check on /dev/disk/by-uuid/5102-F1E1... Aug 29 11:07:06 volumio1 systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 29 11:07:06 volumio1 systemd[1]: plymouth-start.service: Succeeded. Aug 29 11:07:06 volumio1 systemd[1]: Started Show Plymouth Boot Screen. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Started File System Check Daemon to report status. Aug 29 11:07:06 volumio1 systemd[1]: Started Load/Save RF Kill Switch Status. Aug 29 11:07:06 volumio1 systemd-fsck[702]: fsck.fat 4.1 (2017-01-24) Aug 29 11:07:06 volumio1 systemd-fsck[702]: There are differences between boot sector and its backup. Aug 29 11:07:06 volumio1 systemd-fsck[702]: This is mostly harmless. Differences: (offset:original/backup) Aug 29 11:07:06 volumio1 systemd-fsck[702]: 3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 282:2d/00, 283:15/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: , 508:18/00, 509:37/00 Aug 29 11:07:06 volumio1 systemd-fsck[702]: Not automatically fixing this. Aug 29 11:07:06 volumio1 systemd-fsck[702]: /dev/mmcblk0p1: 18 files, 159613/344788 clusters Aug 29 11:07:06 volumio1 systemd[1]: Started File System Check on /dev/disk/by-uuid/5102-F1E1. Aug 29 11:07:06 volumio1 systemd[1]: Mounting /boot... Aug 29 11:07:06 volumio1 kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 29 11:07:06 volumio1 systemd[1]: Mounted /boot. Aug 29 11:07:06 volumio1 systemd[1]: Reached target Local File Systems. Aug 29 11:07:06 volumio1 systemd[1]: Starting Create Volatile Files and Directories... Aug 29 11:07:06 volumio1 systemd[1]: Started ifup for eth0. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Started ifup for wlan0. Aug 29 11:07:06 volumio1 systemd[1]: Starting Preprocess NFS configuration... Aug 29 11:07:06 volumio1 systemd[1]: Starting Raise network interfaces... Aug 29 11:07:06 volumio1 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 29 11:07:06 volumio1 systemd[1]: Received SIGRTMIN+20 from PID 376 (plymouthd). Aug 29 11:07:06 volumio1 systemd[1]: plymouth-read-write.service: Succeeded. Aug 29 11:07:06 volumio1 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 29 11:07:06 volumio1 systemd[1]: nfs-config.service: Succeeded. Aug 29 11:07:06 volumio1 systemd[1]: Started Preprocess NFS configuration. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Reached target NFS client services. Aug 29 11:07:06 volumio1 systemd[1]: Started Create Volatile Files and Directories. Aug 29 11:07:06 volumio1 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Aug 29 11:07:06 volumio1 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Starting RPC bind portmap service... Aug 29 11:07:06 volumio1 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 29 11:07:06 volumio1 systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 29 11:07:06 volumio1 systemd[1]: Reached target System Initialization. Aug 29 11:07:06 volumio1 systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 29 11:07:06 volumio1 systemd[1]: Listening on triggerhappy.socket. Aug 29 11:07:06 volumio1 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 29 11:07:06 volumio1 systemd[1]: Reached target Sockets. Aug 29 11:07:06 volumio1 systemd[1]: Reached target Basic System. Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Started D-Bus System Message Bus. Aug 29 11:07:06 volumio1 systemd[1]: Started volumio-remote-updater.service. Aug 29 11:07:06 volumio1 kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 29 11:07:06 volumio1 systemd[1]: Starting WPA supplicant... Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Starting DHCP Client Daemon... Aug 29 11:07:06 volumio1 systemd[1]: Starting Login Service... Aug 29 11:07:06 volumio1 systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 29 11:07:06 volumio1 systemd[1]: Starting triggerhappy global hotkey daemon... Aug 29 11:07:06 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 29 11:07:06 volumio1 systemd[1]: Started Daily man-db regeneration. Aug 29 11:07:06 volumio1 systemd[1]: Started Manage Sound Card State (restore and store). Aug 29 11:07:06 volumio1 systemd[1]: Starting Save/Restore Sound Card State... Aug 29 11:07:06 volumio1 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 29 11:07:06 volumio1 systemd[1]: Started Volumio Log Rotation Service. Aug 29 11:07:06 volumio1 alsactl[792]: alsactl 1.1.8 daemon started Aug 29 11:07:06 volumio1 systemd[1]: Started Volumio Iptables Module. Aug 29 11:07:06 volumio1 systemd[1]: Starting Wireless Services... Aug 29 11:07:06 volumio1 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 29 11:07:06 volumio1 systemd[1]: Started Daily apt download activities. Aug 29 11:07:06 volumio1 systemd[1]: Started Daily apt upgrade and clean activities. Aug 29 11:07:06 volumio1 systemd[1]: Reached target Timers. Aug 29 11:07:06 volumio1 thd[790]: Unable to parse trigger line: Aug 29 11:07:06 volumio1 thd[790]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 29 11:07:06 volumio1 thd[790]: Unable to parse trigger line: Aug 29 11:07:06 volumio1 thd[790]: Found socket passed from systemd Aug 29 11:07:06 volumio1 systemd[1]: Starting Daily man-db regeneration... Aug 29 11:07:06 volumio1 systemd-logind[789]: New seat seat0. Aug 29 11:07:06 volumio1 systemd-logind[789]: Watching system buttons on /dev/input/event1 (Power Button) Aug 29 11:07:06 volumio1 systemd-logind[789]: Watching system buttons on /dev/input/event0 (Power Button) Aug 29 11:07:07 volumio1 avahi-daemon[798]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Aug 29 11:07:07 volumio1 avahi-daemon[798]: Successfully dropped root privileges. Aug 29 11:07:07 volumio1 avahi-daemon[798]: avahi-daemon 0.7 starting up. Aug 29 11:07:07 volumio1 systemd-logind[789]: Watching system buttons on /dev/input/event6 (chtdc_ti_pwrbtn) Aug 29 11:07:07 volumio1 systemd[1]: Started triggerhappy global hotkey daemon. Aug 29 11:07:07 volumio1 wpa_supplicant[787]: Successfully initialized wpa_supplicant Aug 29 11:07:07 volumio1 systemd[1]: Started Save/Restore Sound Card State. Aug 29 11:07:07 volumio1 avahi-daemon[798]: Successfully called chroot(). Aug 29 11:07:07 volumio1 avahi-daemon[798]: Successfully dropped remaining capabilities. Aug 29 11:07:07 volumio1 avahi-daemon[798]: Loading service file /services/volumio.service. Aug 29 11:07:07 volumio1 systemd[1]: Started Login Service. Aug 29 11:07:07 volumio1 systemd[1]: Started WPA supplicant. Aug 29 11:07:07 volumio1 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 29 11:07:07 volumio1 systemd[1]: Reached target Sound Card. Aug 29 11:07:07 volumio1 ifup[717]: ifup: waiting for lock on /run/network/ifstate.wlan0 Aug 29 11:07:07 volumio1 systemd[1]: man-db.service: Succeeded. Aug 29 11:07:07 volumio1 systemd[1]: Started Daily man-db regeneration. Aug 29 11:07:07 volumio1 volumio-remote-updater[786]: Error: No active session Aug 29 11:07:07 volumio1 haveged[765]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Aug 29 11:07:07 volumio1 haveged[765]: haveged: cpu: (L4 VC); data: 24K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 31410/52825 Aug 29 11:07:07 volumio1 haveged[765]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00075 Aug 29 11:07:07 volumio1 haveged[765]: haveged: fills: 0, generated: 0 Aug 29 11:07:07 volumio1 volumio[791]: Could not open config: /tmp/upmpdcli.conf Aug 29 11:07:07 volumio1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 11:07:07 volumio1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 11:07:07 volumio1 kernel: start_addr=(0x20000), end_addr=(0x40000), buffer_size=(0x20000), smp_number_max=(16384) Aug 29 11:07:07 volumio1 systemd[1]: Started RPC bind portmap service. Aug 29 11:07:07 volumio1 avahi-daemon[798]: Network interface enumeration completed. Aug 29 11:07:07 volumio1 systemd[1]: Reached target Remote File Systems (Pre). Aug 29 11:07:07 volumio1 systemd[1]: Reached target Remote File Systems. Aug 29 11:07:07 volumio1 avahi-daemon[798]: Server startup complete. Host name is volumio1.local. Local service cookie is 2347655008. Aug 29 11:07:07 volumio1 avahi-daemon[798]: Service "Volumio1" (/services/volumio.service) successfully established. Aug 29 11:07:07 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:07] [info] asio async_connect error: system:111 (Connection refused) Aug 29 11:07:07 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 29 11:07:07 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:07] [error] handle_connect error: Underlying Transport Error Aug 29 11:07:07 volumio1 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 29 11:07:07 volumio1 systemd[1]: Reached target RPC Port Mapper. Aug 29 11:07:07 volumio1 sh[712]: wlan0=wlan0 Aug 29 11:07:07 volumio1 systemd[1]: Started Raise network interfaces. Aug 29 11:07:07 volumio1 systemd[1]: Reached target Network. Aug 29 11:07:07 volumio1 kernel: Generic FE-GE Realtek PHY r8169-0-100:00: attached PHY driver (mii_bus:phy_addr=r8169-0-100:00, irq=MAC) Aug 29 11:07:07 volumio1 dhcpcd[788]: no interfaces have a carrier Aug 29 11:07:07 volumio1 dhcpcd[788]: no interfaces have a carrier Aug 29 11:07:07 volumio1 dhcpcd[788]: forked to background, child pid 916 Aug 29 11:07:07 volumio1 systemd[1]: Starting Network Time Service... Aug 29 11:07:07 volumio1 dhcpcd[788]: forked to background, child pid 916 Aug 29 11:07:07 volumio1 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 29 11:07:07 volumio1 systemd[1]: Reached target Network is Online. Aug 29 11:07:07 volumio1 systemd[1]: Starting Daily apt download activities... Aug 29 11:07:07 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 11:07:07 volumio1 systemd[1]: Starting LSB: Brings up/down network automatically... Aug 29 11:07:07 volumio1 systemd[1]: Starting /etc/rc.local Compatibility... Aug 29 11:07:07 volumio1 systemd[1]: Starting Samba NMB Daemon... Aug 29 11:07:07 volumio1 systemd[1]: Starting Permit User Sessions... Aug 29 11:07:07 volumio1 systemd[1]: Started DHCP Client Daemon. Aug 29 11:07:07 volumio1 systemd[1]: Started /etc/rc.local Compatibility. Aug 29 11:07:07 volumio1 systemd[1]: iptables.service: Succeeded. Aug 29 11:07:07 volumio1 systemd[1]: Started Permit User Sessions. Aug 29 11:07:07 volumio1 systemd[1]: Starting Hold until boot process finishes up... Aug 29 11:07:07 volumio1 systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 29 11:07:07 volumio1 systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 29 11:07:07 volumio1 systemd[1]: Started Hold until boot process finishes up. Aug 29 11:07:07 volumio1 systemd[1]: plymouth-quit.service: Succeeded. Aug 29 11:07:07 volumio1 loadcpufreq[832]: Loading cpufreq kernel modules...done (acpi-cpufreq). Aug 29 11:07:07 volumio1 systemd[1]: Started Terminate Plymouth Boot Screen. Aug 29 11:07:07 volumio1 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 29 11:07:07 volumio1 sh[711]: eth0: waiting for carrier Aug 29 11:07:07 volumio1 kernel: r8169 0000:01:00.0 eth0: Link is Down Aug 29 11:07:07 volumio1 dhcpcd[760]: eth0: waiting for carrier Aug 29 11:07:07 volumio1 systemd[1]: Received SIGRTMIN+21 from PID 376 (n/a). Aug 29 11:07:07 volumio1 sh[711]: eth0: carrier acquired Aug 29 11:07:07 volumio1 dhcpcd[760]: eth0: carrier acquired Aug 29 11:07:07 volumio1 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 29 11:07:07 volumio1 dhcpcd[916]: eth0: waiting for carrier Aug 29 11:07:07 volumio1 dhcpcd[916]: wlan0: waiting for carrier Aug 29 11:07:07 volumio1 dhcpcd[916]: eth0: carrier acquired Aug 29 11:07:08 volumio1 systemd[1]: Started Getty on tty1. Aug 29 11:07:08 volumio1 systemd[1]: Reached target Login Prompts. Aug 29 11:07:08 volumio1 sh[711]: DUID 00:04:4c:4c:45:44:00:30:43:10:80:51:b5:c0:4f:4c:54:32 Aug 29 11:07:08 volumio1 sh[711]: eth0: IAID e8:18:d1:c3 Aug 29 11:07:08 volumio1 sh[711]: eth0: adding address fe80::e654:e8ff:fe18:d1c3 Aug 29 11:07:08 volumio1 sh[711]: ipv6_addaddr1: Permission denied Aug 29 11:07:08 volumio1 sh[711]: eth0: carrier lost Aug 29 11:07:08 volumio1 dhcpcd[760]: DUID 00:04:4c:4c:45:44:00:30:43:10:80:51:b5:c0:4f:4c:54:32 Aug 29 11:07:08 volumio1 dhcpcd[760]: eth0: IAID e8:18:d1:c3 Aug 29 11:07:08 volumio1 dhcpcd[760]: eth0: adding address fe80::e654:e8ff:fe18:d1c3 Aug 29 11:07:08 volumio1 dhcpcd[760]: ipv6_addaddr1: Permission denied Aug 29 11:07:08 volumio1 dhcpcd[760]: eth0: carrier lost Aug 29 11:07:08 volumio1 dhcpcd[916]: DUID 00:04:4c:4c:45:44:00:30:43:10:80:51:b5:c0:4f:4c:54:32 Aug 29 11:07:08 volumio1 dhcpcd[916]: eth0: IAID e8:18:d1:c3 Aug 29 11:07:08 volumio1 dhcpcd[916]: eth0: adding address fe80::e654:e8ff:fe18:d1c3 Aug 29 11:07:08 volumio1 dhcpcd[916]: ipv6_addaddr1: Permission denied Aug 29 11:07:08 volumio1 dhcpcd[916]: eth0: carrier lost Aug 29 11:07:08 volumio1 ifplugd(eth0)[987]: ifplugd 0.28 initializing. Aug 29 11:07:08 volumio1 ifplugd(eth0)[987]: Using interface eth0/E4:54:E8:18:D1:C3 with driver (version: 6.6.32-volumio) Aug 29 11:07:08 volumio1 ifplugd(eth0)[987]: Using detection mode: SIOCETHTOOL Aug 29 11:07:08 volumio1 ifplugd(eth0)[987]: Initialization complete, link beat not detected. Aug 29 11:07:08 volumio1 ntpd[910]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 29 11:07:08 volumio1 ntpd[910]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 29 11:07:08 volumio1 systemd[1]: Started Network Time Service. Aug 29 11:07:08 volumio1 ntpd[997]: proto: precision = 0.237 usec (-22) Aug 29 11:07:08 volumio1 ntpd[997]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 29 11:07:08 volumio1 ntpd[997]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 29 11:07:08 volumio1 ntpd[997]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 611 days ago Aug 29 11:07:08 volumio1 ntpd[997]: Listen and drop on 0 v6wildcard [::]:123 Aug 29 11:07:08 volumio1 ntpd[997]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 29 11:07:08 volumio1 ntpd[997]: Listen normally on 2 lo 127.0.0.1:123 Aug 29 11:07:08 volumio1 ntpd[997]: Listening on routing socket on fd #19 for interface updates Aug 29 11:07:08 volumio1 ntpd[997]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 29 11:07:08 volumio1 ntpd[997]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 29 11:07:08 volumio1 cpufrequtils[965]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Aug 29 11:07:08 volumio1 systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 29 11:07:08 volumio1 ifplugd[922]: Network Interface Plugging Daemon...start eth0...done. Aug 29 11:07:08 volumio1 systemd[1]: Started LSB: Brings up/down network automatically. Aug 29 11:07:08 volumio1 wireless.js[797]: Cleaning previous... Aug 29 11:07:08 volumio1 nmbd[932]: [2024/08/29 11:07:08.596338, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 29 11:07:08 volumio1 nmbd[932]: started asyncdns process 1032 Aug 29 11:07:08 volumio1 nmbd[932]: [2024/08/29 11:07:08.598402, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 29 11:07:08 volumio1 nmbd[932]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 29 11:07:08 volumio1 nmbd[932]: [2024/08/29 11:07:08.598551, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 29 11:07:08 volumio1 nmbd[932]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 29 11:07:08 volumio1 sudo[1041]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 29 11:07:08 volumio1 sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:08 volumio1 sudo[1041]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:08 volumio1 sudo[1052]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 29 11:07:08 volumio1 sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:08 volumio1 sudo[1052]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:08 volumio1 wireless.js[797]: Stopped aP Aug 29 11:07:08 volumio1 sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 29 11:07:08 volumio1 sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:08 volumio1 sudo[1068]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:08 volumio1 systemd[1]: apt-daily.service: Succeeded. Aug 29 11:07:08 volumio1 systemd[1]: Started Daily apt download activities. Aug 29 11:07:08 volumio1 sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 29 11:07:08 volumio1 sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:08 volumio1 systemd[1]: Starting Daily apt upgrade and clean activities... Aug 29 11:07:08 volumio1 sudo[1071]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:08 volumio1 sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 29 11:07:08 volumio1 sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:08 volumio1 sudo[1080]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:08 volumio1 sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 29 11:07:08 volumio1 sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:09 volumio1 ntpd[997]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 29 11:07:09 volumio1 systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 29 11:07:09 volumio1 systemd[1]: Started Daily apt upgrade and clean activities. Aug 29 11:07:10 volumio1 ntpd[997]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 29 11:07:11 volumio1 ntpd[997]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 29 11:07:11 volumio1 systemd[1]: systemd-rfkill.service: Succeeded. Aug 29 11:07:12 volumio1 ntpd[997]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 29 11:07:12 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:12] [info] asio async_connect error: system:111 (Connection refused) Aug 29 11:07:12 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 29 11:07:12 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:12] [error] handle_connect error: Underlying Transport Error Aug 29 11:07:12 volumio1 sudo[1083]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:12 volumio1 wireless.js[797]: Start wireless flow Aug 29 11:07:12 volumio1 wireless.js[797]: Stopped hotspot (if there).. Aug 29 11:07:12 volumio1 sudo[1133]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 29 11:07:12 volumio1 sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:12 volumio1 sudo[1133]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:12 volumio1 sudo[1135]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 29 11:07:12 volumio1 sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:12 volumio1 sudo[1135]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:12 volumio1 wireless.js[797]: DHCP IP Aug 29 11:07:12 volumio1 wpa_supplicant[1137]: Successfully initialized wpa_supplicant Aug 29 11:07:12 volumio1 wireless.js[797]: Start ap Aug 29 11:07:13 volumio1 sudo[1138]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 29 11:07:13 volumio1 sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:13 volumio1 dhcpcd[1139]: sending commands to master dhcpcd process Aug 29 11:07:13 volumio1 dhcpcd[916]: control command: /sbin/dhcpcd Aug 29 11:07:13 volumio1 sudo[1138]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:13 volumio1 wpa_supplicant[1137]: nl80211: kernel reports: Authentication algorithm number required Aug 29 11:07:14 volumio1 wireless.js[797]: trying... Aug 29 11:07:14 volumio1 sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:14 volumio1 sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:14 volumio1 sudo[1142]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:14 volumio1 kernel: start_addr=(0x20000), end_addr=(0x40000), buffer_size=(0x20000), smp_number_max=(16384) Aug 29 11:07:15 volumio1 wireless.js[797]: trying... Aug 29 11:07:15 volumio1 sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:15 volumio1 sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:15 volumio1 sudo[1145]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:16 volumio1 wireless.js[797]: trying... Aug 29 11:07:16 volumio1 sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:16 volumio1 sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:16 volumio1 sudo[1148]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:17 volumio1 wireless.js[797]: trying... Aug 29 11:07:17 volumio1 sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:17 volumio1 sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:17 volumio1 sudo[1151]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:18 volumio1 wireless.js[797]: trying... Aug 29 11:07:18 volumio1 sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:18 volumio1 sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:18 volumio1 sudo[1154]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:18 volumio1 wpa_supplicant[1140]: wlan0: Trying to associate with 34:60:f9:2a:23:1d (SSID='Wireless1E' freq=5200 MHz) Aug 29 11:07:18 volumio1 wpa_supplicant[1140]: nl80211: kernel reports: Authentication algorithm number required Aug 29 11:07:19 volumio1 wireless.js[797]: trying... Aug 29 11:07:19 volumio1 sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:19 volumio1 sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:19 volumio1 sudo[1157]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:19 volumio1 kernel: start_addr=(0x20000), end_addr=(0x40000), buffer_size=(0x20000), smp_number_max=(16384) Aug 29 11:07:19 volumio1 wpa_supplicant[1140]: wlan0: Associated with 34:60:f9:2a:23:1d Aug 29 11:07:19 volumio1 wpa_supplicant[1140]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 29 11:07:19 volumio1 wpa_supplicant[1140]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 29 11:07:19 volumio1 wpa_supplicant[1140]: wlan0: WPA: Key negotiation completed with 34:60:f9:2a:23:1d [PTK=CCMP GTK=CCMP] Aug 29 11:07:19 volumio1 wpa_supplicant[1140]: wlan0: CTRL-EVENT-CONNECTED - Connection to 34:60:f9:2a:23:1d completed [id=0 id_str=] Aug 29 11:07:19 volumio1 dhcpcd[916]: wlan0: carrier acquired Aug 29 11:07:19 volumio1 dhcpcd[916]: wlan0: IAID 30:f2:e6:5d Aug 29 11:07:19 volumio1 dhcpcd[916]: wlan0: adding address fe80::42ae:30ff:fef2:e65d Aug 29 11:07:19 volumio1 dhcpcd[916]: ipv6_addaddr1: Permission denied Aug 29 11:07:20 volumio1 wireless.js[797]: trying... Aug 29 11:07:20 volumio1 sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:20 volumio1 sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:20 volumio1 sudo[1161]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:20 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:20] [info] asio async_connect error: system:111 (Connection refused) Aug 29 11:07:20 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 29 11:07:20 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:20] [error] handle_connect error: Underlying Transport Error Aug 29 11:07:20 volumio1 dhcpcd[916]: wlan0: rebinding lease of 192.168.0.6 Aug 29 11:07:20 volumio1 dhcpcd[916]: wlan0: probing address 192.168.0.6/24 Aug 29 11:07:20 volumio1 dhcpcd[916]: wlan0: soliciting an IPv6 router Aug 29 11:07:21 volumio1 wireless.js[797]: trying... Aug 29 11:07:21 volumio1 sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:21 volumio1 sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:21 volumio1 sudo[1164]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:22 volumio1 wireless.js[797]: trying... Aug 29 11:07:22 volumio1 sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:22 volumio1 sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:22 volumio1 sudo[1167]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:23 volumio1 wireless.js[797]: trying... Aug 29 11:07:23 volumio1 sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:23 volumio1 sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:23 volumio1 sudo[1170]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:24 volumio1 wireless.js[797]: trying... Aug 29 11:07:24 volumio1 sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:24 volumio1 sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:24 volumio1 sudo[1173]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:25 volumio1 wireless.js[797]: trying... Aug 29 11:07:25 volumio1 sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:25 volumio1 sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:25 volumio1 sudo[1176]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:25 volumio1 dhcpcd[916]: wlan0: leased 192.168.0.6 for 604800 seconds Aug 29 11:07:25 volumio1 dhcpcd[916]: wlan0: adding route to 192.168.0.0/24 Aug 29 11:07:25 volumio1 avahi-daemon[798]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.6. Aug 29 11:07:25 volumio1 avahi-daemon[798]: New relevant interface wlan0.IPv4 for mDNS. Aug 29 11:07:25 volumio1 avahi-daemon[798]: Registering new address record for 192.168.0.6 on wlan0.IPv4. Aug 29 11:07:25 volumio1 dhcpcd[916]: wlan0: adding default route via 192.168.0.1 Aug 29 11:07:25 volumio1 ntpd[997]: ntpd exiting on signal 15 (Terminated) Aug 29 11:07:25 volumio1 systemd[1]: Stopping Network Time Service... Aug 29 11:07:25 volumio1 systemd[1]: ntp.service: Succeeded. Aug 29 11:07:25 volumio1 systemd[1]: Stopped Network Time Service. Aug 29 11:07:25 volumio1 systemd[1]: Starting Network Time Service... Aug 29 11:07:25 volumio1 ntpd[1214]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 29 11:07:25 volumio1 ntpd[1214]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Aug 29 11:07:25 volumio1 systemd[1]: Started Network Time Service. Aug 29 11:07:25 volumio1 ntpd[1220]: proto: precision = 0.300 usec (-22) Aug 29 11:07:25 volumio1 ntpd[1220]: Listen and drop on 0 v6wildcard [::]:123 Aug 29 11:07:25 volumio1 ntpd[1220]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 29 11:07:25 volumio1 ntpd[1220]: Listen normally on 2 lo 127.0.0.1:123 Aug 29 11:07:25 volumio1 ntpd[1220]: Listen normally on 3 wlan0 192.168.0.6:123 Aug 29 11:07:25 volumio1 ntpd[1220]: Listening on routing socket on fd #20 for interface updates Aug 29 11:07:25 volumio1 ntpd[1220]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 29 11:07:25 volumio1 ntpd[1220]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 29 11:07:26 volumio1 wireless.js[797]: trying... Aug 29 11:07:26 volumio1 sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 11:07:26 volumio1 sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:26 volumio1 sudo[1223]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:26 volumio1 wireless.js[797]: Connected to: ----Wireless1E Aug 29 11:07:26 volumio1 wireless.js[797]: ---- Aug 29 11:07:26 volumio1 sudo[1226]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 11:07:26 volumio1 sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:26 volumio1 sudo[1226]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:26 volumio1 wireless.js[797]: ... joined AP, wlan0 IPv4 is 192.168.0.6, ipV6 is undefined Aug 29 11:07:26 volumio1 wireless.js[797]: It's done! AP Aug 29 11:07:26 volumio1 iwconfig[1230]: Error for wireless request "Set Power Management" (8B2C) : Aug 29 11:07:26 volumio1 iwconfig[1230]: SET failed on device wlan0 ; Operation not permitted. Aug 29 11:07:26 volumio1 systemd[1]: Started Wireless Services. Aug 29 11:07:26 volumio1 systemd[1]: Started Volumio Backend Module. Aug 29 11:07:26 volumio1 systemd[1]: Started Intel SST and HDA soundcard init service. Aug 29 11:07:26 volumio1 systemd[1]: Started Volumio Cpu Tweaker. Aug 29 11:07:26 volumio1 systemd[1]: Started Start Volumio Kiosk. Aug 29 11:07:26 volumio1 volumio-cpu-tweak[1233]: Setting RT Priority for mpd Aug 29 11:07:26 volumio1 volumio-cpu-tweak[1233]: pid 35's current scheduling policy: SCHED_FIFO Aug 29 11:07:26 volumio1 volumio-cpu-tweak[1233]: pid 35's current scheduling priority: 99 Aug 29 11:07:26 volumio1 volumio-cpu-tweak[1233]: Setting MPD Affinity Aug 29 11:07:26 volumio1 volumio-cpu-tweak[1233]: pid 3's current affinity mask: f Aug 29 11:07:26 volumio1 volumio-cpu-tweak[1233]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 29 11:07:26 volumio1 systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 29 11:07:26 volumio1 startx[1235]: X.Org X Server 1.20.4 Aug 29 11:07:26 volumio1 startx[1235]: X Protocol Version 11, Revision 0 Aug 29 11:07:26 volumio1 startx[1235]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Aug 29 11:07:26 volumio1 startx[1235]: Current Operating System: Linux volumio1 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Aug 29 11:07:26 volumio1 startx[1235]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=01702c47-0ef9-4ed1-a87a-8ab83b214a17 bootpart=UUID=5102-F1E1 datapart=UUID=7d68ad65-41f4-48d1-8886-6693f8079a92 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 Aug 29 11:07:26 volumio1 startx[1235]: Build Date: 18 December 2021 09:05:36AM Aug 29 11:07:26 volumio1 startx[1235]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Aug 29 11:07:26 volumio1 startx[1235]: Current version of pixman: 0.36.0 Aug 29 11:07:26 volumio1 startx[1235]: Before reporting problems, check http://wiki.x.org Aug 29 11:07:26 volumio1 startx[1235]: to make sure that you have the latest version. Aug 29 11:07:26 volumio1 startx[1235]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 29 11:07:26 volumio1 startx[1235]: (++) from command line, (!!) notice, (II) informational, Aug 29 11:07:26 volumio1 startx[1235]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 29 11:07:26 volumio1 startx[1235]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Aug 29 11:07:26 2024 Aug 29 11:07:26 volumio1 startx[1235]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 29 11:07:26 volumio1 kernel: broken atomic modeset userspace detected, disabling atomic Aug 29 11:07:26 volumio1 soundcard-init.sh[1232]: cht-bsw-rt5672 detected Aug 29 11:07:26 volumio1 soundcard-init.sh[1232]: amixer: Unable to find simple control 'Headphone',0 Aug 29 11:07:26 volumio1 soundcard-init.sh[1232]: Headphones plugged in --> output to Headphones Aug 29 11:07:27 volumio1 soundcard-init.sh[1232]: ALSA lib utils.c:63:(uc_mgr_config_load) could not open configuration file /usr/share/alsa/ucm/DellInc.-Wyse3040ThinClient--0G56C0/DellInc.-Wyse3040ThinClient--0G56C0.conf Aug 29 11:07:27 volumio1 soundcard-init.sh[1232]: ALSA lib parser.c:1425:(load_master_config) error: could not parse configuration for card DellInc.-Wyse3040ThinClient--0G56C0 Aug 29 11:07:27 volumio1 soundcard-init.sh[1232]: Starting acpid.service for cht-bsw-rt5672 jack detection Aug 29 11:07:27 volumio1 systemd[1]: Listening on ACPID Listen Socket. Aug 29 11:07:27 volumio1 systemd[1]: Started ACPI event daemon. Aug 29 11:07:27 volumio1 soundcard-init.sh[1232]: cht-bsw-rt5672 initialised Aug 29 11:07:27 volumio1 acpid[1343]: starting up with netlink and the input layer Aug 29 11:07:27 volumio1 acpid[1343]: 1 rule loaded Aug 29 11:07:27 volumio1 acpid[1343]: waiting for events: event logging is off Aug 29 11:07:27 volumio1 systemd[1]: soundcard-init.service: Succeeded. Aug 29 11:07:27 volumio1 startx[1235]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Aug 29 11:07:28 volumio1 nmbd[932]: [2024/08/29 11:07:28.608433, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 29 11:07:28 volumio1 nmbd[932]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 29 11:07:28 volumio1 systemd[1]: Started Samba NMB Daemon. Aug 29 11:07:28 volumio1 systemd[1]: Starting Samba Winbind Daemon... Aug 29 11:07:28 volumio1 volumio[1231]: info: ------------------------------------------- Aug 29 11:07:28 volumio1 volumio[1231]: info: ----- Volumio3 ---- Aug 29 11:07:28 volumio1 volumio[1231]: info: ------------------------------------------- Aug 29 11:07:28 volumio1 volumio[1231]: info: ----- System startup ---- Aug 29 11:07:28 volumio1 volumio[1231]: info: ------------------------------------------- Aug 29 11:07:29 volumio1 winbindd[1428]: [2024/08/29 11:07:29.028114, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 29 11:07:29 volumio1 winbindd[1428]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 29 11:07:29 volumio1 winbindd[1428]: [2024/08/29 11:07:29.039616, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 29 11:07:29 volumio1 winbindd[1428]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 29 11:07:29 volumio1 systemd[1]: Started Samba Winbind Daemon. Aug 29 11:07:29 volumio1 systemd[1]: Starting Samba SMB Daemon... Aug 29 11:07:29 volumio1 smbd[1447]: [2024/08/29 11:07:29.541723, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 29 11:07:29 volumio1 smbd[1447]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 29 11:07:29 volumio1 systemd[1]: Started Samba SMB Daemon. Aug 29 11:07:29 volumio1 systemd[1]: Reached target Multi-User System. Aug 29 11:07:29 volumio1 systemd[1]: Reached target Graphical Interface. Aug 29 11:07:29 volumio1 systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 29 11:07:29 volumio1 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 29 11:07:29 volumio1 systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 29 11:07:29 volumio1 systemd[1]: Startup finished in 7.238s (firmware) + 9.671s (loader) + 20.311s (kernel) + 26.957s (userspace) = 1min 4.179s. Aug 29 11:07:30 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:30] [connect] Successful connection Aug 29 11:07:30 volumio1 volumio[1231]: info: MYVOLUMIO Environment detected Aug 29 11:07:30 volumio1 volumio[1231]: info: Plugin folders cleanup Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning into folder /volumio/app/plugins/ Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning category audio_interface Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning category miscellanea Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning category music_service Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning category plugins.json Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning category system_controller Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning category user_interface Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning into folder /data/plugins/ Aug 29 11:07:30 volumio1 volumio[1231]: info: Scanning category music_service Aug 29 11:07:30 volumio1 volumio[1231]: info: Plugin folders cleanup completed Aug 29 11:07:30 volumio1 volumio[1231]: info: ------------------------------------------- Aug 29 11:07:30 volumio1 volumio[1231]: info: ----- Core plugins startup ---- Aug 29 11:07:30 volumio1 volumio[1231]: info: ------------------------------------------- Aug 29 11:07:30 volumio1 volumio[1231]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 11:07:30 volumio1 volumio[1231]: info: Adding plugin upnp to MyMusic Plugins Aug 29 11:07:30 volumio1 volumio[1231]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 11:07:30 volumio1 volumio[1231]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 11:07:30 volumio1 volumio[1231]: info: Loading plugins from folder /data/plugins/ Aug 29 11:07:30 volumio1 volumio[1231]: info: Loading plugin "system"... Aug 29 11:07:31 volumio1 volumio[1231]: info: Loading plugin "appearance"... Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "network"... Aug 29 11:07:32 volumio1 volumio[1231]: info: Refreshing Cached IP Addresses Aug 29 11:07:32 volumio1 sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 11:07:32 volumio1 sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:32 volumio1 sudo[1481]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:32 volumio1 sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 11:07:32 volumio1 sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "services"... Aug 29 11:07:32 volumio1 sudo[1483]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "alsa_controller"... Aug 29 11:07:30 volumio1 sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 11:07:30 volumio1 sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:30 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 11:07:30 volumio1 volumio[1231]: info: Loading plugin "wizard"... Aug 29 11:07:30 volumio1 volumio[1231]: info: Loading plugin "networkfs"... Aug 29 11:07:30 volumio1 dbus-daemon[782]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.7' (uid=0 pid=1429 comm="/usr/lib/chromium/chromium --show-component-extens") Aug 29 11:07:30 volumio1 systemd[1]: Starting Bluetooth service... Aug 29 11:07:31 volumio1 volumio[1231]: info: Starting Udev Watcher for removable devices Aug 29 11:07:31 volumio1 sudo[1486]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:31 volumio1 sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.100/Musik /mnt/NAS/Musik Aug 29 11:07:31 volumio1 sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:31 volumio1 volumio[1231]: info: Ignoring mount for partition: boot Aug 29 11:07:31 volumio1 volumio[1231]: info: Ignoring mount for partition: volumio Aug 29 11:07:31 volumio1 volumio[1231]: info: Ignoring mount for partition: volumio_data Aug 29 11:07:31 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 11:07:31 volumio1 volumio[1231]: info: Loading plugin "volumio_command_line_client"... Aug 29 11:07:31 volumio1 volumio[1231]: info: Loading plugin "upnp"... Aug 29 11:07:31 volumio1 volumio[1231]: info: [1724922451094] Starting Upmpd Daemon Aug 29 11:07:31 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 11:07:31 volumio1 volumio[1231]: info: Loading plugin "my_music"... Aug 29 11:07:31 volumio1 volumio[1231]: info: Loading plugin "mpd"... Aug 29 11:07:31 volumio1 kernel: FS-Cache: Loaded Aug 29 11:07:31 volumio1 kernel: Key type dns_resolver registered Aug 29 11:07:31 volumio1 kernel: Key type cifs.spnego registered Aug 29 11:07:31 volumio1 kernel: Key type cifs.idmap registered Aug 29 11:07:31 volumio1 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. Aug 29 11:07:31 volumio1 kernel: CIFS: Attempting to mount //192.168.0.100/Musik Aug 29 11:07:31 volumio1 kernel: Bluetooth: Core ver 2.22 Aug 29 11:07:31 volumio1 kernel: NET: Registered PF_BLUETOOTH protocol family Aug 29 11:07:31 volumio1 kernel: Bluetooth: HCI device and connection manager initialized Aug 29 11:07:31 volumio1 kernel: Bluetooth: HCI socket layer initialized Aug 29 11:07:31 volumio1 kernel: Bluetooth: L2CAP socket layer initialized Aug 29 11:07:31 volumio1 kernel: Bluetooth: SCO socket layer initialized Aug 29 11:07:31 volumio1 bluetoothd[1496]: Bluetooth daemon 5.50 Aug 29 11:07:31 volumio1 bluetoothd[1496]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 29 11:07:31 volumio1 dbus-daemon[782]: [system] Successfully activated service 'org.bluez' Aug 29 11:07:31 volumio1 systemd[1]: Started Bluetooth service. Aug 29 11:07:31 volumio1 bluetoothd[1496]: Starting SDP server Aug 29 11:07:31 volumio1 bluetoothd[1496]: Excluding (cli) sap Aug 29 11:07:31 volumio1 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 29 11:07:31 volumio1 kernel: Bluetooth: BNEP filters: protocol multicast Aug 29 11:07:31 volumio1 kernel: Bluetooth: BNEP socket layer initialized Aug 29 11:07:31 volumio1 bluetoothd[1496]: Bluetooth management interface 1.22 initialized Aug 29 11:07:31 volumio1 dbus-daemon[782]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.8' (uid=0 pid=1496 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 29 11:07:31 volumio1 sudo[1500]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:31 volumio1 systemd[1]: Starting Hostname Service... Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "upnp_browser"... Aug 29 11:07:32 volumio1 dbus-daemon[782]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 29 11:07:32 volumio1 systemd[1]: Started Hostname Service. Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "alarm-clock"... Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "airplay_emulation"... Aug 29 11:07:32 volumio1 volumio[1231]: info: Starting Shairport Sync Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "last_100"... Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "webradio"... Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "i2s_dacs"... Aug 29 11:07:32 volumio1 volumio[1231]: info: I2S DAC not set, start Auto-detection Aug 29 11:07:32 volumio1 volumio[1231]: info: Loading plugin "volumiodiscovery"... Aug 29 11:07:32 volumio1 volumio[1231]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 11:07:32 volumio1 volumio[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 11:07:32 volumio1 volumio[1231]: *** WARNING *** For more information see Aug 29 11:07:32 volumio1 volumio[1231]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 11:07:32 volumio1 volumio[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 11:07:32 volumio1 volumio[1231]: *** WARNING *** For more information see Aug 29 11:07:32 volumio1 node[1231]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 11:07:32 volumio1 node[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 11:07:32 volumio1 node[1231]: *** WARNING *** For more information see Aug 29 11:07:32 volumio1 node[1231]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 11:07:32 volumio1 node[1231]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 11:07:32 volumio1 node[1231]: *** WARNING *** For more information see Aug 29 11:07:32 volumio1 volumio[1231]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 11:07:33 volumio1 volumio[1231]: info: Discovery: Started advertising with name: Volumio1 Aug 29 11:07:33 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 11:07:33 volumio1 volumio[1231]: info: Loading plugin "spop"... Aug 29 11:07:34 volumio1 volumio[1231]: info: Loading plugin "ytcr"... Aug 29 11:07:34 volumio1 systemd[1]: systemd-fsckd.service: Succeeded. Aug 29 11:07:35 volumio1 sh[711]: timed out Aug 29 11:07:35 volumio1 dhcpcd[760]: timed out Aug 29 11:07:35 volumio1 sh[711]: dhcpcd exited Aug 29 11:07:35 volumio1 dhcpcd[760]: dhcpcd exited Aug 29 11:07:35 volumio1 sh[711]: ifup: failed to bring up eth0 Aug 29 11:07:35 volumio1 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 29 11:07:35 volumio1 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 29 11:07:36 volumio1 volumio[1231]: info: Loading plugin "outputs"... Aug 29 11:07:36 volumio1 volumio[1231]: info: Loading plugin "albumart"... Aug 29 11:07:36 volumio1 volumio[1231]: info: Plugin example_plugin is not enabled Aug 29 11:07:36 volumio1 volumio[1231]: info: Loading plugin "inputs"... Aug 29 11:07:36 volumio1 volumio[1231]: info: Loading plugin "updater_comm"... Aug 29 11:07:36 volumio1 volumio[1231]: Forking 3 albumart workers Aug 29 11:07:36 volumio1 volumio[1231]: info: Plugin mpdemulation is not enabled Aug 29 11:07:36 volumio1 volumio[1231]: info: Loading plugin "rest_api"... Aug 29 11:07:36 volumio1 volumio[1231]: info: Loading plugin "websocket"... Aug 29 11:07:36 volumio1 volumio[1231]: info: Starting Socket.io Server version 2.3.0 Aug 29 11:07:36 volumio1 volumio[1231]: info: Loading plugin "podcast"... Aug 29 11:07:37 volumio1 volumio[1231]: info: ControllerPodcast::constructor Aug 29 11:07:37 volumio1 volumio[1231]: Starting albumart workers Aug 29 11:07:37 volumio1 volumio[1231]: Starting albumart workers Aug 29 11:07:37 volumio1 volumio[1231]: info: Loading i18n strings for locale de Aug 29 11:07:37 volumio1 volumio[1231]: Updating browse sources language Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:37 volumio1 volumio[1231]: Starting albumart workers Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::initPlayerControls Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: Express server listening on port 3000 Aug 29 11:07:37 volumio1 volumio[1231]: [Metrics] WebUI: 12s 9.81ms Aug 29 11:07:37 volumio1 volumio[1231]: info: Setting Device type: x86 Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreStateMachine::resetVolumioState Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreStateMachine::getcurrentVolume Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 11:07:37 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:07:37 volumio1 volumio[1231]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 29 11:07:37 volumio1 volumio[1231]: info: Completed loading Core Plugins Aug 29 11:07:37 volumio1 volumio[1231]: info: Preparing to generate the ALSA configuration file Aug 29 11:07:37 volumio1 volumio[1231]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 29 11:07:37 volumio1 volumio[1231]: info: Reloading queue from file Aug 29 11:07:37 volumio1 volumio[1231]: info: Asound.conf file unchanged, so no further update is needed Aug 29 11:07:37 volumio1 volumio[1231]: info: Output device has changed, restarting MPD Aug 29 11:07:38 volumio1 sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 11:07:38 volumio1 volumio[1231]: info: Output device has changed, restarting Shairport Sync Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:07:38 volumio1 sudo[1702]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:38 volumio1 sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 11:07:38 volumio1 sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:38 volumio1 volumio[1231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 11:07:38 volumio1 volumio[1231]: info: ___________ START PLUGINS ___________ Aug 29 11:07:38 volumio1 systemd[1]: Listening on mpd.socket. Aug 29 11:07:38 volumio1 volumio[1231]: info: ControllerMpd::onStart: Initializing MPD Aug 29 11:07:38 volumio1 volumio[1231]: info: Creating MPD Configuration file Aug 29 11:07:38 volumio1 systemd[1]: Starting Music Player Daemon... Aug 29 11:07:38 volumio1 sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:07:38 volumio1 volumio[1231]: info: [1724922458134] CoreMusicLibrary::Adding element Medienserver Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:38 volumio1 sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:38 volumio1 sudo[1712]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:38 volumio1 sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 11:07:38 volumio1 sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:07:38 volumio1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 11:07:38 volumio1 systemd[1]: mpd.service: Succeeded. Aug 29 11:07:38 volumio1 systemd[1]: Stopped Music Player Daemon. Aug 29 11:07:38 volumio1 systemd[1]: Starting Music Player Daemon... Aug 29 11:07:38 volumio1 volumio[1231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:07:38 volumio1 volumio[1231]: info: [1724922458249] CoreMusicLibrary::Adding element Last_100 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:07:38 volumio1 volumio[1231]: info: [1724922458252] CoreMusicLibrary::Adding element Webradio Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 11:07:38 volumio1 volumio[1231]: info: Initializing BBC Radios Aug 29 11:07:38 volumio1 sudo[1718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 11:07:38 volumio1 sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:38 volumio1 sudo[1718]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 29 11:07:38 volumio1 sudo[1718]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: Creating Spotify config file Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:07:38 volumio1 volumio[1231]: info: [1724922458482] CoreMusicLibrary::Adding element Podcast Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:38 volumio1 volumio[1231]: Cannot find translation for source Podcast Aug 29 11:07:38 volumio1 volumio[1231]: info: Volumio Calling Home Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreStateMachine::setRepeat null single undefined Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreStateMachine::pushState Aug 29 11:07:38 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioPushState Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreStateMachine::setRandom null Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreStateMachine::pushState Aug 29 11:07:38 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioPushState Aug 29 11:07:38 volumio1 volumio[1231]: info: VolumeController:: Volume=79 Mute =false Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:38 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreStateMachine::pushState Aug 29 11:07:38 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioPushState Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreStateMachine::updateTrackBlock Aug 29 11:07:38 volumio1 volumio[1231]: info: CorePlayQueue::getTrackBlock Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 11:07:38 volumio1 volumio[1231]: info: MPD Permissions set Aug 29 11:07:38 volumio1 volumio[1231]: info: MPD Permissions set Aug 29 11:07:38 volumio1 volumio[1231]: info: Volumio called home Aug 29 11:07:38 volumio1 volumio[1231]: info: Spotify config file written Aug 29 11:07:38 volumio1 volumio[1231]: info: Discovery: adding d53e2559-fa62-4748-995f-c1e7265dcf39 Aug 29 11:07:38 volumio1 volumio[1231]: info: Discovery: Found device Volumio1 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:38 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:38 volumio1 sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 11:07:38 volumio1 sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:38 volumio1 systemd[1]: Started go-librespot Daemon. Aug 29 11:07:38 volumio1 go-librespot[1744]: Librespot-go daemon starting... Aug 29 11:07:38 volumio1 sudo[1742]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:38 volumio1 volumio[1231]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:38 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=info msg="generated new device id: aedcf9f7418894d6a6301b3f945a37afa0e1ba8a" Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=debug msg="stored credentials found for 1165119697" Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:07:39 volumio1 volumio[1231]: info: VolumeController:: Volume=79 Mute =false Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:39 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreStateMachine::pushState Aug 29 11:07:39 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::volumioPushState Aug 29 11:07:39 volumio1 volumio[1231]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 29 11:07:39 volumio1 volumio[1231]: SPOTIFY: BQA9oyz-IqMuB0UQCGOA_6yuDzsQWUj-cVLxn9YeWnjfVfl-RK1VUVjIfijDdJcNHS8p_H72EOI2HDjFcr100SEy8z187zfwa-0vtsNFRY4SdczuzxRERAUICj2AHaMtr50n7whY0UfPYAd6TNjuZW5xKq_5ggMr8j3GN69LafBDyTgcmt7GGTv9gtnTU14oaot6ppBEUw4w0aCs8wYz6_EI4-98BzQ4A7bC7frvt239vbXdOX65SROT7T0 Aug 29 11:07:39 volumio1 volumio[1231]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 29 11:07:39 volumio1 volumio[1231]: info: New Spotify access token = BQA9oyz-IqMuB0UQCGOA_6yuDzsQWUj-cVLxn9YeWnjfVfl-RK1VUVjIfijDdJcNHS8p_H72EOI2HDjFcr100SEy8z187zfwa-0vtsNFRY4SdczuzxRERAUICj2AHaMtr50n7whY0UfPYAd6TNjuZW5xKq_5ggMr8j3GN69LafBDyTgcmt7GGTv9gtnTU14oaot6ppBEUw4w0aCs8wYz6_EI4-98BzQ4A7bC7frvt239vbXdOX65SROT7T0 Aug 29 11:07:39 volumio1 volumio[1231]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 29 11:07:39 volumio1 volumio[1231]: info: Starting Shairport Sync Aug 29 11:07:39 volumio1 volumio[1231]: info: Starting Shairport Sync Aug 29 11:07:39 volumio1 volumio[1231]: info: Starting Shairport Sync Aug 29 11:07:39 volumio1 sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 11:07:39 volumio1 sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=debug msg="zeroconf server listening on port 42267" Aug 29 11:07:39 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 11:07:39 volumio1 systemd[1]: shairport-sync.service: Succeeded. Aug 29 11:07:39 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 11:07:39 volumio1 sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 11:07:39 volumio1 sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:39 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 11:07:39 volumio1 sudo[1765]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:39 volumio1 sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 11:07:39 volumio1 sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:39 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:39 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 11:07:39 volumio1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 29 11:07:39 volumio1 systemd[1]: shairport-sync.service: Succeeded. Aug 29 11:07:39 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=debug msg="obtained new client token: AACxyFu/Q0UTuP40eMEmGzVTF2UgNmN/+/+qdf288+wJx7Q2va6xeQoIZC5T96+onLTMzZ7kK5gQtmeC6wnE7yiWZ27ivOl63pDHVBf1Tcf2qEKWIDcq1MqaN1rQe+Bb5G++lxrPootwJSx2wzgD0xc++2QCmQVHBC0L1qv7A6jeT10HGLOhSFPPJ6jjUOY3Vl701K09jlTi2Ot2yMFXNG+S7KQqL+TxazIBCkMNT8cg5NwXHF6Ziwi06A==" Aug 29 11:07:39 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 11:07:39 volumio1 sudo[1767]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:39 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 11:07:39 volumio1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 29 11:07:39 volumio1 systemd[1]: shairport-sync.service: Succeeded. Aug 29 11:07:39 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 11:07:39 volumio1 volumio[1231]: info: Shairport-Sync Started Aug 29 11:07:39 volumio1 volumio[1231]: Error adding Membership: Error: addMembership EINVAL Aug 29 11:07:39 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 11:07:39 volumio1 sudo[1773]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:39 volumio1 volumio[1231]: info: Shairport-Sync Started Aug 29 11:07:39 volumio1 go-librespot[1744]: time="2024-08-29T11:07:39+02:00" level=debug msg="completed keyexchange" Aug 29 11:07:39 volumio1 volumio[1231]: info: Shairport-Sync Started Aug 29 11:07:39 volumio1 volumio[1231]: SPOTIFY: User informations: {"display_name":"1165119697","external_urls":{"spotify":"https://open.spotify.com/user/1165119697"},"href":"https://api.spotify.com/v1/users/1165119697","id":"1165119697","images":[],"type":"user","uri":"spotify:user:1165119697","followers":{"href":null,"total":4},"country":"DE","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"juri@dachtera.de"} Aug 29 11:07:39 volumio1 volumio[1231]: info: Spotify Successfully logged in Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:07:39 volumio1 volumio[1231]: info: [1724922459666] CoreMusicLibrary::Adding element Spotify Aug 29 11:07:39 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:39 volumio1 volumio[1231]: Cannot find translation for source Podcast Aug 29 11:07:39 volumio1 volumio[1231]: Cannot find translation for source Spotify Aug 29 11:07:39 volumio1 mpd[1727]: Aug 29 11:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 11:07:40 volumio1 systemd[1]: Started Music Player Daemon. Aug 29 11:07:40 volumio1 sudo[1704]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:40 volumio1 sudo[1714]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="completed challenge" Aug 29 11:07:40 volumio1 volumio[1231]: error: MPD error: The expression evaluated to a falsy value: Aug 29 11:07:40 volumio1 volumio[1231]: assert.ok(self.idling) Aug 29 11:07:40 volumio1 volumio[1231]: error: The expression evaluated to a falsy value: Aug 29 11:07:40 volumio1 volumio[1231]: assert.ok(self.idling) Aug 29 11:07:40 volumio1 volumio[1231]: error: updateQueue error: null Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="authenticated as 1165119697" Aug 29 11:07:40 volumio1 volumio[1231]: info: MPD running with PID1727 Aug 29 11:07:40 volumio1 volumio[1231]: ,establishing connection Aug 29 11:07:40 volumio1 volumio[1231]: error: updateQueue error: null Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="authenticated as 1165119697" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="dealer connection opened" Aug 29 11:07:40 volumio1 volumio[1231]: info: [yt-cast-receiver] DIAL server listening on port 8098 Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="initializing zeroconf session, username: 1165119697" Aug 29 11:07:40 volumio1 volumio[1231]: info: Completed starting Core Plugins Aug 29 11:07:40 volumio1 volumio[1231]: info: ------------------------------------------- Aug 29 11:07:40 volumio1 volumio[1231]: info: ----- MyVolumio plugins startup ---- Aug 29 11:07:40 volumio1 volumio[1231]: info: ------------------------------------------- Aug 29 11:07:40 volumio1 volumio[1231]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="received connection id: NThiNTY0YTEtNWJkOC00NTU2LWE0M2EtYzNmOTYzMTA4MGFmK2RlYWxlcit0Y3A6Ly8wYWNhNTg5NS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQ0ZBNEFFNDQzQjlGQ0VFNzlDMzk2RDYzNUQwRDMwMkE0NUEyQTY4REMwNEUxNjU1MTU3QzNERjcyNzVGOTU2Rg==" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="put connect state because NEW_DEVICE" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Aug 29 11:07:40 volumio1 go-librespot[1744]: time="2024-08-29T11:07:40+02:00" level=debug msg="autoplay enabled: false" Aug 29 11:07:41 volumio1 sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 11:07:41 volumio1 sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:41 volumio1 sudo[1787]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:41 volumio1 volumio[1231]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 11:07:41 volumio1 sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 11:07:41 volumio1 sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:41 volumio1 sudo[1789]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:41 volumio1 sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 11:07:41 volumio1 sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:07:41 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 29 11:07:41 volumio1 sudo[1793]: pam_unix(sudo:session): session closed for user root Aug 29 11:07:41 volumio1 volumio[1231]: info: Upmpdcli Daemon Started Aug 29 11:07:41 volumio1 volumio[1795]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 29 11:07:41 volumio1 volumio[1795]: ........++++ Aug 29 11:07:41 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 11:07:42 volumio1 volumio[1231]: info: go-librespot daemon successfully initialized Aug 29 11:07:42 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:42] [connect] Successful connection Aug 29 11:07:42 volumio1 volumio-remote-updater[786]: [2024-08-29 11:07:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724922462 101 Aug 29 11:07:42 volumio1 volumio[1231]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 29 11:07:43 volumio1 volumio[1231]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 11:07:45 volumio1 volumio[1231]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 11:07:45 volumio1 volumio[1231]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetQueue Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreStateMachine::getQueue Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getQueue Aug 29 11:07:45 volumio1 volumio[1231]: info: Listing playlists Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 11:07:45 volumio1 volumio[1231]: info: Received Get System Info Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 11:07:45 volumio1 volumio[1231]: info: Discovery: Getting this device information Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 11:07:45 volumio1 volumio[1231]: info: Received Get System Info Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 11:07:45 volumio1 volumio[1231]: info: Discovery: Getting this device information Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetQueue Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreStateMachine::getQueue Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getQueue Aug 29 11:07:45 volumio1 volumio[1231]: info: Listing playlists Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 11:07:45 volumio1 volumio[1231]: info: Received Get System Info Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 11:07:45 volumio1 volumio[1231]: info: Discovery: Getting this device information Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 11:07:45 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:45 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:45 volumio1 volumio[1231]: info: Initializing connection to go-librespot Websocket Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="new websocket client" Aug 29 11:07:45 volumio1 volumio[1231]: info: Connection to go-librespot Websocket established Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="handling transfer player command from 818e39b5f5ea33316cb738a852adc0a6010d0bd1" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="resolved context of track" uri="spotify:album:03nNngl4Bs6vUmwFMf3oMb" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=trace msg="fetched new page 0 with 18 items (list: 18)" uri="spotify:album:03nNngl4Bs6vUmwFMf3oMb" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=trace msg="emitting websocket event: will_play" Aug 29 11:07:45 volumio1 volumio[1231]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3X4DnFKV2P88rWXA8X9Bfd","play_origin":"com.spotify.gaia"}} Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="selected format OGG_VORBIS_320 (b87ad16c1451642d8c1c32df4f42ff67bab059cb)" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="requested aes key for file b87ad16c1451642d8c1c32df4f42ff67bab059cb, gid: 3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1361" Aug 29 11:07:45 volumio1 volumio[1795]: ......................................................................................................................................................++++ Aug 29 11:07:45 volumio1 volumio[1795]: e is 65537 (0x010001) Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="fetched first chunk of 4, total size is 1713133 bytes" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:45 volumio1 volumio[1795]: writing RSA key Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="fetched chunk 3/3, size: 140269" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1135" Aug 29 11:07:45 volumio1 go-librespot[1744]: time="2024-08-29T11:07:45+02:00" level=debug msg="fetched chunk 2/3, size: 524288" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:46 volumio1 kernel: intel_sst_acpi 808622A8:00: FW Version 01.0b.02.02 Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="fetched chunk 1/3, size: 524288" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10000 us, period size = 441 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 50 frames" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="created new output device" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=info msg="loaded track \"161 - und der Juwelenschatz - Outro\" (paused: true, position: 0ms, duration: 45760ms, prefetched: false)" uri="spotify:track:3X4DnFKV2P88rWXA8X9Bfd" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=trace msg="emitting websocket event: metadata" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=trace msg="emitting websocket event: active" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="sending successful reply for dealer request" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Aug 29 11:07:46 volumio1 volumio[1231]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3X4DnFKV2P88rWXA8X9Bfd","name":"161 - und der Juwelenschatz - Outro","artist_names":["Fünf Freunde"],"album_name":"Folge 161: und der Juwelenschatz","album_cover_url":"https://i.scdn.co/image/ab67616d00001e024c040cab796023c166d071dd","position":0,"duration":45760,"release_date":"year:2024 month:7 day:26","track_number":18,"disc_number":1}} Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Aug 29 11:07:46 volumio1 volumio[1231]: SPOTIFY: received: {"type":"active","data":null} Aug 29 11:07:46 volumio1 volumio[1231]: info: Aligning Spotify Volume to Volumio Volume Aug 29 11:07:46 volumio1 volumio[1231]: info: CoreCommandRouter::volumioGetState Aug 29 11:07:46 volumio1 volumio[1231]: info: CorePlayQueue::getTrack 0 Aug 29 11:07:46 volumio1 volumio[1231]: info: Setting Spotify Volume from Volumio: 79 Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 29 11:07:46 volumio1 go-librespot[1744]: time="2024-08-29T11:07:46+02:00" level=trace msg="emitting websocket event: paused" Aug 29 11:07:46 volumio1 volumio[1231]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3X4DnFKV2P88rWXA8X9Bfd","play_origin":"com.spotify.gaia"}} Aug 29 11:07:46 volumio1 volumio[1231]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 11:07:46 volumio1 volumio[1231]: TypeError: Cannot read property 'service' of undefined Aug 29 11:07:46 volumio1 volumio[1231]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Aug 29 11:07:46 volumio1 volumio[1231]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Aug 29 11:07:46 volumio1 volumio[1231]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Aug 29 11:07:46 volumio1 volumio[1231]: at WebSocket.emit (events.js:400:28) Aug 29 11:07:46 volumio1 volumio[1231]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Aug 29 11:07:46 volumio1 volumio[1231]: at Receiver.emit (events.js:400:28) Aug 29 11:07:46 volumio1 volumio[1231]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Aug 29 11:07:46 volumio1 volumio[1231]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Aug 29 11:07:46 volumio1 volumio[1231]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Aug 29 11:07:46 volumio1 volumio[1231]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Aug 29 11:07:46 volumio1 volumio[1231]: at writeOrBuffer (internal/streams/writable.js:358:12) Aug 29 11:07:46 volumio1 volumio[1231]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Aug 29 11:07:46 volumio1 volumio[1231]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Aug 29 11:07:46 volumio1 volumio[1231]: at Socket.emit (events.js:400:28) Aug 29 11:07:46 volumio1 volumio[1231]: at addChunk (internal/streams/readable.js:293:12) Aug 29 11:07:46 volumio1 volumio[1231]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 29 11:07:46 volumio1 volumio[1231]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 11:07:46 volumio1 sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 11:06 Aug 29 11:07:46 volumio1 sudo[1848]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"