-- Logs begin at Sat 2024-11-02 07:39:04 EDT, end at Sat 2024-11-02 07:39:23 EDT. -- Nov 02 07:39:04 volumio3 kernel: Linux version 6.6.31-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 Fri May 17 18:26:28 CEST 2024 Nov 02 07:39:04 volumio3 kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=38de4b05-5c13-4ba3-9ef0-502f28f757a5 bootpart=UUID=6952-DF04 datapart=UUID=a878be2e-06ad-4aec-9ab2-0e14c44f1b3e hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Nov 02 07:39:04 volumio3 kernel: KERNEL supported cpus: Nov 02 07:39:04 volumio3 kernel: Intel GenuineIntel Nov 02 07:39:04 volumio3 kernel: AMD AuthenticAMD Nov 02 07:39:04 volumio3 kernel: Hygon HygonGenuine Nov 02 07:39:04 volumio3 kernel: Centaur CentaurHauls Nov 02 07:39:04 volumio3 kernel: zhaoxin Shanghai Nov 02 07:39:04 volumio3 kernel: BIOS-provided physical RAM map: Nov 02 07:39:04 volumio3 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Nov 02 07:39:04 volumio3 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Nov 02 07:39:04 volumio3 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Nov 02 07:39:04 volumio3 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffddfff] usable Nov 02 07:39:04 volumio3 kernel: BIOS-e820: [mem 0x000000007ffde000-0x000000007fffffff] reserved Nov 02 07:39:04 volumio3 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 02 07:39:04 volumio3 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Nov 02 07:39:04 volumio3 kernel: NX (Execute Disable) protection: active Nov 02 07:39:04 volumio3 kernel: APIC: Static calls initialized Nov 02 07:39:04 volumio3 kernel: SMBIOS 2.8 present. Nov 02 07:39:04 volumio3 kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 Nov 02 07:39:04 volumio3 kernel: Hypervisor detected: KVM Nov 02 07:39:04 volumio3 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 02 07:39:04 volumio3 kernel: kvm-clock: using sched offset of 15550670760 cycles Nov 02 07:39:04 volumio3 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 02 07:39:04 volumio3 kernel: tsc: Detected 1992.004 MHz processor Nov 02 07:39:04 volumio3 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 02 07:39:04 volumio3 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 02 07:39:04 volumio3 kernel: last_pfn = 0x7ffde max_arch_pfn = 0x400000000 Nov 02 07:39:04 volumio3 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Nov 02 07:39:04 volumio3 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- UC Nov 02 07:39:04 volumio3 kernel: found SMP MP-table at [mem 0x000f5440-0x000f544f] Nov 02 07:39:04 volumio3 kernel: RAMDISK: [mem 0x7bd5b000-0x7ffddfff] Nov 02 07:39:04 volumio3 kernel: ACPI: Early table checksum verification disabled Nov 02 07:39:04 volumio3 kernel: ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) Nov 02 07:39:04 volumio3 kernel: ACPI: RSDT 0x000000007FFE303C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 02 07:39:04 volumio3 kernel: ACPI: FACP 0x000000007FFE2E0E 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 02 07:39:04 volumio3 kernel: ACPI: DSDT 0x000000007FFDF040 003DCE (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 02 07:39:04 volumio3 kernel: ACPI: FACS 0x000000007FFDF000 000040 Nov 02 07:39:04 volumio3 kernel: ACPI: APIC 0x000000007FFE2E82 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 02 07:39:04 volumio3 kernel: ACPI: SSDT 0x000000007FFE2F12 0000CA (v01 BOCHS VMGENID 00000001 BXPC 00000001) Nov 02 07:39:04 volumio3 kernel: ACPI: HPET 0x000000007FFE2FDC 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 02 07:39:04 volumio3 kernel: ACPI: WAET 0x000000007FFE3014 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 02 07:39:04 volumio3 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe2e0e-0x7ffe2e81] Nov 02 07:39:04 volumio3 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffdf040-0x7ffe2e0d] Nov 02 07:39:04 volumio3 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffdf000-0x7ffdf03f] Nov 02 07:39:04 volumio3 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe2e82-0x7ffe2f11] Nov 02 07:39:04 volumio3 kernel: ACPI: Reserving SSDT table memory at [mem 0x7ffe2f12-0x7ffe2fdb] Nov 02 07:39:04 volumio3 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe2fdc-0x7ffe3013] Nov 02 07:39:04 volumio3 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe3014-0x7ffe303b] Nov 02 07:39:04 volumio3 kernel: Zone ranges: Nov 02 07:39:04 volumio3 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 02 07:39:04 volumio3 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffddfff] Nov 02 07:39:04 volumio3 kernel: Normal empty Nov 02 07:39:04 volumio3 kernel: Movable zone start for each node Nov 02 07:39:04 volumio3 kernel: Early memory node ranges Nov 02 07:39:04 volumio3 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Nov 02 07:39:04 volumio3 kernel: node 0: [mem 0x0000000000100000-0x000000007ffddfff] Nov 02 07:39:04 volumio3 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffddfff] Nov 02 07:39:04 volumio3 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 02 07:39:04 volumio3 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Nov 02 07:39:04 volumio3 kernel: On node 0, zone DMA32: 34 pages in unavailable ranges Nov 02 07:39:04 volumio3 kernel: ACPI: PM-Timer IO Port: 0x608 Nov 02 07:39:04 volumio3 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 02 07:39:04 volumio3 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 02 07:39:04 volumio3 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 02 07:39:04 volumio3 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 02 07:39:04 volumio3 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 02 07:39:04 volumio3 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 02 07:39:04 volumio3 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 02 07:39:04 volumio3 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 02 07:39:04 volumio3 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Nov 02 07:39:04 volumio3 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Nov 02 07:39:04 volumio3 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Nov 02 07:39:04 volumio3 kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Nov 02 07:39:04 volumio3 kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] Nov 02 07:39:04 volumio3 kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] Nov 02 07:39:04 volumio3 kernel: PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] Nov 02 07:39:04 volumio3 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Nov 02 07:39:04 volumio3 kernel: Booting paravirtualized kernel on KVM Nov 02 07:39:04 volumio3 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Nov 02 07:39:04 volumio3 kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Nov 02 07:39:04 volumio3 kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u524288 Nov 02 07:39:04 volumio3 kernel: pcpu-alloc: s191400 r8192 d29784 u524288 alloc=1*2097152 Nov 02 07:39:04 volumio3 kernel: pcpu-alloc: [0] 0 1 2 3 Nov 02 07:39:04 volumio3 kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=38de4b05-5c13-4ba3-9ef0-502f28f757a5 bootpart=UUID=6952-DF04 datapart=UUID=a878be2e-06ad-4aec-9ab2-0e14c44f1b3e hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Nov 02 07:39:04 volumio3 kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=38de4b05-5c13-4ba3-9ef0-502f28f757a5 bootpart=UUID=6952-DF04 datapart=UUID=a878be2e-06ad-4aec-9ab2-0e14c44f1b3e hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Nov 02 07:39:04 volumio3 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 02 07:39:04 volumio3 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 02 07:39:04 volumio3 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515806 Nov 02 07:39:04 volumio3 kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off Nov 02 07:39:04 volumio3 kernel: Memory: 1964180K/2096624K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 132188K reserved, 0K cma-reserved) Nov 02 07:39:04 volumio3 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 02 07:39:04 volumio3 kernel: Kernel/User page tables isolation: enabled Nov 02 07:39:04 volumio3 kernel: ftrace: allocating 40432 entries in 158 pages Nov 02 07:39:04 volumio3 kernel: ftrace: allocated 158 pages with 5 groups Nov 02 07:39:04 volumio3 kernel: Dynamic Preempt: voluntary Nov 02 07:39:04 volumio3 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 02 07:39:04 volumio3 kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Nov 02 07:39:04 volumio3 kernel: Trampoline variant of Tasks RCU enabled. Nov 02 07:39:04 volumio3 kernel: Rude variant of Tasks RCU enabled. Nov 02 07:39:04 volumio3 kernel: Tracing variant of Tasks RCU enabled. Nov 02 07:39:04 volumio3 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Nov 02 07:39:04 volumio3 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 02 07:39:04 volumio3 kernel: NR_IRQS: 4352, nr_irqs: 456, preallocated irqs: 16 Nov 02 07:39:04 volumio3 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 02 07:39:04 volumio3 kernel: Console: colour VGA+ 80x25 Nov 02 07:39:04 volumio3 kernel: printk: console [tty0] enabled Nov 02 07:39:04 volumio3 kernel: ACPI: Core revision 20230628 Nov 02 07:39:04 volumio3 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Nov 02 07:39:04 volumio3 kernel: APIC: Switch to symmetric I/O mode setup Nov 02 07:39:04 volumio3 kernel: x2apic enabled Nov 02 07:39:04 volumio3 kernel: APIC: Switched APIC routing to: physical x2apic Nov 02 07:39:04 volumio3 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 02 07:39:04 volumio3 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x396d59a56c3, max_idle_ns: 881590573781 ns Nov 02 07:39:04 volumio3 kernel: Calibrating delay loop (skipped) preset value.. 3984.00 BogoMIPS (lpj=7968016) Nov 02 07:39:04 volumio3 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Nov 02 07:39:04 volumio3 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Nov 02 07:39:04 volumio3 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 02 07:39:04 volumio3 kernel: Spectre V2 : Mitigation: Retpolines Nov 02 07:39:04 volumio3 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Nov 02 07:39:04 volumio3 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Nov 02 07:39:04 volumio3 kernel: Speculative Store Bypass: Vulnerable Nov 02 07:39:04 volumio3 kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Nov 02 07:39:04 volumio3 kernel: MMIO Stale Data: Unknown: No mitigations Nov 02 07:39:04 volumio3 kernel: x86/fpu: x87 FPU will use FXSAVE Nov 02 07:39:04 volumio3 kernel: Freeing SMP alternatives memory: 32K Nov 02 07:39:04 volumio3 kernel: pid_max: default: 32768 minimum: 301 Nov 02 07:39:04 volumio3 kernel: LSM: initializing lsm=capability,yama,integrity Nov 02 07:39:04 volumio3 kernel: Yama: becoming mindful. Nov 02 07:39:04 volumio3 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Nov 02 07:39:04 volumio3 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Nov 02 07:39:04 volumio3 kernel: smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1) Nov 02 07:39:04 volumio3 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 02 07:39:04 volumio3 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 02 07:39:04 volumio3 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 02 07:39:04 volumio3 kernel: Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. Nov 02 07:39:04 volumio3 kernel: signal: max sigframe size: 1440 Nov 02 07:39:04 volumio3 kernel: rcu: Hierarchical SRCU implementation. Nov 02 07:39:04 volumio3 kernel: rcu: Max phase no-delay instances is 1000. Nov 02 07:39:04 volumio3 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 02 07:39:04 volumio3 kernel: smp: Bringing up secondary CPUs ... Nov 02 07:39:04 volumio3 kernel: smpboot: x86: Booting SMP configuration: Nov 02 07:39:04 volumio3 kernel: .... node #0, CPUs: #1 #2 #3 Nov 02 07:39:04 volumio3 kernel: smpboot: CPU 2 Converting physical 0 to logical die 1 Nov 02 07:39:04 volumio3 kernel: smp: Brought up 1 node, 4 CPUs Nov 02 07:39:04 volumio3 kernel: smpboot: Max logical packages: 2 Nov 02 07:39:04 volumio3 kernel: smpboot: Total of 4 processors activated (15936.03 BogoMIPS) Nov 02 07:39:04 volumio3 kernel: devtmpfs: initialized Nov 02 07:39:04 volumio3 kernel: x86/mm: Memory block size: 128MB Nov 02 07:39:04 volumio3 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Nov 02 07:39:04 volumio3 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 02 07:39:04 volumio3 kernel: pinctrl core: initialized pinctrl subsystem Nov 02 07:39:04 volumio3 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 02 07:39:04 volumio3 kernel: audit: initializing netlink subsys (disabled) Nov 02 07:39:04 volumio3 kernel: audit: type=2000 audit(1730547537.013:1): state=initialized audit_enabled=0 res=1 Nov 02 07:39:04 volumio3 kernel: thermal_sys: Registered thermal governor 'fair_share' Nov 02 07:39:04 volumio3 kernel: thermal_sys: Registered thermal governor 'bang_bang' Nov 02 07:39:04 volumio3 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 02 07:39:04 volumio3 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 02 07:39:04 volumio3 kernel: EISA bus registered Nov 02 07:39:04 volumio3 kernel: cpuidle: using governor ladder Nov 02 07:39:04 volumio3 kernel: cpuidle: using governor menu Nov 02 07:39:04 volumio3 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 02 07:39:04 volumio3 kernel: PCI: Using configuration type 1 for base access Nov 02 07:39:04 volumio3 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 02 07:39:04 volumio3 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 02 07:39:04 volumio3 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 02 07:39:04 volumio3 kernel: ACPI: Added _OSI(Module Device) Nov 02 07:39:04 volumio3 kernel: ACPI: Added _OSI(Processor Device) Nov 02 07:39:04 volumio3 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 02 07:39:04 volumio3 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 02 07:39:04 volumio3 kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded Nov 02 07:39:04 volumio3 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Nov 02 07:39:04 volumio3 kernel: ACPI: Interpreter enabled Nov 02 07:39:04 volumio3 kernel: ACPI: PM: (supports S0 S3 S4 S5) Nov 02 07:39:04 volumio3 kernel: ACPI: Using IOAPIC for interrupt routing Nov 02 07:39:04 volumio3 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 02 07:39:04 volumio3 kernel: PCI: Using E820 reservations for host bridge windows Nov 02 07:39:04 volumio3 kernel: ACPI: Enabled 3 GPEs in block 00 to 0F Nov 02 07:39:04 volumio3 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 02 07:39:04 volumio3 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Nov 02 07:39:04 volumio3 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Nov 02 07:39:04 volumio3 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [18] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [6] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [7] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [8] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [9] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [10] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [11] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [12] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [13] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [14] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [15] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [16] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [17] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [19] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [20] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [21] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [22] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [23] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [24] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [25] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [26] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [27] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [28] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [29] registered Nov 02 07:39:04 volumio3 kernel: PCI host bridge to bus 0000:00 Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.1: reg 0x20: [io 0xf0a0-0xf0af] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.2: reg 0x20: [io 0xf080-0xf09f] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfcffffff pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfea50000-0xfea50fff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfea40000-0xfea4ffff pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:03.0: [1af4:1002] type 00 class 0x00ff00 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:03.0: reg 0x10: [io 0xf000-0xf03f] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfd600000-0xfd603fff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: [1b36:0001] type 01 class 0x060400 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: reg 0x10: [mem 0xfea51000-0xfea510ff 64bit] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:12.0: [1af4:1000] type 00 class 0x020000 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:12.0: reg 0x10: [io 0xf040-0xf07f] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:12.0: reg 0x14: [mem 0xfea52000-0xfea52fff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:12.0: reg 0x20: [mem 0xfd604000-0xfd607fff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:12.0: reg 0x30: [mem 0xfea00000-0xfea3ffff pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: [1b36:0001] type 01 class 0x060400 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: reg 0x10: [mem 0xfea53000-0xfea530ff 64bit] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: [1b36:0001] type 01 class 0x060400 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: reg 0x10: [mem 0xfea54000-0xfea540ff 64bit] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:01: extended config space not accessible Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [0] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [1-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [3-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [4-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [5] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [6-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [7-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [8-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [9-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [10-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [11-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [12-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [13-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [14-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [15-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [16-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [17-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [18-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [19-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [20-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [21-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [22-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [23-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [24-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [25-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [26-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [27-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [28-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [29-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [30] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [31] registered Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: bridge window [mem 0xfd400000-0xfd5fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:02: extended config space not accessible Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [0-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [1-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [2-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [3-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [4-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [5-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [6-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [7-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [8-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [9-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [10-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [11-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [12-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [13-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [14-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [15-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [16-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [17-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [18-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [19-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [20-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [21-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [22-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [23-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [24-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [25-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [26-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [27-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [28-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [29-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [30-2] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [31-2] registered Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: bridge window [mem 0xfd200000-0xfd3fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:03: extended config space not accessible Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [12-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [0-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [1-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [2-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [3-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [4-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [5-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [6-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [7-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [8-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [9-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [10-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [11-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [13-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [14-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [15-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [16-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [17-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [18-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [19-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [20-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [21-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [22-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [23-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [24-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [25-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [26-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [27-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [28-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [29-4] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [30-3] registered Nov 02 07:39:04 volumio3 kernel: acpiphp: Slot [31-3] registered Nov 02 07:39:04 volumio3 kernel: pci 0000:03:0c.0: [8086:293e] type 00 class 0x040300 Nov 02 07:39:04 volumio3 kernel: pci 0000:03:0c.0: reg 0x10: [mem 0xfe400000-0xfe403fff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: on NUMA node 0 Nov 02 07:39:04 volumio3 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Nov 02 07:39:04 volumio3 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Nov 02 07:39:04 volumio3 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Nov 02 07:39:04 volumio3 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Nov 02 07:39:04 volumio3 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Nov 02 07:39:04 volumio3 kernel: iommu: Default domain type: Translated Nov 02 07:39:04 volumio3 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 02 07:39:04 volumio3 kernel: SCSI subsystem initialized Nov 02 07:39:04 volumio3 kernel: libata version 3.00 loaded. Nov 02 07:39:04 volumio3 kernel: ACPI: bus type USB registered Nov 02 07:39:04 volumio3 kernel: usbcore: registered new interface driver usbfs Nov 02 07:39:04 volumio3 kernel: usbcore: registered new interface driver hub Nov 02 07:39:04 volumio3 kernel: usbcore: registered new device driver usb Nov 02 07:39:04 volumio3 kernel: PCI: Using ACPI for IRQ routing Nov 02 07:39:04 volumio3 kernel: PCI: pci_cache_line_size set to 64 bytes Nov 02 07:39:04 volumio3 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Nov 02 07:39:04 volumio3 kernel: e820: reserve RAM buffer [mem 0x7ffde000-0x7fffffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Nov 02 07:39:04 volumio3 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Nov 02 07:39:04 volumio3 kernel: vgaarb: loaded Nov 02 07:39:04 volumio3 kernel: hpet: 3 channels of 0 reserved for per-cpu timers Nov 02 07:39:04 volumio3 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Nov 02 07:39:04 volumio3 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Nov 02 07:39:04 volumio3 kernel: clocksource: Switched to clocksource kvm-clock Nov 02 07:39:04 volumio3 kernel: VFS: Disk quotas dquot_6.6.0 Nov 02 07:39:04 volumio3 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 02 07:39:04 volumio3 kernel: pnp: PnP ACPI init Nov 02 07:39:04 volumio3 kernel: pnp 00:02: [dma 2] Nov 02 07:39:04 volumio3 kernel: pnp: PnP ACPI: found 4 devices Nov 02 07:39:04 volumio3 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 02 07:39:04 volumio3 kernel: NET: Registered PF_INET protocol family Nov 02 07:39:04 volumio3 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 02 07:39:04 volumio3 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Nov 02 07:39:04 volumio3 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 02 07:39:04 volumio3 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 02 07:39:04 volumio3 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Nov 02 07:39:04 volumio3 kernel: TCP: Hash tables configured (established 16384 bind 16384) Nov 02 07:39:04 volumio3 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Nov 02 07:39:04 volumio3 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Nov 02 07:39:04 volumio3 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:05.0: bridge window [mem 0xfd400000-0xfd5fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1e.0: bridge window [mem 0xfd200000-0xfd3fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:1f.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:01: resource 0 [io 0xe000-0xefff] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:01: resource 1 [mem 0xfe800000-0xfe9fffff] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:01: resource 2 [mem 0xfd400000-0xfd5fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:02: resource 0 [io 0xd000-0xdfff] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:02: resource 1 [mem 0xfe600000-0xfe7fffff] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:02: resource 2 [mem 0xfd200000-0xfd3fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:03: resource 0 [io 0xc000-0xcfff] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:03: resource 1 [mem 0xfe400000-0xfe5fffff] Nov 02 07:39:04 volumio3 kernel: pci_bus 0000:03: resource 2 [mem 0xfd000000-0xfd1fffff 64bit pref] Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Nov 02 07:39:04 volumio3 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Nov 02 07:39:04 volumio3 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Nov 02 07:39:04 volumio3 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x770 took 29667 usecs Nov 02 07:39:04 volumio3 kernel: PCI: CLS 0 bytes, default 64 Nov 02 07:39:04 volumio3 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x396d59a56c3, max_idle_ns: 881590573781 ns Nov 02 07:39:04 volumio3 kernel: Trying to unpack rootfs image as initramfs... Nov 02 07:39:04 volumio3 kernel: Initialise system trusted keyrings Nov 02 07:39:04 volumio3 kernel: workingset: timestamp_bits=46 max_order=19 bucket_order=0 Nov 02 07:39:04 volumio3 kernel: zbud: loaded Nov 02 07:39:04 volumio3 kernel: Key type asymmetric registered Nov 02 07:39:04 volumio3 kernel: Asymmetric key parser 'x509' registered Nov 02 07:39:04 volumio3 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Nov 02 07:39:04 volumio3 kernel: io scheduler mq-deadline registered Nov 02 07:39:04 volumio3 kernel: io scheduler kyber registered Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.S28_) Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:05.0: Cannot get control of SHPC hotplug Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF0_) Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:1e.0: Cannot get control of SHPC hotplug Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF8_) Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Nov 02 07:39:04 volumio3 kernel: shpchp 0000:00:1f.0: Cannot get control of SHPC hotplug Nov 02 07:39:04 volumio3 kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Nov 02 07:39:04 volumio3 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Nov 02 07:39:04 volumio3 kernel: ACPI: button: Power Button [PWRF] Nov 02 07:39:04 volumio3 kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Nov 02 07:39:04 volumio3 kernel: Linux agpgart interface v0.103 Nov 02 07:39:04 volumio3 kernel: ata_piix 0000:00:01.1: version 2.13 Nov 02 07:39:04 volumio3 kernel: scsi host0: ata_piix Nov 02 07:39:04 volumio3 kernel: scsi host1: ata_piix Nov 02 07:39:04 volumio3 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xf0a0 irq 14 Nov 02 07:39:04 volumio3 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xf0a8 irq 15 Nov 02 07:39:04 volumio3 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Nov 02 07:39:04 volumio3 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Nov 02 07:39:04 volumio3 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Nov 02 07:39:04 volumio3 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000f080 Nov 02 07:39:04 volumio3 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06 Nov 02 07:39:04 volumio3 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 02 07:39:04 volumio3 kernel: usb usb1: Product: UHCI Host Controller Nov 02 07:39:04 volumio3 kernel: usb usb1: Manufacturer: Linux 6.6.31-volumio uhci_hcd Nov 02 07:39:04 volumio3 kernel: usb usb1: SerialNumber: 0000:00:01.2 Nov 02 07:39:04 volumio3 kernel: hub 1-0:1.0: USB hub found Nov 02 07:39:04 volumio3 kernel: hub 1-0:1.0: 2 ports detected Nov 02 07:39:04 volumio3 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 02 07:39:04 volumio3 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 02 07:39:04 volumio3 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 02 07:39:04 volumio3 kernel: mousedev: PS/2 mouse device common for all mice Nov 02 07:39:04 volumio3 kernel: rtc_cmos 00:03: RTC can wake from S4 Nov 02 07:39:04 volumio3 kernel: rtc_cmos 00:03: registered as rtc0 Nov 02 07:39:04 volumio3 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 Nov 02 07:39:04 volumio3 kernel: rtc_cmos 00:03: setting system clock to 2024-11-02T11:38:57 UTC (1730547537) Nov 02 07:39:04 volumio3 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Nov 02 07:39:04 volumio3 kernel: i2c_dev: i2c /dev entries driver Nov 02 07:39:04 volumio3 kernel: platform eisa.0: Probing EISA bus 0 Nov 02 07:39:04 volumio3 kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Nov 02 07:39:04 volumio3 kernel: intel_pstate: CPU model not supported Nov 02 07:39:04 volumio3 kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 02 07:39:04 volumio3 kernel: NET: Registered PF_INET6 protocol family Nov 02 07:39:04 volumio3 kernel: Segment Routing with IPv6 Nov 02 07:39:04 volumio3 kernel: In-situ OAM (IOAM) with IPv6 Nov 02 07:39:04 volumio3 kernel: mip6: Mobile IPv6 Nov 02 07:39:04 volumio3 kernel: NET: Registered PF_PACKET protocol family Nov 02 07:39:04 volumio3 kernel: mpls_gso: MPLS GSO support Nov 02 07:39:04 volumio3 kernel: IPI shorthand broadcast: enabled Nov 02 07:39:04 volumio3 kernel: sched_clock: Marking stable (404003882, 8320415)->(429826365, -17502068) Nov 02 07:39:04 volumio3 kernel: Loading compiled-in X.509 certificates Nov 02 07:39:04 volumio3 kernel: clk: Disabling unused clocks Nov 02 07:39:04 volumio3 kernel: ata2: found unknown device (class 0) Nov 02 07:39:04 volumio3 kernel: ata1: found unknown device (class 0) Nov 02 07:39:04 volumio3 kernel: ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Nov 02 07:39:04 volumio3 kernel: ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 Nov 02 07:39:04 volumio3 kernel: ata1.00: 67108864 sectors, multi 16: LBA48 Nov 02 07:39:04 volumio3 kernel: scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Nov 02 07:39:04 volumio3 kernel: sd 0:0:0:0: [sda] 67108864 512-byte logical blocks: (34.4 GB/32.0 GiB) Nov 02 07:39:04 volumio3 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 02 07:39:04 volumio3 kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 Nov 02 07:39:04 volumio3 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Nov 02 07:39:04 volumio3 kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Nov 02 07:39:04 volumio3 kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Nov 02 07:39:04 volumio3 kernel: sda: sda1 sda2 sda3 Nov 02 07:39:04 volumio3 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 02 07:39:04 volumio3 kernel: usb 1-1: new full-speed USB device number 2 using uhci_hcd Nov 02 07:39:04 volumio3 kernel: usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 Nov 02 07:39:04 volumio3 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 Nov 02 07:39:04 volumio3 kernel: usb 1-1: Product: QEMU USB Tablet Nov 02 07:39:04 volumio3 kernel: usb 1-1: Manufacturer: QEMU Nov 02 07:39:04 volumio3 kernel: usb 1-1: SerialNumber: 28754-0000:00:01.2-1 Nov 02 07:39:04 volumio3 kernel: Freeing initrd memory: 68108K Nov 02 07:39:04 volumio3 kernel: Freeing unused kernel image (initmem) memory: 2988K Nov 02 07:39:04 volumio3 kernel: Write protecting the kernel read-only data: 20480k Nov 02 07:39:04 volumio3 kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K Nov 02 07:39:04 volumio3 kernel: Run /init as init process Nov 02 07:39:04 volumio3 kernel: with arguments: Nov 02 07:39:04 volumio3 kernel: /init Nov 02 07:39:04 volumio3 kernel: splash Nov 02 07:39:04 volumio3 kernel: with environment: Nov 02 07:39:04 volumio3 kernel: HOME=/ Nov 02 07:39:04 volumio3 kernel: TERM=linux Nov 02 07:39:04 volumio3 kernel: BOOT_IMAGE=vmlinuz Nov 02 07:39:04 volumio3 kernel: biosdevname=0 Nov 02 07:39:04 volumio3 kernel: imgpart=UUID=38de4b05-5c13-4ba3-9ef0-502f28f757a5 Nov 02 07:39:04 volumio3 kernel: bootpart=UUID=6952-DF04 Nov 02 07:39:04 volumio3 kernel: datapart=UUID=a878be2e-06ad-4aec-9ab2-0e14c44f1b3e Nov 02 07:39:04 volumio3 kernel: hwdevice=x86 Nov 02 07:39:04 volumio3 kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Nov 02 07:39:04 volumio3 kernel: imgfile=/volumio_current.sqsh Nov 02 07:39:04 volumio3 kernel: use_kmsg=no Nov 02 07:39:04 volumio3 kernel: FDC 0 is a S82078B Nov 02 07:39:04 volumio3 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 10 Nov 02 07:39:04 volumio3 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Nov 02 07:39:04 volumio3 kernel: ACPI: bus type drm_connector registered Nov 02 07:39:04 volumio3 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 02 07:39:04 volumio3 kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 Nov 02 07:39:04 volumio3 kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 Nov 02 07:39:04 volumio3 kernel: usbcore: registered new interface driver usbhid Nov 02 07:39:04 volumio3 kernel: usbhid: USB HID core driver Nov 02 07:39:04 volumio3 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Nov 02 07:39:04 volumio3 kernel: bochs-drm 0000:00:02.0: vgaarb: deactivate vga console Nov 02 07:39:04 volumio3 kernel: Console: switching to colour dummy device 80x25 Nov 02 07:39:04 volumio3 kernel: [drm] Found bochs VGA, ID 0xb0c5. Nov 02 07:39:04 volumio3 kernel: [drm] Framebuffer size 16384 kB @ 0xfc000000, mmio @ 0xfea50000. Nov 02 07:39:04 volumio3 kernel: sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Nov 02 07:39:04 volumio3 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 02 07:39:04 volumio3 kernel: [drm] Found EDID data blob. Nov 02 07:39:04 volumio3 kernel: input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input5 Nov 02 07:39:04 volumio3 kernel: hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0 Nov 02 07:39:04 volumio3 kernel: [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0 Nov 02 07:39:04 volumio3 kernel: fbcon: bochs-drmdrmfb (fb0) is primary device Nov 02 07:39:04 volumio3 kernel: Console: switching to colour frame buffer device 160x50 Nov 02 07:39:04 volumio3 kernel: bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device Nov 02 07:39:04 volumio3 kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0 Nov 02 07:39:04 volumio3 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 02 07:39:04 volumio3 kernel: sdhci: Secure Digital Host Controller Interface driver Nov 02 07:39:04 volumio3 kernel: sdhci: Copyright(c) Pierre Ossman Nov 02 07:39:04 volumio3 kernel: fuse: init (API version 7.39) Nov 02 07:39:04 volumio3 kernel: usbcore: registered new interface driver usb-storage Nov 02 07:39:04 volumio3 kernel: usbcore: registered new interface driver uas Nov 02 07:39:04 volumio3 kernel: [drm] radeon kernel modesetting enabled. Nov 02 07:39:04 volumio3 kernel: random: crng init done Nov 02 07:39:04 volumio3 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Nov 02 07:39:04 volumio3 kernel: EXT4-fs (sda2): recovery complete Nov 02 07:39:04 volumio3 kernel: EXT4-fs (sda2): mounted filesystem 38de4b05-5c13-4ba3-9ef0-502f28f757a5 r/w with ordered data mode. Quota mode: none. Nov 02 07:39:04 volumio3 kernel: loop: module loaded Nov 02 07:39:04 volumio3 kernel: block device autoloading is deprecated and will be removed. Nov 02 07:39:04 volumio3 kernel: loop0: detected capacity change from 0 to 1721400 Nov 02 07:39:04 volumio3 kernel: EXT4-fs (sda3): recovery complete Nov 02 07:39:04 volumio3 kernel: EXT4-fs (sda3): mounted filesystem a878be2e-06ad-4aec-9ab2-0e14c44f1b3e r/w with ordered data mode. Quota mode: none. Nov 02 07:39:04 volumio3 kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Nov 02 07:39:04 volumio3 systemd[1]: Inserted module 'autofs4' Nov 02 07:39:04 volumio3 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) Nov 02 07:39:04 volumio3 systemd[1]: Detected virtualization kvm. Nov 02 07:39:04 volumio3 systemd[1]: Detected architecture x86-64. Nov 02 07:39:04 volumio3 systemd[1]: Set hostname to . Nov 02 07:39:04 volumio3 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. Nov 02 07:39:04 volumio3 systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Nov 02 07:39:04 volumio3 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. Nov 02 07:39:04 volumio3 systemd[1]: Reached target System Time Synchronized. Nov 02 07:39:04 volumio3 systemd[1]: Listening on udev Control Socket. Nov 02 07:39:04 volumio3 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Nov 02 07:39:04 volumio3 systemd-journald[436]: Journal started Nov 02 07:39:04 volumio3 systemd-journald[436]: Runtime journal (/run/log/journal/5a1fb0a0895bdd08688b0a1963e27672) is 3.7M, max 30.0M, 26.2M free. Nov 02 07:39:04 volumio3 systemd[1]: Mounted Huge Pages File System. Nov 02 07:39:04 volumio3 systemd[1]: Mounted FUSE Control File System. Nov 02 07:39:04 volumio3 systemd[1]: Mounted POSIX Message Queue File System. Nov 02 07:39:04 volumio3 systemd[1]: Started Remount Root and Kernel File Systems. Nov 02 07:39:04 volumio3 systemd[1]: Mounted Kernel Debug File System. Nov 02 07:39:04 volumio3 systemd[1]: Mounted Kernel Configuration File System. Nov 02 07:39:04 volumio3 systemd[1]: Starting Create System Users... Nov 02 07:39:04 volumio3 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 02 07:39:04 volumio3 systemd[1]: Starting Load/Save Random Seed... Nov 02 07:39:04 volumio3 systemd[1]: Started Apply Kernel Variables. Nov 02 07:39:04 volumio3 fake-hwclock[447]: Current system time: 2024-11-02 11:39:04 Nov 02 07:39:04 volumio3 fake-hwclock[447]: fake-hwclock saved clock information is in the past: 2024-06-25 17:37:18 Nov 02 07:39:04 volumio3 fake-hwclock[447]: To set system time to this saved clock anyway, use "force" Nov 02 07:39:04 volumio3 systemd[1]: Started Restore / save the current clock. Nov 02 07:39:04 volumio3 systemd[1]: Mounted RPC Pipe File System. Nov 02 07:39:04 volumio3 kernel: RPC: Registered named UNIX socket transport module. Nov 02 07:39:04 volumio3 kernel: RPC: Registered udp transport module. Nov 02 07:39:04 volumio3 kernel: RPC: Registered tcp transport module. Nov 02 07:39:04 volumio3 kernel: RPC: Registered tcp-with-tls transport module. Nov 02 07:39:04 volumio3 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 02 07:39:04 volumio3 systemd[1]: Started Load/Save Random Seed. Nov 02 07:39:04 volumio3 systemd[1]: Started Create System Users. Nov 02 07:39:04 volumio3 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 02 07:39:04 volumio3 systemd[1]: dynamicswap.service: Succeeded. Nov 02 07:39:04 volumio3 systemd[1]: Started udev Coldplug all Devices. Nov 02 07:39:04 volumio3 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Nov 02 07:39:04 volumio3 systemd[1]: Started Create Static Device Nodes in /dev. Nov 02 07:39:04 volumio3 systemd[1]: Starting udev Kernel Device Manager... Nov 02 07:39:04 volumio3 systemd[1]: Reached target Local File Systems (Pre). Nov 02 07:39:04 volumio3 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 02 07:39:04 volumio3 systemd[1]: Mounting /tmp... Nov 02 07:39:04 volumio3 systemd[1]: Mounting /var/spool/cups... Nov 02 07:39:04 volumio3 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 02 07:39:04 volumio3 systemd[1]: Mounting /var/log... Nov 02 07:39:04 volumio3 systemd[1]: Mounted /tmp. Nov 02 07:39:04 volumio3 systemd[1]: Mounted /var/spool/cups. Nov 02 07:39:04 volumio3 systemd[1]: Started Helper to synchronize boot up for ifupdown. Nov 02 07:39:04 volumio3 systemd[1]: Mounted /var/log. Nov 02 07:39:04 volumio3 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 02 07:39:04 volumio3 systemd[1]: Mounting /var/spool/cups/tmp... Nov 02 07:39:04 volumio3 systemd[1]: Mounted /var/spool/cups/tmp. Nov 02 07:39:04 volumio3 systemd-udevd[470]: Network interface NamePolicy= disabled on kernel command line, ignoring. Nov 02 07:39:04 volumio3 systemd-journald[436]: Runtime journal (/run/log/journal/5a1fb0a0895bdd08688b0a1963e27672) is 3.7M, max 30.0M, 26.2M free. Nov 02 07:39:04 volumio3 systemd[1]: Started udev Kernel Device Manager. Nov 02 07:39:04 volumio3 systemd[1]: Starting Show Plymouth Boot Screen... Nov 02 07:39:04 volumio3 systemd[1]: Started Flush Journal to Persistent Storage. Nov 02 07:39:04 volumio3 systemd[1]: plymouth-start.service: Succeeded. Nov 02 07:39:04 volumio3 systemd[1]: Started Show Plymouth Boot Screen. Nov 02 07:39:04 volumio3 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 02 07:39:04 volumio3 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 02 07:39:04 volumio3 systemd[1]: Reached target Paths. Nov 02 07:39:04 volumio3 systemd[1]: Reached target Local Encrypted Volumes. Nov 02 07:39:04 volumio3 systemd-udevd[491]: Using default interface naming scheme 'v240'. Nov 02 07:39:04 volumio3 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Nov 02 07:39:04 volumio3 kernel: sr 1:0:0:0: Attached scsi generic sg1 type 5 Nov 02 07:39:04 volumio3 systemd-udevd[491]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 02 07:39:04 volumio3 systemd-udevd[480]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 02 07:39:04 volumio3 systemd-udevd[488]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:04 volumio3 systemd-udevd[482]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:04 volumio3 systemd-udevd[489]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:04 volumio3 systemd-udevd[487]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:04 volumio3 systemd-udevd[477]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:04 volumio3 systemd-udevd[492]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:04 volumio3 kernel: snd_hda_codec_generic hdaudioC0D0: autoconfig for Generic: line_outs=1 (0x3/0x0/0x0/0x0/0x0) type:speaker Nov 02 07:39:04 volumio3 kernel: snd_hda_codec_generic hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Nov 02 07:39:04 volumio3 kernel: snd_hda_codec_generic hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) Nov 02 07:39:04 volumio3 kernel: snd_hda_codec_generic hdaudioC0D0: mono: mono_out=0x0 Nov 02 07:39:04 volumio3 kernel: snd_hda_codec_generic hdaudioC0D0: inputs: Nov 02 07:39:04 volumio3 kernel: snd_hda_codec_generic hdaudioC0D0: Mic=0x5 Nov 02 07:39:05 volumio3 kernel: snd_hda_codec_generic hdaudioC0D1: autoconfig for Generic: line_outs=1 (0x3/0x0/0x0/0x0/0x0) type:line Nov 02 07:39:05 volumio3 kernel: snd_hda_codec_generic hdaudioC0D1: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Nov 02 07:39:05 volumio3 kernel: snd_hda_codec_generic hdaudioC0D1: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) Nov 02 07:39:05 volumio3 kernel: snd_hda_codec_generic hdaudioC0D1: mono: mono_out=0x0 Nov 02 07:39:05 volumio3 kernel: snd_hda_codec_generic hdaudioC0D1: inputs: Nov 02 07:39:05 volumio3 kernel: snd_hda_codec_generic hdaudioC0D1: Line=0x5 Nov 02 07:39:05 volumio3 kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Master Playback Volume:0 is already present Nov 02 07:39:05 volumio3 kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Master Playback Switch:0 is already present Nov 02 07:39:05 volumio3 kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Capture Volume:0 is already present Nov 02 07:39:05 volumio3 kernel: snd_hda_intel 0000:03:0c.0: control 2:0:0:Capture Switch:0 is already present Nov 02 07:39:05 volumio3 systemd-udevd[485]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:05 volumio3 kernel: alsactl[537]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Nov 02 07:39:05 volumio3 systemd-udevd[484]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 02 07:39:05 volumio3 systemd[1]: Found device QEMU_HARDDISK boot. Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Virtio network device being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Starting Show Plymouth Boot Screen... Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Starting File System Check on /dev/disk/by-uuid/6952-DF04... Nov 02 07:39:05 volumio3 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Nov 02 07:39:05 volumio3 systemd[1]: Started File System Check Daemon to report status. Nov 02 07:39:05 volumio3 systemd[1]: plymouth-start.service: Succeeded. Nov 02 07:39:05 volumio3 systemd[1]: Started Show Plymouth Boot Screen. Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 02 07:39:05 volumio3 systemd-fsck[543]: fsck.fat 4.1 (2017-01-24) Nov 02 07:39:05 volumio3 systemd-fsck[543]: /dev/sda1: 22 files, 20017/43727 clusters Nov 02 07:39:05 volumio3 systemd[1]: Started File System Check on /dev/disk/by-uuid/6952-DF04. Nov 02 07:39:05 volumio3 systemd[1]: Mounting /boot... Nov 02 07:39:05 volumio3 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Nov 02 07:39:05 volumio3 systemd[1]: Mounted /boot. Nov 02 07:39:05 volumio3 systemd[1]: Reached target Local File Systems. Nov 02 07:39:05 volumio3 systemd[1]: Started ifup for eth0. Nov 02 07:39:05 volumio3 systemd[1]: Starting Preprocess NFS configuration... Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 02 07:39:05 volumio3 systemd[1]: Starting Create Volatile Files and Directories... Nov 02 07:39:05 volumio3 systemd[1]: Starting Raise network interfaces... Nov 02 07:39:05 volumio3 systemd[1]: nfs-config.service: Succeeded. Nov 02 07:39:05 volumio3 systemd[1]: Started Preprocess NFS configuration. Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Reached target NFS client services. Nov 02 07:39:05 volumio3 systemd[1]: Received SIGRTMIN+20 from PID 331 (plymouthd). Nov 02 07:39:05 volumio3 systemd[1]: plymouth-read-write.service: Succeeded. Nov 02 07:39:05 volumio3 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 02 07:39:05 volumio3 systemd[1]: Started Create Volatile Files and Directories. Nov 02 07:39:05 volumio3 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 02 07:39:05 volumio3 systemd[1]: Starting RPC bind portmap service... Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Nov 02 07:39:05 volumio3 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Nov 02 07:39:05 volumio3 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Nov 02 07:39:05 volumio3 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Nov 02 07:39:05 volumio3 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 02 07:39:05 volumio3 systemd[1]: Started RPC bind portmap service. Nov 02 07:39:05 volumio3 systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 02 07:39:05 volumio3 systemd[1]: Reached target System Initialization. Nov 02 07:39:05 volumio3 sh[550]: command failed: No such device (-19) Nov 02 07:39:05 volumio3 ifup[554]: command failed: No such device (-19) Nov 02 07:39:05 volumio3 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 02 07:39:05 volumio3 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 02 07:39:05 volumio3 systemd[1]: Listening on triggerhappy.socket. Nov 02 07:39:05 volumio3 systemd[1]: Reached target Sockets. Nov 02 07:39:05 volumio3 systemd[1]: Reached target Basic System. Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Starting DHCP Client Daemon... Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Started Volumio Log Rotation Service. Nov 02 07:39:05 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 02 07:39:05 volumio3 systemd[1]: Started D-Bus System Message Bus. Nov 02 07:39:05 volumio3 systemd[1]: Started Daily apt download activities. Nov 02 07:39:05 volumio3 systemd[1]: Starting triggerhappy global hotkey daemon... Nov 02 07:39:05 volumio3 systemd[1]: Started Manage Sound Card State (restore and store). Nov 02 07:39:05 volumio3 alsactl[603]: alsactl 1.1.8 daemon started Nov 02 07:39:05 volumio3 systemd[1]: Starting Save/Restore Sound Card State... Nov 02 07:39:05 volumio3 systemd[1]: Starting WPA supplicant... Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Nov 02 07:39:05 volumio3 systemd[1]: Starting Wireless Services... Nov 02 07:39:05 volumio3 systemd[1]: Started Daily man-db regeneration. Nov 02 07:39:05 volumio3 systemd[1]: Started Daily apt upgrade and clean activities. Nov 02 07:39:05 volumio3 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 02 07:39:05 volumio3 systemd[1]: Reached target Timers. Nov 02 07:39:05 volumio3 systemd[1]: Starting Login Service... Nov 02 07:39:05 volumio3 systemd[1]: Started volumio-remote-updater.service. Nov 02 07:39:05 volumio3 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 02 07:39:05 volumio3 systemd[1]: Started Volumio Iptables Module. Nov 02 07:39:05 volumio3 systemd[1]: Reached target RPC Port Mapper. Nov 02 07:39:05 volumio3 systemd[1]: Reached target Remote File Systems (Pre). Nov 02 07:39:05 volumio3 systemd[1]: Reached target Remote File Systems. Nov 02 07:39:05 volumio3 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 02 07:39:05 volumio3 systemd[1]: Starting Daily man-db regeneration... Nov 02 07:39:05 volumio3 thd[602]: Unable to parse trigger line: Nov 02 07:39:05 volumio3 thd[602]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Nov 02 07:39:05 volumio3 thd[602]: Unable to parse trigger line: Nov 02 07:39:05 volumio3 thd[602]: Found socket passed from systemd Nov 02 07:39:05 volumio3 ifup[554]: command failed: No such device (-19) Nov 02 07:39:05 volumio3 systemd-logind[609]: New seat seat0. Nov 02 07:39:05 volumio3 systemd-logind[609]: Watching system buttons on /dev/input/event0 (Power Button) Nov 02 07:39:05 volumio3 systemd-logind[609]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 02 07:39:05 volumio3 avahi-daemon[611]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Nov 02 07:39:05 volumio3 avahi-daemon[611]: Successfully dropped root privileges. Nov 02 07:39:05 volumio3 avahi-daemon[611]: avahi-daemon 0.7 starting up. Nov 02 07:39:05 volumio3 kernel: 8021q: 802.1Q VLAN Support v1.8 Nov 02 07:39:05 volumio3 haveged[574]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Nov 02 07:39:05 volumio3 haveged[574]: haveged: cpu: (L4 VC); data: 32K (L2 L4 V); inst: 32K (L2 L4 V); idx: 24/40; sz: 31410/52825 Nov 02 07:39:05 volumio3 haveged[574]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99776 Nov 02 07:39:05 volumio3 haveged[574]: haveged: fills: 0, generated: 0 Nov 02 07:39:05 volumio3 systemd[1]: Started triggerhappy global hotkey daemon. Nov 02 07:39:05 volumio3 systemd[1]: Started Save/Restore Sound Card State. Nov 02 07:39:05 volumio3 wpa_supplicant[605]: Successfully initialized wpa_supplicant Nov 02 07:39:05 volumio3 kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Nov 02 07:39:05 volumio3 sh[550]: eth0: waiting for carrier Nov 02 07:39:05 volumio3 sh[550]: eth0: carrier acquired Nov 02 07:39:05 volumio3 dhcpcd[591]: no interfaces have a carrier Nov 02 07:39:05 volumio3 dhcpcd[591]: forked to background, child pid 682 Nov 02 07:39:05 volumio3 avahi-daemon[611]: Successfully called chroot(). Nov 02 07:39:05 volumio3 systemd[1]: Started DHCP Client Daemon. Nov 02 07:39:05 volumio3 sh[550]: DUID 00:04:38:47:50:66:9c:ce:43:b3:a9:87:7b:ee:e9:7f:bb:11 Nov 02 07:39:05 volumio3 sh[550]: eth0: IAID bf:8b:ad:bb Nov 02 07:39:05 volumio3 avahi-daemon[611]: Successfully dropped remaining capabilities. Nov 02 07:39:05 volumio3 avahi-daemon[611]: Loading service file /services/volumio.service. Nov 02 07:39:05 volumio3 avahi-daemon[611]: Network interface enumeration completed. Nov 02 07:39:05 volumio3 avahi-daemon[611]: Server startup complete. Host name is volumio3.local. Local service cookie is 4102204223. Nov 02 07:39:05 volumio3 avahi-daemon[611]: Service "Volumio3" (/services/volumio.service) successfully established. Nov 02 07:39:05 volumio3 dhcpcd[600]: eth0: waiting for carrier Nov 02 07:39:05 volumio3 dhcpcd[600]: eth0: carrier acquired Nov 02 07:39:05 volumio3 dhcpcd[591]: no interfaces have a carrier Nov 02 07:39:05 volumio3 dhcpcd[591]: forked to background, child pid 682 Nov 02 07:39:05 volumio3 dhcpcd[682]: eth0: waiting for carrier Nov 02 07:39:05 volumio3 dhcpcd[682]: eth0: carrier acquired Nov 02 07:39:05 volumio3 dhcpcd[600]: DUID 00:04:38:47:50:66:9c:ce:43:b3:a9:87:7b:ee:e9:7f:bb:11 Nov 02 07:39:05 volumio3 dhcpcd[600]: eth0: IAID bf:8b:ad:bb Nov 02 07:39:05 volumio3 dhcpcd[682]: DUID 00:04:38:47:50:66:9c:ce:43:b3:a9:87:7b:ee:e9:7f:bb:11 Nov 02 07:39:05 volumio3 dhcpcd[682]: eth0: IAID bf:8b:ad:bb Nov 02 07:39:05 volumio3 dhcpcd[682]: eth0: adding address fe80::b807:db51:14dc:caca Nov 02 07:39:05 volumio3 dhcpcd[682]: ipv6_addaddr1: Permission denied Nov 02 07:39:05 volumio3 sh[550]: eth0: adding address fe80::b807:db51:14dc:caca Nov 02 07:39:05 volumio3 sh[550]: ipv6_addaddr1: Permission denied Nov 02 07:39:05 volumio3 dhcpcd[600]: eth0: adding address fe80::b807:db51:14dc:caca Nov 02 07:39:05 volumio3 dhcpcd[600]: ipv6_addaddr1: Permission denied Nov 02 07:39:05 volumio3 systemd[1]: Started Login Service. Nov 02 07:39:05 volumio3 systemd[1]: Started WPA supplicant. Nov 02 07:39:05 volumio3 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 02 07:39:05 volumio3 systemd[1]: Reached target Sound Card. Nov 02 07:39:05 volumio3 systemd[1]: Started Raise network interfaces. Nov 02 07:39:05 volumio3 systemd[1]: Reached target Network. Nov 02 07:39:05 volumio3 systemd[1]: Starting Permit User Sessions... Nov 02 07:39:05 volumio3 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 02 07:39:05 volumio3 volumio-remote-updater[610]: Error: No active session Nov 02 07:39:05 volumio3 volumio-remote-updater[610]: [2024-11-02 07:39:05] [info] asio async_connect error: system:111 (Connection refused) Nov 02 07:39:05 volumio3 volumio-remote-updater[610]: [2024-11-02 07:39:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 02 07:39:05 volumio3 systemd[1]: Starting OpenBSD Secure Shell server... Nov 02 07:39:05 volumio3 systemd[1]: Starting Network Time Service... Nov 02 07:39:05 volumio3 systemd[1]: Reached target Network is Online. Nov 02 07:39:05 volumio3 volumio-remote-updater[610]: [2024-11-02 07:39:05] [error] handle_connect error: Underlying Transport Error Nov 02 07:39:05 volumio3 systemd[1]: Starting Daily apt download activities... Nov 02 07:39:05 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:39:05 volumio3 systemd[1]: Starting LSB: Brings up/down network automatically... Nov 02 07:39:05 volumio3 systemd[1]: Starting /etc/rc.local Compatibility... Nov 02 07:39:05 volumio3 systemd[1]: Starting Samba NMB Daemon... Nov 02 07:39:05 volumio3 systemd[1]: Started Permit User Sessions. Nov 02 07:39:05 volumio3 systemd[1]: Started /etc/rc.local Compatibility. Nov 02 07:39:05 volumio3 systemd[1]: Starting Hold until boot process finishes up... Nov 02 07:39:05 volumio3 systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 02 07:39:05 volumio3 volumio[597]: Could not open config: /tmp/upmpdcli.conf Nov 02 07:39:05 volumio3 systemd[1]: Received SIGRTMIN+21 from PID 331 (plymouthd). Nov 02 07:39:05 volumio3 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 02 07:39:05 volumio3 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 02 07:39:06 volumio3 systemd[1]: plymouth-quit.service: Succeeded. Nov 02 07:39:06 volumio3 systemd[1]: Started Terminate Plymouth Boot Screen. Nov 02 07:39:06 volumio3 systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 02 07:39:06 volumio3 systemd[1]: Started Hold until boot process finishes up. Nov 02 07:39:06 volumio3 systemd[1]: Received SIGRTMIN+21 from PID 331 (n/a). Nov 02 07:39:06 volumio3 systemd[1]: Started Getty on tty1. Nov 02 07:39:06 volumio3 systemd[1]: Reached target Login Prompts. Nov 02 07:39:06 volumio3 sh[550]: eth0: soliciting a DHCP lease Nov 02 07:39:06 volumio3 dhcpcd[600]: eth0: soliciting a DHCP lease Nov 02 07:39:06 volumio3 ifplugd(eth0)[770]: ifplugd 0.28 initializing. Nov 02 07:39:06 volumio3 ifplugd(eth0)[770]: Using interface eth0/DE:DC:BF:8B:AD:BB with driver (version: 1.0.0) Nov 02 07:39:06 volumio3 ifplugd(eth0)[770]: Using detection mode: SIOCETHTOOL Nov 02 07:39:06 volumio3 ifplugd(eth0)[770]: Initialization complete, link beat detected. Nov 02 07:39:06 volumio3 loadcpufreq[616]: Loading cpufreq kernel modules...failed. Nov 02 07:39:06 volumio3 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 02 07:39:06 volumio3 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 02 07:39:06 volumio3 systemd[1]: iptables.service: Succeeded. Nov 02 07:39:06 volumio3 systemd[1]: man-db.service: Succeeded. Nov 02 07:39:06 volumio3 systemd[1]: Started Daily man-db regeneration. Nov 02 07:39:06 volumio3 ntpd[717]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 02 07:39:06 volumio3 ntpd[717]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 02 07:39:06 volumio3 systemd[1]: Started Network Time Service. Nov 02 07:39:06 volumio3 ntpd[778]: proto: precision = 0.059 usec (-24) Nov 02 07:39:06 volumio3 sshd[782]: Server listening on 0.0.0.0 port 22. Nov 02 07:39:06 volumio3 sshd[782]: Server listening on :: port 22. Nov 02 07:39:06 volumio3 systemd[1]: Started OpenBSD Secure Shell server. Nov 02 07:39:06 volumio3 ifplugd(eth0)[770]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 02 07:39:06 volumio3 cpufrequtils[775]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Nov 02 07:39:06 volumio3 systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 02 07:39:06 volumio3 ntpd[778]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 02 07:39:06 volumio3 ntpd[778]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 02 07:39:06 volumio3 ntpd[778]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 676 days ago Nov 02 07:39:06 volumio3 ntpd[778]: Listen and drop on 0 v6wildcard [::]:123 Nov 02 07:39:06 volumio3 ntpd[778]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 02 07:39:06 volumio3 ntpd[778]: Listen normally on 2 lo 127.0.0.1:123 Nov 02 07:39:06 volumio3 ntpd[778]: Listening on routing socket on fd #19 for interface updates Nov 02 07:39:06 volumio3 ntpd[778]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 02 07:39:06 volumio3 ntpd[778]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 02 07:39:06 volumio3 ifplugd(eth0)[770]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Nov 02 07:39:06 volumio3 dhcpcd[682]: eth0: soliciting a DHCP lease Nov 02 07:39:06 volumio3 dhcpcd[682]: eth0: soliciting an IPv6 router Nov 02 07:39:06 volumio3 nmbd[725]: [2024/11/02 07:39:06.519739, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 02 07:39:06 volumio3 nmbd[725]: started asyncdns process 816 Nov 02 07:39:06 volumio3 nmbd[725]: [2024/11/02 07:39:06.520709, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 02 07:39:06 volumio3 nmbd[725]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 02 07:39:06 volumio3 nmbd[725]: [2024/11/02 07:39:06.520762, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 02 07:39:06 volumio3 nmbd[725]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 02 07:39:06 volumio3 wireless.js[607]: WIRELESS: No wireless interface, exiting Nov 02 07:39:06 volumio3 iw[821]: command failed: No such device (-19) Nov 02 07:39:06 volumio3 iwconfig[824]: Error for wireless request "Set Power Management" (8B2C) : Nov 02 07:39:06 volumio3 iwconfig[824]: SET failed on device wlan0 ; No such device. Nov 02 07:39:06 volumio3 systemd[1]: wireless.service: Succeeded. Nov 02 07:39:06 volumio3 systemd[1]: Started Wireless Services. Nov 02 07:39:06 volumio3 systemd[1]: Started Volumio Backend Module. Nov 02 07:39:06 volumio3 dhcpcd[600]: eth0: soliciting an IPv6 router Nov 02 07:39:06 volumio3 systemd[1]: Started Volumio Cpu Tweaker. Nov 02 07:39:06 volumio3 sh[550]: eth0: soliciting an IPv6 router Nov 02 07:39:06 volumio3 systemd[1]: Started Intel SST and HDA soundcard init service. Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: Setting RT Priority for mpd Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: pid 35's current scheduling policy: SCHED_FIFO Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: pid 35's current scheduling priority: 99 Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: Setting MPD Affinity Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: pid 3's current affinity mask: f Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: Error setting new values. Common errors: Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: - Do you have proper administration rights? (super-user?) Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: - Is the governor you requested available and modprobed? Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: - Trying to set an invalid policy? Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: - Trying to set a specific frequency, but userspace governor is not available, Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: for example because of hardware which cannot be set to a specific frequency Nov 02 07:39:06 volumio3 volumio-cpu-tweak[828]: or because the userspace governor isn't loaded? Nov 02 07:39:06 volumio3 systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 02 07:39:06 volumio3 systemd[1]: soundcard-init.service: Succeeded. Nov 02 07:39:07 volumio3 ntpd[778]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 02 07:39:07 volumio3 volumio[827]: info: ------------------------------------------- Nov 02 07:39:07 volumio3 volumio[827]: info: ----- Volumio3 ---- Nov 02 07:39:07 volumio3 volumio[827]: info: ------------------------------------------- Nov 02 07:39:07 volumio3 volumio[827]: info: ----- System startup ---- Nov 02 07:39:07 volumio3 volumio[827]: info: ------------------------------------------- Nov 02 07:39:07 volumio3 volumio[827]: info: MYVOLUMIO Environment detected Nov 02 07:39:07 volumio3 volumio[827]: info: Plugin folders cleanup Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning into folder /volumio/app/plugins/ Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category audio_interface Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category miscellanea Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category music_service Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category plugins.json Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category system_controller Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category user_interface Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning into folder /data/plugins/ Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category audio_interface Nov 02 07:39:07 volumio3 volumio[827]: info: Scanning category music_service Nov 02 07:39:07 volumio3 volumio[827]: info: Plugin folders cleanup completed Nov 02 07:39:07 volumio3 volumio[827]: info: ------------------------------------------- Nov 02 07:39:07 volumio3 volumio[827]: info: ----- Core plugins startup ---- Nov 02 07:39:07 volumio3 volumio[827]: info: ------------------------------------------- Nov 02 07:39:07 volumio3 volumio[827]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 07:39:07 volumio3 volumio[827]: info: Adding plugin upnp to MyMusic Plugins Nov 02 07:39:07 volumio3 volumio[827]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 07:39:07 volumio3 volumio[827]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 07:39:07 volumio3 volumio[827]: info: Loading plugins from folder /data/plugins/ Nov 02 07:39:07 volumio3 volumio[827]: info: Loading plugin "system"... Nov 02 07:39:07 volumio3 volumio[827]: info: Loading plugin "appearance"... Nov 02 07:39:08 volumio3 ntpd[778]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "network"... Nov 02 07:39:08 volumio3 volumio[827]: info: Refreshing Cached IP Addresses Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "services"... Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "alsa_controller"... Nov 02 07:39:08 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 07:39:08 volumio3 sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:39:08 volumio3 sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 07:39:08 volumio3 sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:08 volumio3 sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:39:08 volumio3 sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "wizard"... Nov 02 07:39:08 volumio3 sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:08 volumio3 sudo[947]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "networkfs"... Nov 02 07:39:08 volumio3 sudo[949]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:08 volumio3 sudo[951]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:08 volumio3 volumio[827]: info: Starting Udev Watcher for removable devices Nov 02 07:39:08 volumio3 sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=randbvolumio!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.58/Media/Music /mnt/NAS/MUSIC Nov 02 07:39:08 volumio3 sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:08 volumio3 volumio[827]: info: Ignoring mount for partition: boot Nov 02 07:39:08 volumio3 volumio[827]: info: Ignoring mount for partition: volumio Nov 02 07:39:08 volumio3 volumio[827]: info: Ignoring mount for partition: volumio_data Nov 02 07:39:08 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "volumio_command_line_client"... Nov 02 07:39:08 volumio3 volumio[827]: info: Plugin upnp is not enabled Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "my_music"... Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "mpd"... Nov 02 07:39:08 volumio3 kernel: FS-Cache: Loaded Nov 02 07:39:08 volumio3 kernel: Key type dns_resolver registered Nov 02 07:39:08 volumio3 kernel: Key type cifs.spnego registered Nov 02 07:39:08 volumio3 kernel: Key type cifs.idmap registered Nov 02 07:39:08 volumio3 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. Nov 02 07:39:08 volumio3 kernel: CIFS: Attempting to mount //10.0.0.58/Media/Music Nov 02 07:39:08 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 02 07:39:08 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Nov 02 07:39:08 volumio3 sudo[962]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:08 volumio3 volumio[827]: info: Plugin upnp_browser is not enabled Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "alarm-clock"... Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "airplay_emulation"... Nov 02 07:39:08 volumio3 volumio[827]: info: Starting Shairport Sync Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "last_100"... Nov 02 07:39:08 volumio3 volumio[827]: info: Loading plugin "webradio"... Nov 02 07:39:09 volumio3 ntpd[778]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 02 07:39:09 volumio3 volumio[827]: info: Loading plugin "i2s_dacs"... Nov 02 07:39:09 volumio3 volumio[827]: info: I2S DAC not set, start Auto-detection Nov 02 07:39:09 volumio3 volumio[827]: info: Loading plugin "volumiodiscovery"... Nov 02 07:39:09 volumio3 sh[550]: eth0: probing address 10.0.0.127/24 Nov 02 07:39:09 volumio3 dhcpcd[600]: eth0: probing address 10.0.0.127/24 Nov 02 07:39:09 volumio3 dhcpcd[682]: eth0: probing address 10.0.0.127/24 Nov 02 07:39:10 volumio3 ntpd[778]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 02 07:39:10 volumio3 volumio[827]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:39:10 volumio3 volumio[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:39:10 volumio3 volumio[827]: *** WARNING *** For more information see Nov 02 07:39:10 volumio3 volumio[827]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:39:10 volumio3 volumio[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:39:10 volumio3 volumio[827]: *** WARNING *** For more information see Nov 02 07:39:10 volumio3 node[827]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:39:10 volumio3 node[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:39:10 volumio3 node[827]: *** WARNING *** For more information see Nov 02 07:39:10 volumio3 node[827]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:39:10 volumio3 node[827]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:39:10 volumio3 node[827]: *** WARNING *** For more information see Nov 02 07:39:10 volumio3 volumio[827]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 07:39:10 volumio3 volumio[827]: info: Discovery: Started advertising with name: Volumio3 Nov 02 07:39:10 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:39:10 volumio3 volumio[827]: info: Loading plugin "spop"... Nov 02 07:39:10 volumio3 systemd[1]: apt-daily.service: Succeeded. Nov 02 07:39:10 volumio3 systemd[1]: Started Daily apt download activities. Nov 02 07:39:10 volumio3 systemd[1]: Starting Daily apt upgrade and clean activities... Nov 02 07:39:10 volumio3 volumio-remote-updater[610]: [2024-11-02 07:39:10] [connect] Successful connection Nov 02 07:39:11 volumio3 volumio[827]: info: Loading plugin "outputs"... Nov 02 07:39:11 volumio3 volumio[827]: info: Loading plugin "albumart"... Nov 02 07:39:11 volumio3 volumio[827]: info: Plugin example_plugin is not enabled Nov 02 07:39:11 volumio3 volumio[827]: info: Loading plugin "inputs"... Nov 02 07:39:11 volumio3 volumio[827]: info: Loading plugin "updater_comm"... Nov 02 07:39:11 volumio3 volumio[827]: Forking 3 albumart workers Nov 02 07:39:11 volumio3 volumio[827]: info: Plugin mpdemulation is not enabled Nov 02 07:39:11 volumio3 volumio[827]: info: Loading plugin "rest_api"... Nov 02 07:39:11 volumio3 volumio[827]: info: Loading plugin "websocket"... Nov 02 07:39:11 volumio3 volumio[827]: info: Starting Socket.io Server version 2.3.0 Nov 02 07:39:11 volumio3 volumio[827]: info: Loading plugin "snapserver"... Nov 02 07:39:11 volumio3 volumio[827]: Starting albumart workers Nov 02 07:39:11 volumio3 volumio[827]: Starting albumart workers Nov 02 07:39:11 volumio3 volumio[827]: Starting albumart workers Nov 02 07:39:11 volumio3 volumio[827]: info: Applying required configuration parameters for plugin snapserver Nov 02 07:39:11 volumio3 systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 02 07:39:11 volumio3 systemd[1]: Started Daily apt upgrade and clean activities. Nov 02 07:39:11 volumio3 volumio[827]: info: Loading i18n strings for locale en Nov 02 07:39:11 volumio3 volumio[827]: Updating browse sources language Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::initPlayerControls Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: Express server listening on port 3000 Nov 02 07:39:11 volumio3 volumio[827]: [Metrics] WebUI: 4s 253.07ms Nov 02 07:39:11 volumio3 volumio[827]: info: Setting Device type: x86 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::resetVolumioState Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::getcurrentVolume Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 02 07:39:11 volumio3 volumio[827]: info: Completed loading Core Plugins Nov 02 07:39:11 volumio3 volumio[827]: info: Preparing to generate the ALSA configuration file Nov 02 07:39:11 volumio3 volumio[827]: info: Cannot mount NAS MUSIC at system boot, trial number 1 ,retrying in 5 seconds Nov 02 07:39:11 volumio3 volumio[827]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 02 07:39:11 volumio3 volumio[827]: wlan0 Interface doesn't support scanning. Nov 02 07:39:11 volumio3 volumio[827]: info: Cannot use regular scanning, forcing with ap-force Nov 02 07:39:11 volumio3 sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 02 07:39:11 volumio3 sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 sudo[1108]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 volumio[827]: command failed: No such device (-19) Nov 02 07:39:11 volumio3 volumio[827]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 02 07:39:11 volumio3 volumio[827]: command failed: No such device (-19) Nov 02 07:39:11 volumio3 volumio[827]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 02 07:39:11 volumio3 volumio[827]: info: The plugin snapserver has an ALSA contribution file multiRoomPlayback.ignore.1.conf Nov 02 07:39:11 volumio3 volumio[827]: info: Reading ALSA contributions from plugins. Nov 02 07:39:11 volumio3 volumio-remote-updater[610]: [2024-11-02 07:39:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730547550 101 Nov 02 07:39:11 volumio3 volumio[827]: 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: 1 Nov 02 07:39:11 volumio3 volumio[827]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 02 07:39:11 volumio3 volumio[827]: info: Reloading queue from file Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::setRepeat null single undefined Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::pushState Nov 02 07:39:11 volumio3 volumio[827]: info: CorePlayQueue::getTrack 0 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioPushState Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::setRandom null Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::pushState Nov 02 07:39:11 volumio3 volumio[827]: info: CorePlayQueue::getTrack 0 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioPushState Nov 02 07:39:11 volumio3 volumio[827]: info: VolumeController:: Volume=undefined Mute =false Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::pushState Nov 02 07:39:11 volumio3 volumio[827]: info: CorePlayQueue::getTrack 0 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioPushState Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::updateTrackBlock Nov 02 07:39:11 volumio3 volumio[827]: info: CorePlayQueue::getTrackBlock Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 07:39:11 volumio3 volumio[827]: info: Asound.conf file unchanged, so no further update is needed Nov 02 07:39:11 volumio3 volumio[827]: info: Output device has changed, restarting MPD Nov 02 07:39:11 volumio3 sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 07:39:11 volumio3 sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 sudo[1119]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 volumio[827]: info: Output device has changed, restarting Shairport Sync Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:11 volumio3 sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 07:39:11 volumio3 sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 systemd[1]: Listening on mpd.socket. Nov 02 07:39:11 volumio3 volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:39:11 volumio3 volumio[827]: info: ___________ START PLUGINS ___________ Nov 02 07:39:11 volumio3 volumio[827]: info: ControllerMpd::onStart: Initializing MPD Nov 02 07:39:11 volumio3 volumio[827]: info: Creating MPD Configuration file Nov 02 07:39:11 volumio3 sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 07:39:11 volumio3 sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:11 volumio3 sudo[1129]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 07:39:11 volumio3 systemd[1]: Starting Music Player Daemon... Nov 02 07:39:11 volumio3 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:11 volumio3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 07:39:11 volumio3 systemd[1]: mpd.service: Succeeded. Nov 02 07:39:11 volumio3 systemd[1]: Stopped Music Player Daemon. Nov 02 07:39:11 volumio3 systemd[1]: Starting Music Player Daemon... Nov 02 07:39:11 volumio3 volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:39:11 volumio3 volumio[827]: info: [1730547551613] CoreMusicLibrary::Adding element Last_100 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:39:11 volumio3 volumio[827]: info: [1730547551615] CoreMusicLibrary::Adding element Webradio Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 07:39:11 volumio3 volumio[827]: info: Initializing BBC Radios Nov 02 07:39:11 volumio3 sudo[1137]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 07:39:11 volumio3 sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 sudo[1137]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 02 07:39:11 volumio3 sudo[1137]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: Creating Spotify config file Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: Volumio Calling Home Nov 02 07:39:11 volumio3 sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapserver Nov 02 07:39:11 volumio3 sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 systemd[1]: Started Snapcast server. Nov 02 07:39:11 volumio3 sudo[1154]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 volumio[827]: info: SnapServer started Nov 02 07:39:11 volumio3 volumio[827]: info: MPD Permissions set Nov 02 07:39:11 volumio3 volumio[827]: info: MPD Permissions set Nov 02 07:39:11 volumio3 volumio[827]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 02 07:39:11 volumio3 volumio[827]: info: Spotify config file written Nov 02 07:39:11 volumio3 snapserver[1158]: Version 0.27.0, revision 54a3d862 Nov 02 07:39:11 volumio3 snapserver[1158]: Adding source: pipe:///tmp/snapfifo?name=default Nov 02 07:39:11 volumio3 snapserver[1158]: Settings file: "/var/lib/snapserver/server.json" Nov 02 07:39:11 volumio3 volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 02 07:39:11 volumio3 snapserver[1158]: Using HTTP host name: volumio3 Nov 02 07:39:11 volumio3 sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 02 07:39:11 volumio3 sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 snapserver[1158]: Adding service 'Snapcast' Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:11 volumio3 snapserver[1158]: PcmStream: default, sampleFormat: 48000:16:2 Nov 02 07:39:11 volumio3 snapserver[1158]: PipeStream mode: create Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 07:39:11 volumio3 snapserver[1158]: Stream: {"fragment":"","host":"","path":"/tmp/snapfifo","query":{"chunk_ms":"20","codec":"flac","name":"default","sampleformat":"48000:16:2"},"raw":"pipe:////tmp/snapfifo?chunk_ms=20&codec=flac&name=default&sampleformat=48000:16:2","scheme":"pipe"} Nov 02 07:39:11 volumio3 snapserver[1158]: Init - compression level: 2 Nov 02 07:39:11 volumio3 snapserver[1158]: Exception: end of file Nov 02 07:39:11 volumio3 snapserver[1158]: Creating TCP acceptor for address: 0.0.0.0, port: 1705 Nov 02 07:39:11 volumio3 snapserver[1158]: Creating HTTP acceptor for address: 0.0.0.0, port: 1780 Nov 02 07:39:11 volumio3 snapserver[1158]: Creating stream acceptor for address: 0.0.0.0, port: 1704 Nov 02 07:39:11 volumio3 snapserver[1158]: Number of threads: 4, hw threads: 4 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 systemd[1]: Started go-librespot Daemon. Nov 02 07:39:11 volumio3 go-librespot[1168]: Librespot-go daemon starting... Nov 02 07:39:11 volumio3 sudo[1162]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:39:11 volumio3 volumio[827]: info: VolumeController:: Volume=undefined Mute =false Nov 02 07:39:11 volumio3 volumio[827]: info: CoreStateMachine::pushState Nov 02 07:39:11 volumio3 volumio[827]: info: CorePlayQueue::getTrack 0 Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioPushState Nov 02 07:39:11 volumio3 volumio[827]: info: Starting Shairport Sync Nov 02 07:39:11 volumio3 volumio[827]: info: Starting Shairport Sync Nov 02 07:39:11 volumio3 volumio[827]: info: Starting Shairport Sync Nov 02 07:39:11 volumio3 sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:39:11 volumio3 sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:39:11 volumio3 sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:39:11 volumio3 sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:11 volumio3 snapserver[1158]: Service 'Snapcast' successfully established. Nov 02 07:39:11 volumio3 volumio[827]: info: CoreCommandRouter::volumioGetState Nov 02 07:39:11 volumio3 volumio[827]: info: CorePlayQueue::getTrack 0 Nov 02 07:39:11 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 07:39:11 volumio3 systemd[1]: shairport-sync.service: Succeeded. Nov 02 07:39:11 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 07:39:11 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:39:11 volumio3 sudo[1184]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 sudo[1192]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 sudo[1187]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:11 volumio3 volumio[827]: info: Shairport-Sync Started Nov 02 07:39:11 volumio3 volumio[827]: Error adding Membership: Error: addMembership EINVAL Nov 02 07:39:11 volumio3 volumio[827]: info: Shairport-Sync Started Nov 02 07:39:11 volumio3 volumio[827]: info: Shairport-Sync Started Nov 02 07:39:11 volumio3 go-librespot[1168]: time="2024-11-02T07:39:11-04:00" level=info msg="generated new device id: e427859fc420a1345ed5406d82d554af8a348a8a" Nov 02 07:39:11 volumio3 go-librespot[1168]: time="2024-11-02T07:39:11-04:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 02 07:39:11 volumio3 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 07:39:11 volumio3 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 07:39:12 volumio3 mpd[1145]: Nov 02 07:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 07:39:12 volumio3 systemd[1]: Started Music Player Daemon. Nov 02 07:39:12 volumio3 sudo[1122]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:12 volumio3 sudo[1131]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:12 volumio3 volumio[827]: info: Completed starting Core Plugins Nov 02 07:39:12 volumio3 volumio[827]: info: ------------------------------------------- Nov 02 07:39:12 volumio3 volumio[827]: info: ----- MyVolumio plugins startup ---- Nov 02 07:39:12 volumio3 volumio[827]: info: ------------------------------------------- Nov 02 07:39:12 volumio3 volumio[827]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 07:39:12 volumio3 volumio[827]: error: MPD error: The expression evaluated to a falsy value: Nov 02 07:39:12 volumio3 volumio[827]: assert.ok(self.idling) Nov 02 07:39:12 volumio3 volumio[827]: error: The expression evaluated to a falsy value: Nov 02 07:39:12 volumio3 volumio[827]: assert.ok(self.idling) Nov 02 07:39:12 volumio3 volumio[827]: error: updateQueue error: null Nov 02 07:39:12 volumio3 volumio[827]: info: MPD running with PID1145 Nov 02 07:39:12 volumio3 volumio[827]: ,establishing connection Nov 02 07:39:12 volumio3 volumio[827]: error: updateQueue error: null Nov 02 07:39:13 volumio3 dhcpcd[682]: eth0: leased 10.0.0.127 for 604800 seconds Nov 02 07:39:13 volumio3 dhcpcd[682]: eth0: adding route to 10.0.0.0/24 Nov 02 07:39:13 volumio3 avahi-daemon[611]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.0.127. Nov 02 07:39:13 volumio3 dhcpcd[682]: eth0: adding default route via 10.0.0.1 Nov 02 07:39:13 volumio3 avahi-daemon[611]: New relevant interface eth0.IPv4 for mDNS. Nov 02 07:39:13 volumio3 avahi-daemon[611]: Registering new address record for 10.0.0.127 on eth0.IPv4. Nov 02 07:39:13 volumio3 ntpd[778]: ntpd exiting on signal 15 (Terminated) Nov 02 07:39:13 volumio3 systemd[1]: Stopping Network Time Service... Nov 02 07:39:13 volumio3 systemd[1]: ntp.service: Succeeded. Nov 02 07:39:13 volumio3 systemd[1]: Stopped Network Time Service. Nov 02 07:39:13 volumio3 systemd[1]: Starting Network Time Service... Nov 02 07:39:13 volumio3 ntpd[1237]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 02 07:39:13 volumio3 ntpd[1237]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 02 07:39:13 volumio3 systemd[1]: Started Network Time Service. Nov 02 07:39:13 volumio3 ntpd[1243]: proto: precision = 0.049 usec (-24) Nov 02 07:39:13 volumio3 ntpd[1243]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 02 07:39:13 volumio3 ntpd[1243]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 02 07:39:13 volumio3 ntpd[1243]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 676 days ago Nov 02 07:39:13 volumio3 ntpd[1243]: Listen and drop on 0 v6wildcard [::]:123 Nov 02 07:39:13 volumio3 ntpd[1243]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 02 07:39:13 volumio3 ntpd[1243]: Listen normally on 2 lo 127.0.0.1:123 Nov 02 07:39:13 volumio3 ntpd[1243]: Listen normally on 3 eth0 10.0.0.127:123 Nov 02 07:39:13 volumio3 ntpd[1243]: Listening on routing socket on fd #20 for interface updates Nov 02 07:39:13 volumio3 ntpd[1243]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 02 07:39:13 volumio3 ntpd[1243]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 02 07:39:14 volumio3 snapserver[1158]: Service name collision, renaming service to 'Snapcast #2' Nov 02 07:39:14 volumio3 snapserver[1158]: Adding service 'Snapcast #2' Nov 02 07:39:14 volumio3 sh[550]: eth0: leased 10.0.0.127 for 604800 seconds Nov 02 07:39:14 volumio3 dhcpcd[600]: eth0: leased 10.0.0.127 for 604800 seconds Nov 02 07:39:14 volumio3 sh[550]: eth0: adding route to 10.0.0.0/24 Nov 02 07:39:14 volumio3 sh[550]: eth0: adding default route via 10.0.0.1 Nov 02 07:39:14 volumio3 dhcpcd[600]: eth0: adding route to 10.0.0.0/24 Nov 02 07:39:14 volumio3 dhcpcd[600]: eth0: adding default route via 10.0.0.1 Nov 02 07:39:14 volumio3 ntpd[1243]: ntpd exiting on signal 15 (Terminated) Nov 02 07:39:14 volumio3 systemd[1]: Stopping Network Time Service... Nov 02 07:39:14 volumio3 systemd[1]: ntp.service: Succeeded. Nov 02 07:39:14 volumio3 systemd[1]: Stopped Network Time Service. Nov 02 07:39:14 volumio3 systemd[1]: Starting Network Time Service... Nov 02 07:39:14 volumio3 ntpd[1279]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 02 07:39:14 volumio3 ntpd[1279]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 02 07:39:14 volumio3 systemd[1]: Started Network Time Service. Nov 02 07:39:14 volumio3 sh[550]: forked to background, child pid 1286 Nov 02 07:39:14 volumio3 ntpd[1285]: proto: precision = 0.060 usec (-24) Nov 02 07:39:14 volumio3 dhcpcd[600]: forked to background, child pid 1286 Nov 02 07:39:14 volumio3 ntpd[1285]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 02 07:39:14 volumio3 ntpd[1285]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 02 07:39:14 volumio3 ntpd[1285]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 676 days ago Nov 02 07:39:14 volumio3 ntpd[1285]: Listen and drop on 0 v6wildcard [::]:123 Nov 02 07:39:14 volumio3 ntpd[1285]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 02 07:39:14 volumio3 ntpd[1285]: Listen normally on 2 lo 127.0.0.1:123 Nov 02 07:39:14 volumio3 ntpd[1285]: Listen normally on 3 eth0 10.0.0.127:123 Nov 02 07:39:14 volumio3 ntpd[1285]: Listening on routing socket on fd #20 for interface updates Nov 02 07:39:14 volumio3 ntpd[1285]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 02 07:39:14 volumio3 ntpd[1285]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 02 07:39:14 volumio3 ifplugd(eth0)[770]: client: ifup: interface eth0 already configured Nov 02 07:39:14 volumio3 sh[550]: eth0=eth0 Nov 02 07:39:14 volumio3 ifplugd(eth0)[770]: Program executed successfully. Nov 02 07:39:14 volumio3 ifplugd[721]: Network Interface Plugging Daemon...start eth0...done. Nov 02 07:39:14 volumio3 systemd[1]: Started LSB: Brings up/down network automatically. Nov 02 07:39:14 volumio3 volumio[827]: info: Discovery: adding f906ab95-2bdc-456f-96b1-1937cee13499 Nov 02 07:39:14 volumio3 volumio[827]: info: Discovery: Found device Volumio3 Nov 02 07:39:14 volumio3 volumio[827]: info: CoreCommandRouter::volumioGetState Nov 02 07:39:14 volumio3 volumio[827]: info: CorePlayQueue::getTrack 0 Nov 02 07:39:14 volumio3 volumio[827]: info: go-librespot daemon successfully initialized Nov 02 07:39:15 volumio3 snapserver[1158]: Service 'Snapcast #2' successfully established. Nov 02 07:39:15 volumio3 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 02 07:39:15 volumio3 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Nov 02 07:39:15 volumio3 systemd[1]: Stopped go-librespot Daemon. Nov 02 07:39:15 volumio3 systemd[1]: Started go-librespot Daemon. Nov 02 07:39:15 volumio3 go-librespot[1313]: Librespot-go daemon starting... Nov 02 07:39:15 volumio3 go-librespot[1313]: time="2024-11-02T07:39:15-04:00" level=info msg="generated new device id: ebde371e28c32c6279a147bec0e65ba951c1fad4" Nov 02 07:39:15 volumio3 ntpd[1285]: Soliciting pool server 204.2.134.163 Nov 02 07:39:15 volumio3 go-librespot[1313]: time="2024-11-02T07:39:15-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 02 07:39:15 volumio3 go-librespot[1313]: time="2024-11-02T07:39:15-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 02 07:39:15 volumio3 go-librespot[1313]: time="2024-11-02T07:39:15-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 02 07:39:15 volumio3 go-librespot[1313]: time="2024-11-02T07:39:15-04:00" level=debug msg="zeroconf server listening on port 39299" Nov 02 07:39:16 volumio3 ntpd[1285]: Soliciting pool server 108.181.220.94 Nov 02 07:39:16 volumio3 ntpd[1285]: Soliciting pool server 108.61.73.244 Nov 02 07:39:16 volumio3 sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=randbvolumio!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.58/Media/Music /mnt/NAS/MUSIC Nov 02 07:39:16 volumio3 sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:16 volumio3 kernel: CIFS: Attempting to mount //10.0.0.58/Media/Music Nov 02 07:39:16 volumio3 kernel: cryptd: max_cpu_qlen set to 1000 Nov 02 07:39:16 volumio3 nmbd[725]: [2024/11/02 07:39:16.530781, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 02 07:39:16 volumio3 systemd[1]: Started Samba NMB Daemon. Nov 02 07:39:16 volumio3 nmbd[725]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 02 07:39:16 volumio3 nmbd[725]: [2024/11/02 07:39:16.539072, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 02 07:39:16 volumio3 nmbd[725]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.127 for name WORKGROUP<1d>. Nov 02 07:39:16 volumio3 nmbd[725]: This response was from IP 10.0.0.76, reporting an IP address of 10.0.0.76. Nov 02 07:39:16 volumio3 systemd[1]: Starting Samba Winbind Daemon... Nov 02 07:39:16 volumio3 winbindd[1339]: [2024/11/02 07:39:16.647011, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 02 07:39:16 volumio3 winbindd[1339]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 02 07:39:16 volumio3 winbindd[1339]: [2024/11/02 07:39:16.649839, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 02 07:39:16 volumio3 winbindd[1339]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 02 07:39:16 volumio3 systemd[1]: Started Samba Winbind Daemon. Nov 02 07:39:16 volumio3 systemd[1]: Starting Samba SMB Daemon... Nov 02 07:39:16 volumio3 sudo[1322]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:16 volumio3 smbd[1355]: [2024/11/02 07:39:16.866855, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 02 07:39:16 volumio3 systemd[1]: Started Samba SMB Daemon. Nov 02 07:39:16 volumio3 smbd[1355]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 02 07:39:16 volumio3 systemd[1]: Reached target Multi-User System. Nov 02 07:39:16 volumio3 systemd[1]: Reached target Graphical Interface. Nov 02 07:39:16 volumio3 systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 02 07:39:16 volumio3 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 02 07:39:16 volumio3 systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 02 07:39:16 volumio3 systemd[1]: Startup finished in 7.094s (kernel) + 12.699s (userspace) = 19.794s. Nov 02 07:39:17 volumio3 ntpd[1285]: Soliciting pool server 66.118.231.14 Nov 02 07:39:17 volumio3 ntpd[1285]: Soliciting pool server 23.150.41.123 Nov 02 07:39:17 volumio3 ntpd[1285]: Soliciting pool server 137.190.2.4 Nov 02 07:39:17 volumio3 volumio[827]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 07:39:17 volumio3 volumio[827]: info: Initializing connection to go-librespot Websocket Nov 02 07:39:17 volumio3 go-librespot[1313]: time="2024-11-02T07:39:17-04:00" level=debug msg="new websocket client" Nov 02 07:39:18 volumio3 volumio[827]: info: Connection to go-librespot Websocket established Nov 02 07:39:18 volumio3 ntpd[1285]: Soliciting pool server 162.220.14.14 Nov 02 07:39:18 volumio3 ntpd[1285]: Soliciting pool server 64.111.99.224 Nov 02 07:39:18 volumio3 ntpd[1285]: Soliciting pool server 44.190.5.123 Nov 02 07:39:18 volumio3 ntpd[1285]: Soliciting pool server 216.229.4.69 Nov 02 07:39:19 volumio3 ntpd[1285]: Soliciting pool server 50.218.103.254 Nov 02 07:39:19 volumio3 ntpd[1285]: Soliciting pool server 74.50.98.131 Nov 02 07:39:19 volumio3 ntpd[1285]: Soliciting pool server 104.167.215.195 Nov 02 07:39:20 volumio3 ntpd[1285]: Soliciting pool server 162.159.200.1 Nov 02 07:39:20 volumio3 ntpd[1285]: Soliciting pool server 15.204.87.223 Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 07:39:21 volumio3 volumio[827]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 07:39:21 volumio3 volumio[827]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 07:39:21 volumio3 volumio[827]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 07:39:21 volumio3 volumio[827]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 07:39:21 volumio3 volumio[827]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 07:39:21 volumio3 volumio[827]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 07:39:21 volumio3 ntpd[1285]: Soliciting pool server 162.159.200.123 Nov 02 07:39:21 volumio3 ntpd[1285]: Soliciting pool server 2620:83:8000:ffd1:2::e Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 07:39:21 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:21 volumio3 volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:39:21 volumio3 volumio[827]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 07:39:21 volumio3 volumio[827]: info: MyVolumio login type: Token Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 07:39:21 volumio3 volumio[827]: info: Starting Streaming Service Transparent Proxy Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 07:39:21 volumio3 volumio[827]: info: Streaming services startup Nov 02 07:39:21 volumio3 volumio[827]: info: Starting Streaming Daemon Nov 02 07:39:21 volumio3 sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 07:39:21 volumio3 sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:21 volumio3 sudo[1371]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:21 volumio3 volumio[827]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 07:39:21 volumio3 volumio[827]: info: Getting Spotify volume Nov 02 07:39:21 volumio3 volumio[827]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.703&uuid=3f01c5883ff493c28d6e4744555293f4" http://updates.volumio.org/downloader-v1/track-device Nov 02 07:39:21 volumio3 volumio[827]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 02 07:39:21 volumio3 volumio[827]: Dload Upload Total Spent Left Speed Nov 02 07:39:21 volumio3 volumio[827]: [132B blob data] Nov 02 07:39:21 volumio3 volumio[827]: retrying in 5 seconds, trial 0 Nov 02 07:39:21 volumio3 volumio[827]: info: Volumio Calling Home Nov 02 07:39:21 volumio3 volumio[827]: error: Cannot start Volumio Streaming Daemon Nov 02 07:39:21 volumio3 volumio[827]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 07:39:21 volumio3 volumio[827]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 07:39:21 volumio3 volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 02 07:39:21 volumio3 volumio[827]: info: CoreCommandRouter::volumioGetState Nov 02 07:39:21 volumio3 volumio[827]: info: CorePlayQueue::getTrack 0 Nov 02 07:39:21 volumio3 volumio[827]: STREAMING PROXY: Starting server on port 3245 Nov 02 07:39:21 volumio3 volumio[827]: Node JS runtime: 14 Nov 02 07:39:21 volumio3 volumio[827]: error: MyVolumio Custom Token format not valid, refreshing it Nov 02 07:39:22 volumio3 volumio[827]: info: Volumio called home Nov 02 07:39:22 volumio3 volumio[827]: info: MyVolumio login type: Token Nov 02 07:39:22 volumio3 volumio[827]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 07:39:22 volumio3 volumio[827]: info: MyVolumio token set successfully Nov 02 07:39:22 volumio3 volumio[827]: info: MYVOLUMIO: Adding device Nov 02 07:39:22 volumio3 volumio[827]: info: MYVOLUMIO: Evaluating Server Nov 02 07:39:23 volumio3 volumio[827]: info: MyVolumio status changed Nov 02 07:39:23 volumio3 volumio[827]: info: Streaming services startup Nov 02 07:39:23 volumio3 volumio[827]: info: Starting Streaming Daemon Nov 02 07:39:23 volumio3 volumio[827]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 07:39:23 volumio3 volumio[827]: info: Removing audio output: Nov 02 07:39:23 volumio3 volumio[827]: info: Stoppping Tunnel 1 Nov 02 07:39:23 volumio3 sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 07:39:23 volumio3 sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:23 volumio3 sudo[1399]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:23 volumio3 volumio[827]: error: Cannot start Volumio Streaming Daemon Nov 02 07:39:23 volumio3 volumio[827]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 07:39:23 volumio3 volumio[827]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 07:39:23 volumio3 sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 07:39:23 volumio3 sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:39:23 volumio3 sudo[1402]: pam_unix(sudo:session): session closed for user root Nov 02 07:39:23 volumio3 volumio[827]: info: Remote SSH Stopped Nov 02 07:39:22 volumio3 ntpd[1285]: receive: Unexpected origin timestamp 0xead08deb.3b0dbd96 does not match aorg 0000000000.00000000 from server@50.218.103.254 xmt 0xead08dea.f49f8938 Nov 02 07:39:22 volumio3 ntpd[1285]: receive: Unexpected origin timestamp 0xead08deb.3b1191cb does not match aorg 0000000000.00000000 from server@108.181.220.94 xmt 0xead08dea.f516e86c Nov 02 07:39:22 volumio3 ntpd[1285]: receive: Unexpected origin timestamp 0xead08deb.3b0eb668 does not match aorg 0000000000.00000000 from server@216.229.4.69 xmt 0xead08dea.f632d0ad Nov 02 07:39:22 volumio3 volumio[827]: error: Failed to ping endpoint eu9.myvolumio.org : unknown error Nov 02 07:39:22 volumio3 volumio[827]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:39:22 volumio3 volumio[827]: Error: Unable to resolve or reject the same promise twice Nov 02 07:39:22 volumio3 volumio[827]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 02 07:39:22 volumio3 volumio[827]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Nov 02 07:39:22 volumio3 volumio[827]: at Socket.emit (events.js:412:35) Nov 02 07:39:22 volumio3 volumio[827]: at endReadableNT (internal/streams/readable.js:1333:12) Nov 02 07:39:22 volumio3 volumio[827]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Nov 02 07:39:22 volumio3 volumio[827]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:39:22 volumio3 ntpd[1285]: receive: Unexpected origin timestamp 0xead08deb.3b101cf9 does not match aorg 0000000000.00000000 from server@64.111.99.224 xmt 0xead08dea.f9c6d237 Nov 02 07:39:23 volumio3 ntpd[1285]: receive: Unexpected origin timestamp 0xead08deb.3b0b218b does not match aorg 0000000000.00000000 from server@74.50.98.131 xmt 0xead08dea.f72cf046 Nov 02 07:39:23 volumio3 ntpd[1285]: receive: Unexpected origin timestamp 0xead08deb.3b0f6afb does not match aorg 0000000000.00000000 from server@44.190.5.123 xmt 0xead08dea.f785fa24 Nov 02 07:39:23 volumio3 ntpd[1285]: receive: Unexpected origin timestamp 0xead08deb.3b123726 does not match aorg 0000000000.00000000 from server@204.2.134.163 xmt 0xead08dea.f82aaff9 Nov 02 07:39:23 volumio3 sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 07:38 Nov 02 07:39:23 volumio3 sudo[1414]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"