-- Logs begin at Tue 2024-08-27 16:50:24 CEST, end at Tue 2024-08-27 16:50:41 CEST. -- Aug 27 16:50:24 volumio kernel: Linux version 6.6.32-volumio (gkkpch@UbuntuVM24.04) (gcc (Ubuntu 13.2.0-23ubuntu4) 13.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 Aug 27 16:50:24 volumio kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=59352269-fffe-41bb-98b7-1413e085cd7a bootpart=UUID=75B5-D8CD datapart=UUID=61f275c2-d556-43d7-bb82-92a62431eff2 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 Aug 27 16:50:24 volumio kernel: KERNEL supported cpus: Aug 27 16:50:24 volumio kernel: Intel GenuineIntel Aug 27 16:50:24 volumio kernel: AMD AuthenticAMD Aug 27 16:50:24 volumio kernel: Hygon HygonGenuine Aug 27 16:50:24 volumio kernel: Centaur CentaurHauls Aug 27 16:50:24 volumio kernel: zhaoxin Shanghai Aug 27 16:50:24 volumio kernel: BIOS-provided physical RAM map: Aug 27 16:50:24 volumio kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Aug 27 16:50:24 volumio kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Aug 27 16:50:24 volumio kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Aug 27 16:50:24 volumio kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdbfff] usable Aug 27 16:50:24 volumio kernel: BIOS-e820: [mem 0x000000007ffdc000-0x000000007fffffff] reserved Aug 27 16:50:24 volumio kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Aug 27 16:50:24 volumio kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Aug 27 16:50:24 volumio kernel: NX (Execute Disable) protection: active Aug 27 16:50:24 volumio kernel: APIC: Static calls initialized Aug 27 16:50:24 volumio kernel: SMBIOS 2.8 present. Aug 27 16:50:24 volumio kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 Aug 27 16:50:24 volumio kernel: Hypervisor detected: KVM Aug 27 16:50:24 volumio kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Aug 27 16:50:24 volumio kernel: kvm-clock: using sched offset of 142843504533954 cycles Aug 27 16:50:24 volumio kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Aug 27 16:50:24 volumio kernel: tsc: Detected 806.400 MHz processor Aug 27 16:50:24 volumio kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 27 16:50:24 volumio kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 27 16:50:24 volumio kernel: last_pfn = 0x7ffdc max_arch_pfn = 0x400000000 Aug 27 16:50:24 volumio kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Aug 27 16:50:24 volumio kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 27 16:50:24 volumio kernel: found SMP MP-table at [mem 0x000f5450-0x000f545f] Aug 27 16:50:24 volumio kernel: RAMDISK: [mem 0x7bd59000-0x7ffdbfff] Aug 27 16:50:24 volumio kernel: ACPI: Early table checksum verification disabled Aug 27 16:50:24 volumio kernel: ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) Aug 27 16:50:24 volumio kernel: ACPI: RSDT 0x000000007FFE2F86 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 27 16:50:24 volumio kernel: ACPI: FACP 0x000000007FFE2D68 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 27 16:50:24 volumio kernel: ACPI: DSDT 0x000000007FFDF040 003D28 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 27 16:50:24 volumio kernel: ACPI: FACS 0x000000007FFDF000 000040 Aug 27 16:50:24 volumio kernel: ACPI: APIC 0x000000007FFE2DDC 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Aug 27 16:50:24 volumio kernel: ACPI: SSDT 0x000000007FFE2E5C 0000CA (v01 BOCHS VMGENID 00000001 BXPC 00000001) Aug 27 16:50:24 volumio kernel: ACPI: HPET 0x000000007FFE2F26 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 27 16:50:24 volumio kernel: ACPI: WAET 0x000000007FFE2F5E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 27 16:50:24 volumio kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe2d68-0x7ffe2ddb] Aug 27 16:50:24 volumio kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffdf040-0x7ffe2d67] Aug 27 16:50:24 volumio kernel: ACPI: Reserving FACS table memory at [mem 0x7ffdf000-0x7ffdf03f] Aug 27 16:50:24 volumio kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe2ddc-0x7ffe2e5b] Aug 27 16:50:24 volumio kernel: ACPI: Reserving SSDT table memory at [mem 0x7ffe2e5c-0x7ffe2f25] Aug 27 16:50:24 volumio kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe2f26-0x7ffe2f5d] Aug 27 16:50:24 volumio kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe2f5e-0x7ffe2f85] Aug 27 16:50:24 volumio kernel: Zone ranges: Aug 27 16:50:24 volumio kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 27 16:50:24 volumio kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdbfff] Aug 27 16:50:24 volumio kernel: Normal empty Aug 27 16:50:24 volumio kernel: Movable zone start for each node Aug 27 16:50:24 volumio kernel: Early memory node ranges Aug 27 16:50:24 volumio kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Aug 27 16:50:24 volumio kernel: node 0: [mem 0x0000000000100000-0x000000007ffdbfff] Aug 27 16:50:24 volumio kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff] Aug 27 16:50:24 volumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 27 16:50:24 volumio kernel: On node 0, zone DMA: 97 pages in unavailable ranges Aug 27 16:50:24 volumio kernel: On node 0, zone DMA32: 36 pages in unavailable ranges Aug 27 16:50:24 volumio kernel: ACPI: PM-Timer IO Port: 0x608 Aug 27 16:50:24 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Aug 27 16:50:24 volumio kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Aug 27 16:50:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Aug 27 16:50:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Aug 27 16:50:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 27 16:50:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Aug 27 16:50:24 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Aug 27 16:50:24 volumio kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 27 16:50:24 volumio kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Aug 27 16:50:24 volumio kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Aug 27 16:50:24 volumio kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Aug 27 16:50:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Aug 27 16:50:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] Aug 27 16:50:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] Aug 27 16:50:24 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] Aug 27 16:50:24 volumio kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Aug 27 16:50:24 volumio kernel: Booting paravirtualized kernel on KVM Aug 27 16:50:24 volumio kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Aug 27 16:50:24 volumio kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Aug 27 16:50:24 volumio kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u1048576 Aug 27 16:50:24 volumio kernel: pcpu-alloc: s191400 r8192 d29784 u1048576 alloc=1*2097152 Aug 27 16:50:24 volumio kernel: pcpu-alloc: [0] 0 1 Aug 27 16:50:24 volumio kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=59352269-fffe-41bb-98b7-1413e085cd7a bootpart=UUID=75B5-D8CD datapart=UUID=61f275c2-d556-43d7-bb82-92a62431eff2 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 Aug 27 16:50:24 volumio kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=59352269-fffe-41bb-98b7-1413e085cd7a bootpart=UUID=75B5-D8CD datapart=UUID=61f275c2-d556-43d7-bb82-92a62431eff2 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Aug 27 16:50:24 volumio kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 27 16:50:24 volumio kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 27 16:50:24 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 515804 Aug 27 16:50:24 volumio kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off Aug 27 16:50:24 volumio kernel: Memory: 1964620K/2096616K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 131740K reserved, 0K cma-reserved) Aug 27 16:50:24 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Aug 27 16:50:24 volumio kernel: Kernel/User page tables isolation: enabled Aug 27 16:50:24 volumio kernel: ftrace: allocating 40433 entries in 158 pages Aug 27 16:50:24 volumio kernel: ftrace: allocated 158 pages with 5 groups Aug 27 16:50:24 volumio kernel: Dynamic Preempt: voluntary Aug 27 16:50:24 volumio kernel: rcu: Preemptible hierarchical RCU implementation. Aug 27 16:50:24 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2. Aug 27 16:50:24 volumio kernel: Trampoline variant of Tasks RCU enabled. Aug 27 16:50:24 volumio kernel: Rude variant of Tasks RCU enabled. Aug 27 16:50:24 volumio kernel: Tracing variant of Tasks RCU enabled. Aug 27 16:50:24 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Aug 27 16:50:24 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Aug 27 16:50:24 volumio kernel: NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16 Aug 27 16:50:24 volumio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 27 16:50:24 volumio kernel: Console: colour VGA+ 80x25 Aug 27 16:50:24 volumio kernel: printk: console [tty0] enabled Aug 27 16:50:24 volumio kernel: ACPI: Core revision 20230628 Aug 27 16:50:24 volumio kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Aug 27 16:50:24 volumio kernel: APIC: Switch to symmetric I/O mode setup Aug 27 16:50:24 volumio kernel: x2apic enabled Aug 27 16:50:24 volumio kernel: APIC: Switched APIC routing to: physical x2apic Aug 27 16:50:24 volumio kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Aug 27 16:50:24 volumio kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0xb9fb05486c, max_idle_ns: 440795231721 ns Aug 27 16:50:24 volumio kernel: Calibrating delay loop (skipped) preset value.. 1612.80 BogoMIPS (lpj=3225600) Aug 27 16:50:24 volumio kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Aug 27 16:50:24 volumio kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Aug 27 16:50:24 volumio kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 27 16:50:24 volumio kernel: Spectre V2 : Mitigation: Retpolines Aug 27 16:50:24 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Aug 27 16:50:24 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Aug 27 16:50:24 volumio kernel: Speculative Store Bypass: Vulnerable Aug 27 16:50:24 volumio kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Aug 27 16:50:24 volumio kernel: MMIO Stale Data: Unknown: No mitigations Aug 27 16:50:24 volumio kernel: x86/fpu: x87 FPU will use FXSAVE Aug 27 16:50:24 volumio kernel: Freeing SMP alternatives memory: 32K Aug 27 16:50:24 volumio kernel: pid_max: default: 32768 minimum: 301 Aug 27 16:50:24 volumio kernel: LSM: initializing lsm=capability,yama,integrity Aug 27 16:50:24 volumio kernel: Yama: becoming mindful. Aug 27 16:50:24 volumio kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 27 16:50:24 volumio kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 27 16:50:24 volumio kernel: smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0xf, model: 0x6b, stepping: 0x1) Aug 27 16:50:24 volumio kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 27 16:50:24 volumio kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 27 16:50:24 volumio kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 27 16:50:24 volumio kernel: Performance Events: unsupported Netburst CPU model 107 no PMU driver, software events only. Aug 27 16:50:24 volumio kernel: signal: max sigframe size: 1440 Aug 27 16:50:24 volumio kernel: rcu: Hierarchical SRCU implementation. Aug 27 16:50:24 volumio kernel: rcu: Max phase no-delay instances is 1000. Aug 27 16:50:24 volumio kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 27 16:50:24 volumio kernel: smp: Bringing up secondary CPUs ... Aug 27 16:50:24 volumio kernel: smpboot: x86: Booting SMP configuration: Aug 27 16:50:24 volumio kernel: .... node #0, CPUs: #1 Aug 27 16:50:24 volumio kernel: smp: Brought up 1 node, 2 CPUs Aug 27 16:50:24 volumio kernel: smpboot: Max logical packages: 1 Aug 27 16:50:24 volumio kernel: smpboot: Total of 2 processors activated (3225.60 BogoMIPS) Aug 27 16:50:24 volumio kernel: devtmpfs: initialized Aug 27 16:50:24 volumio kernel: x86/mm: Memory block size: 128MB Aug 27 16:50:24 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Aug 27 16:50:24 volumio kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Aug 27 16:50:24 volumio kernel: pinctrl core: initialized pinctrl subsystem Aug 27 16:50:24 volumio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 27 16:50:24 volumio kernel: audit: initializing netlink subsys (disabled) Aug 27 16:50:24 volumio kernel: audit: type=2000 audit(1724770217.901:1): state=initialized audit_enabled=0 res=1 Aug 27 16:50:24 volumio kernel: thermal_sys: Registered thermal governor 'fair_share' Aug 27 16:50:24 volumio kernel: thermal_sys: Registered thermal governor 'bang_bang' Aug 27 16:50:24 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 27 16:50:24 volumio kernel: thermal_sys: Registered thermal governor 'user_space' Aug 27 16:50:24 volumio kernel: EISA bus registered Aug 27 16:50:24 volumio kernel: cpuidle: using governor ladder Aug 27 16:50:24 volumio kernel: cpuidle: using governor menu Aug 27 16:50:24 volumio kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 27 16:50:24 volumio kernel: PCI: Using configuration type 1 for base access Aug 27 16:50:24 volumio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 27 16:50:24 volumio kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 27 16:50:24 volumio kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 27 16:50:24 volumio kernel: ACPI: Added _OSI(Module Device) Aug 27 16:50:24 volumio kernel: ACPI: Added _OSI(Processor Device) Aug 27 16:50:24 volumio kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 27 16:50:24 volumio kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 27 16:50:24 volumio kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded Aug 27 16:50:24 volumio kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Aug 27 16:50:24 volumio kernel: ACPI: Interpreter enabled Aug 27 16:50:24 volumio kernel: ACPI: PM: (supports S0 S3 S4 S5) Aug 27 16:50:24 volumio kernel: ACPI: Using IOAPIC for interrupt routing Aug 27 16:50:24 volumio kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 27 16:50:24 volumio kernel: PCI: Using E820 reservations for host bridge windows Aug 27 16:50:24 volumio kernel: ACPI: Enabled 3 GPEs in block 00 to 0F Aug 27 16:50:24 volumio kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 27 16:50:24 volumio kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Aug 27 16:50:24 volumio kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Aug 27 16:50:24 volumio kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Aug 27 16:50:24 volumio kernel: acpiphp: Slot [3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [7] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [18] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [6] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [8] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [9] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [10] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [11] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [12] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [13] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [14] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [15] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [16] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [17] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [19] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [20] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [21] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [22] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [23] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [24] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [25] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [26] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [27] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [28] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [29] registered Aug 27 16:50:24 volumio kernel: PCI host bridge to bus 0000:00 Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 27 16:50:24 volumio kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Aug 27 16:50:24 volumio kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Aug 27 16:50:24 volumio kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Aug 27 16:50:24 volumio kernel: pci 0000:00:01.1: reg 0x20: [io 0xf0a0-0xf0af] Aug 27 16:50:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Aug 27 16:50:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Aug 27 16:50:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Aug 27 16:50:24 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Aug 27 16:50:24 volumio kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Aug 27 16:50:24 volumio kernel: pci 0000:00:01.2: reg 0x20: [io 0xf040-0xf05f] Aug 27 16:50:24 volumio kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Aug 27 16:50:24 volumio kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Aug 27 16:50:24 volumio kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfcffffff pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfea50000-0xfea50fff] Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfea40000-0xfea4ffff pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:03.0: [1af4:1002] type 00 class 0x00ff00 Aug 27 16:50:24 volumio kernel: pci 0000:00:03.0: reg 0x10: [io 0xf000-0xf03f] Aug 27 16:50:24 volumio kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfd600000-0xfd603fff 64bit pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: [1b36:0001] type 01 class 0x060400 Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: reg 0x10: [mem 0xfea51000-0xfea510ff 64bit] Aug 27 16:50:24 volumio kernel: pci 0000:00:07.0: [8086:2922] type 00 class 0x010601 Aug 27 16:50:24 volumio kernel: pci 0000:00:07.0: reg 0x20: [io 0xf060-0xf07f] Aug 27 16:50:24 volumio kernel: pci 0000:00:07.0: reg 0x24: [mem 0xfea52000-0xfea52fff] Aug 27 16:50:24 volumio kernel: pci 0000:00:12.0: [1af4:1000] type 00 class 0x020000 Aug 27 16:50:24 volumio kernel: pci 0000:00:12.0: reg 0x10: [io 0xf080-0xf09f] Aug 27 16:50:24 volumio kernel: pci 0000:00:12.0: reg 0x14: [mem 0xfea53000-0xfea53fff] Aug 27 16:50:24 volumio kernel: pci 0000:00:12.0: reg 0x20: [mem 0xfd604000-0xfd607fff 64bit pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:12.0: reg 0x30: [mem 0xfea00000-0xfea3ffff pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: [1b36:0001] type 01 class 0x060400 Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: reg 0x10: [mem 0xfea54000-0xfea540ff 64bit] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: [1b36:0001] type 01 class 0x060400 Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: reg 0x10: [mem 0xfea55000-0xfea550ff 64bit] Aug 27 16:50:24 volumio kernel: pci_bus 0000:01: extended config space not accessible Aug 27 16:50:24 volumio kernel: acpiphp: Slot [0] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [1-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [3-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [4-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [5] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [6-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [7-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [8-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [9-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [10-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [11-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [12-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [13-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [14-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [15-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [16-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [17-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [18-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [19-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [20-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [21-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [22-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [23-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [24-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [25-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [26-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [27-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [28-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [29-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [30] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [31] registered Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xfd400000-0xfd5fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci_bus 0000:02: extended config space not accessible Aug 27 16:50:24 volumio kernel: acpiphp: Slot [27-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [0-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [1-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [2-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [3-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [4-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [5-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [6-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [7-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [8-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [9-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [10-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [11-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [12-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [13-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [14-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [15-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [16-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [17-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [18-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [19-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [20-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [21-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [22-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [23-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [24-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [25-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [26-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [28-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [29-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [30-2] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [31-2] registered Aug 27 16:50:24 volumio kernel: pci 0000:02:1b.0: [1b36:000d] type 00 class 0x0c0330 Aug 27 16:50:24 volumio kernel: pci 0000:02:1b.0: reg 0x10: [mem 0xfe600000-0xfe603fff 64bit] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xfd200000-0xfd3fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci_bus 0000:03: extended config space not accessible Aug 27 16:50:24 volumio kernel: acpiphp: Slot [0-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [1-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [2-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [3-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [4-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [5-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [6-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [7-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [8-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [9-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [10-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [11-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [12-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [13-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [14-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [15-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [16-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [17-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [18-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [19-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [20-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [21-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [22-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [23-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [24-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [25-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [26-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [27-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [28-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [29-4] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [30-3] registered Aug 27 16:50:24 volumio kernel: acpiphp: Slot [31-3] registered Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: on NUMA node 0 Aug 27 16:50:24 volumio kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Aug 27 16:50:24 volumio kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Aug 27 16:50:24 volumio kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Aug 27 16:50:24 volumio kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Aug 27 16:50:24 volumio kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Aug 27 16:50:24 volumio kernel: iommu: Default domain type: Translated Aug 27 16:50:24 volumio kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 27 16:50:24 volumio kernel: SCSI subsystem initialized Aug 27 16:50:24 volumio kernel: libata version 3.00 loaded. Aug 27 16:50:24 volumio kernel: ACPI: bus type USB registered Aug 27 16:50:24 volumio kernel: usbcore: registered new interface driver usbfs Aug 27 16:50:24 volumio kernel: usbcore: registered new interface driver hub Aug 27 16:50:24 volumio kernel: usbcore: registered new device driver usb Aug 27 16:50:24 volumio kernel: PCI: Using ACPI for IRQ routing Aug 27 16:50:24 volumio kernel: PCI: pci_cache_line_size set to 64 bytes Aug 27 16:50:24 volumio kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Aug 27 16:50:24 volumio kernel: e820: reserve RAM buffer [mem 0x7ffdc000-0x7fffffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: vgaarb: bridge control possible Aug 27 16:50:24 volumio kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Aug 27 16:50:24 volumio kernel: vgaarb: loaded Aug 27 16:50:24 volumio kernel: hpet: 3 channels of 0 reserved for per-cpu timers Aug 27 16:50:24 volumio kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Aug 27 16:50:24 volumio kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Aug 27 16:50:24 volumio kernel: clocksource: Switched to clocksource kvm-clock Aug 27 16:50:24 volumio kernel: VFS: Disk quotas dquot_6.6.0 Aug 27 16:50:24 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 27 16:50:24 volumio kernel: pnp: PnP ACPI init Aug 27 16:50:24 volumio kernel: pnp 00:02: [dma 2] Aug 27 16:50:24 volumio kernel: pnp: PnP ACPI: found 4 devices Aug 27 16:50:24 volumio kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 27 16:50:24 volumio kernel: NET: Registered PF_INET protocol family Aug 27 16:50:24 volumio kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 27 16:50:24 volumio kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Aug 27 16:50:24 volumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 27 16:50:24 volumio kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Aug 27 16:50:24 volumio kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Aug 27 16:50:24 volumio kernel: TCP: Hash tables configured (established 16384 bind 16384) Aug 27 16:50:24 volumio kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 27 16:50:24 volumio kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 27 16:50:24 volumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: PCI bridge to [bus 01] Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: bridge window [io 0xe000-0xefff] Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xfe800000-0xfe9fffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:05.0: bridge window [mem 0xfd400000-0xfd5fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: PCI bridge to [bus 02] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xfe600000-0xfe7fffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1e.0: bridge window [mem 0xfd200000-0xfd3fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: PCI bridge to [bus 03] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xfe400000-0xfe5fffff] Aug 27 16:50:24 volumio kernel: pci 0000:00:1f.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Aug 27 16:50:24 volumio kernel: pci_bus 0000:01: resource 0 [io 0xe000-0xefff] Aug 27 16:50:24 volumio kernel: pci_bus 0000:01: resource 1 [mem 0xfe800000-0xfe9fffff] Aug 27 16:50:24 volumio kernel: pci_bus 0000:01: resource 2 [mem 0xfd400000-0xfd5fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci_bus 0000:02: resource 0 [io 0xd000-0xdfff] Aug 27 16:50:24 volumio kernel: pci_bus 0000:02: resource 1 [mem 0xfe600000-0xfe7fffff] Aug 27 16:50:24 volumio kernel: pci_bus 0000:02: resource 2 [mem 0xfd200000-0xfd3fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci_bus 0000:03: resource 0 [io 0xc000-0xcfff] Aug 27 16:50:24 volumio kernel: pci_bus 0000:03: resource 1 [mem 0xfe400000-0xfe5fffff] Aug 27 16:50:24 volumio kernel: pci_bus 0000:03: resource 2 [mem 0xfd000000-0xfd1fffff 64bit pref] Aug 27 16:50:24 volumio kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Aug 27 16:50:24 volumio kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Aug 27 16:50:24 volumio kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Aug 27 16:50:24 volumio kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x770 took 21053 usecs Aug 27 16:50:24 volumio kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Aug 27 16:50:24 volumio kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Aug 27 16:50:24 volumio kernel: pci 0000:02:1b.0: quirk_usb_early_handoff+0x0/0x770 took 30774 usecs Aug 27 16:50:24 volumio kernel: PCI: CLS 0 bytes, default 64 Aug 27 16:50:24 volumio kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0xb9fb05486c, max_idle_ns: 440795231721 ns Aug 27 16:50:24 volumio kernel: Trying to unpack rootfs image as initramfs... Aug 27 16:50:24 volumio kernel: Initialise system trusted keyrings Aug 27 16:50:24 volumio kernel: workingset: timestamp_bits=46 max_order=19 bucket_order=0 Aug 27 16:50:24 volumio kernel: zbud: loaded Aug 27 16:50:24 volumio kernel: Key type asymmetric registered Aug 27 16:50:24 volumio kernel: Asymmetric key parser 'x509' registered Aug 27 16:50:24 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Aug 27 16:50:24 volumio kernel: io scheduler mq-deadline registered Aug 27 16:50:24 volumio kernel: io scheduler kyber registered Aug 27 16:50:24 volumio kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.S28_) Aug 27 16:50:24 volumio kernel: shpchp 0000:00:05.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Aug 27 16:50:24 volumio kernel: shpchp 0000:00:05.0: Cannot get control of SHPC hotplug Aug 27 16:50:24 volumio kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF0_) Aug 27 16:50:24 volumio kernel: shpchp 0000:00:1e.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Aug 27 16:50:24 volumio kernel: shpchp 0000:00:1e.0: Cannot get control of SHPC hotplug Aug 27 16:50:24 volumio kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.SF8_) Aug 27 16:50:24 volumio kernel: shpchp 0000:00:1f.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) Aug 27 16:50:24 volumio kernel: shpchp 0000:00:1f.0: Cannot get control of SHPC hotplug Aug 27 16:50:24 volumio kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Aug 27 16:50:24 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Aug 27 16:50:24 volumio kernel: ACPI: button: Power Button [PWRF] Aug 27 16:50:24 volumio kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Aug 27 16:50:24 volumio kernel: Linux agpgart interface v0.103 Aug 27 16:50:24 volumio kernel: ata_piix 0000:00:01.1: version 2.13 Aug 27 16:50:24 volumio kernel: scsi host0: ata_piix Aug 27 16:50:24 volumio kernel: scsi host1: ata_piix Aug 27 16:50:24 volumio kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xf0a0 irq 14 Aug 27 16:50:24 volumio kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xf0a8 irq 15 Aug 27 16:50:24 volumio kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Aug 27 16:50:24 volumio kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Aug 27 16:50:24 volumio kernel: uhci_hcd 0000:00:01.2: detected 2 ports Aug 27 16:50:24 volumio kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000f040 Aug 27 16:50:24 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06 Aug 27 16:50:24 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 27 16:50:24 volumio kernel: usb usb1: Product: UHCI Host Controller Aug 27 16:50:24 volumio kernel: usb usb1: Manufacturer: Linux 6.6.32-volumio uhci_hcd Aug 27 16:50:24 volumio kernel: usb usb1: SerialNumber: 0000:00:01.2 Aug 27 16:50:24 volumio kernel: hub 1-0:1.0: USB hub found Aug 27 16:50:24 volumio kernel: hub 1-0:1.0: 2 ports detected Aug 27 16:50:24 volumio kernel: xhci_hcd 0000:02:1b.0: xHCI Host Controller Aug 27 16:50:24 volumio kernel: xhci_hcd 0000:02:1b.0: new USB bus registered, assigned bus number 2 Aug 27 16:50:24 volumio kernel: xhci_hcd 0000:02:1b.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 Aug 27 16:50:24 volumio kernel: xhci_hcd 0000:02:1b.0: xHCI Host Controller Aug 27 16:50:24 volumio kernel: xhci_hcd 0000:02:1b.0: new USB bus registered, assigned bus number 3 Aug 27 16:50:24 volumio kernel: xhci_hcd 0000:02:1b.0: Host supports USB 3.0 SuperSpeed Aug 27 16:50:24 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Aug 27 16:50:24 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 27 16:50:24 volumio kernel: usb usb2: Product: xHCI Host Controller Aug 27 16:50:24 volumio kernel: usb usb2: Manufacturer: Linux 6.6.32-volumio xhci-hcd Aug 27 16:50:24 volumio kernel: usb usb2: SerialNumber: 0000:02:1b.0 Aug 27 16:50:24 volumio kernel: hub 2-0:1.0: USB hub found Aug 27 16:50:24 volumio kernel: hub 2-0:1.0: 15 ports detected Aug 27 16:50:24 volumio kernel: usb usb3: We don't know the algorithms for LPM for this host, disabling LPM. Aug 27 16:50:24 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Aug 27 16:50:24 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 27 16:50:24 volumio kernel: usb usb3: Product: xHCI Host Controller Aug 27 16:50:24 volumio kernel: usb usb3: Manufacturer: Linux 6.6.32-volumio xhci-hcd Aug 27 16:50:24 volumio kernel: usb usb3: SerialNumber: 0000:02:1b.0 Aug 27 16:50:24 volumio kernel: hub 3-0:1.0: USB hub found Aug 27 16:50:24 volumio kernel: hub 3-0:1.0: 15 ports detected Aug 27 16:50:24 volumio kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Aug 27 16:50:24 volumio kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Aug 27 16:50:24 volumio kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Aug 27 16:50:24 volumio kernel: mousedev: PS/2 mouse device common for all mice Aug 27 16:50:24 volumio kernel: rtc_cmos 00:03: RTC can wake from S4 Aug 27 16:50:24 volumio kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 Aug 27 16:50:24 volumio kernel: rtc_cmos 00:03: registered as rtc0 Aug 27 16:50:24 volumio kernel: rtc_cmos 00:03: setting system clock to 2024-08-27T14:50:18 UTC (1724770218) Aug 27 16:50:24 volumio kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Aug 27 16:50:24 volumio kernel: i2c_dev: i2c /dev entries driver Aug 27 16:50:24 volumio kernel: platform eisa.0: Probing EISA bus 0 Aug 27 16:50:24 volumio kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Aug 27 16:50:24 volumio kernel: intel_pstate: CPU model not supported Aug 27 16:50:24 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 27 16:50:24 volumio kernel: NET: Registered PF_INET6 protocol family Aug 27 16:50:24 volumio kernel: Segment Routing with IPv6 Aug 27 16:50:24 volumio kernel: In-situ OAM (IOAM) with IPv6 Aug 27 16:50:24 volumio kernel: mip6: Mobile IPv6 Aug 27 16:50:24 volumio kernel: NET: Registered PF_PACKET protocol family Aug 27 16:50:24 volumio kernel: mpls_gso: MPLS GSO support Aug 27 16:50:24 volumio kernel: IPI shorthand broadcast: enabled Aug 27 16:50:24 volumio kernel: sched_clock: Marking stable (348852134, 7252974)->(357116117, -1011009) Aug 27 16:50:24 volumio kernel: Loading compiled-in X.509 certificates Aug 27 16:50:24 volumio kernel: clk: Disabling unused clocks Aug 27 16:50:24 volumio kernel: ata2: found unknown device (class 0) Aug 27 16:50:24 volumio kernel: ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Aug 27 16:50:24 volumio kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Aug 27 16:50:24 volumio kernel: usb 1-1: new full-speed USB device number 2 using uhci_hcd Aug 27 16:50:24 volumio kernel: usb 2-1: new full-speed USB device number 2 using xhci_hcd Aug 27 16:50:24 volumio kernel: usb 1-1: not running at top speed; connect to a high speed hub Aug 27 16:50:24 volumio kernel: usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 Aug 27 16:50:24 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 Aug 27 16:50:24 volumio kernel: usb 1-1: Product: QEMU USB Tablet Aug 27 16:50:24 volumio kernel: usb 1-1: Manufacturer: QEMU Aug 27 16:50:24 volumio kernel: usb 1-1: SerialNumber: 28754-0000:00:01.2-1 Aug 27 16:50:24 volumio kernel: usb 2-1: New USB device found, idVendor=0d8c, idProduct=0102, bcdDevice= 0.10 Aug 27 16:50:24 volumio kernel: usb 2-1: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Aug 27 16:50:24 volumio kernel: usb 2-1: Product: USB Sound Device Aug 27 16:50:24 volumio kernel: Freeing initrd memory: 68108K Aug 27 16:50:24 volumio kernel: Freeing unused kernel image (initmem) memory: 2988K Aug 27 16:50:24 volumio kernel: Write protecting the kernel read-only data: 20480k Aug 27 16:50:24 volumio kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K Aug 27 16:50:24 volumio kernel: Run /init as init process Aug 27 16:50:24 volumio kernel: with arguments: Aug 27 16:50:24 volumio kernel: /init Aug 27 16:50:24 volumio kernel: splash Aug 27 16:50:24 volumio kernel: with environment: Aug 27 16:50:24 volumio kernel: HOME=/ Aug 27 16:50:24 volumio kernel: TERM=linux Aug 27 16:50:24 volumio kernel: BOOT_IMAGE=vmlinuz Aug 27 16:50:24 volumio kernel: biosdevname=0 Aug 27 16:50:24 volumio kernel: imgpart=UUID=59352269-fffe-41bb-98b7-1413e085cd7a Aug 27 16:50:24 volumio kernel: bootpart=UUID=75B5-D8CD Aug 27 16:50:24 volumio kernel: datapart=UUID=61f275c2-d556-43d7-bb82-92a62431eff2 Aug 27 16:50:24 volumio kernel: hwdevice=x86 Aug 27 16:50:24 volumio kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Aug 27 16:50:24 volumio kernel: imgfile=/volumio_current.sqsh Aug 27 16:50:24 volumio kernel: use_kmsg=no Aug 27 16:50:24 volumio kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Aug 27 16:50:24 volumio kernel: ahci 0000:00:07.0: version 3.0 Aug 27 16:50:24 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Aug 27 16:50:24 volumio kernel: FDC 0 is a S82078B Aug 27 16:50:24 volumio kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 Aug 27 16:50:24 volumio kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 Aug 27 16:50:24 volumio kernel: usbcore: registered new interface driver usbhid Aug 27 16:50:24 volumio kernel: usbhid: USB HID core driver Aug 27 16:50:24 volumio kernel: sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Aug 27 16:50:24 volumio kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Aug 27 16:50:24 volumio kernel: ACPI: bus type drm_connector registered Aug 27 16:50:24 volumio 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 Aug 27 16:50:24 volumio 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 Aug 27 16:50:24 volumio kernel: input: USB Sound Device as /devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb2/2-1/2-1:1.3/0003:0D8C:0102.0002/input/input6 Aug 27 16:50:24 volumio kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Aug 27 16:50:24 volumio kernel: ahci 0000:00:07.0: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Aug 27 16:50:24 volumio kernel: ahci 0000:00:07.0: flags: 64bit ncq only Aug 27 16:50:24 volumio kernel: scsi host2: ahci Aug 27 16:50:24 volumio kernel: scsi host3: ahci Aug 27 16:50:24 volumio kernel: scsi host4: ahci Aug 27 16:50:24 volumio kernel: scsi host5: ahci Aug 27 16:50:24 volumio kernel: scsi host6: ahci Aug 27 16:50:24 volumio kernel: scsi host7: ahci Aug 27 16:50:24 volumio kernel: ata3: SATA max UDMA/133 abar m4096@0xfea52000 port 0xfea52100 irq 27 Aug 27 16:50:24 volumio kernel: ata4: SATA max UDMA/133 abar m4096@0xfea52000 port 0xfea52180 irq 27 Aug 27 16:50:24 volumio kernel: ata5: SATA max UDMA/133 abar m4096@0xfea52000 port 0xfea52200 irq 27 Aug 27 16:50:24 volumio kernel: ata6: SATA max UDMA/133 abar m4096@0xfea52000 port 0xfea52280 irq 27 Aug 27 16:50:24 volumio kernel: ata7: SATA max UDMA/133 abar m4096@0xfea52000 port 0xfea52300 irq 27 Aug 27 16:50:24 volumio kernel: ata8: SATA max UDMA/133 abar m4096@0xfea52000 port 0xfea52380 irq 27 Aug 27 16:50:24 volumio kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0 Aug 27 16:50:24 volumio kernel: hid-generic 0003:0D8C:0102.0002: input,hidraw1: USB HID v1.00 Device [USB Sound Device ] on usb-0000:02:1b.0-1/input3 Aug 27 16:50:24 volumio kernel: ata5: SATA link down (SStatus 0 SControl 300) Aug 27 16:50:24 volumio kernel: ata4: SATA link down (SStatus 0 SControl 300) Aug 27 16:50:24 volumio kernel: ata8: SATA link down (SStatus 0 SControl 300) Aug 27 16:50:24 volumio kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Aug 27 16:50:24 volumio kernel: ata6: SATA link down (SStatus 0 SControl 300) Aug 27 16:50:24 volumio kernel: ata3.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 Aug 27 16:50:24 volumio kernel: ata3.00: 7806976 sectors, multi 16: LBA48 NCQ (depth 32) Aug 27 16:50:24 volumio kernel: ata3.00: applying bridge limits Aug 27 16:50:24 volumio kernel: ata3.00: configured for UDMA/100 Aug 27 16:50:24 volumio kernel: ata7: SATA link down (SStatus 0 SControl 300) Aug 27 16:50:24 volumio kernel: scsi 2:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Aug 27 16:50:24 volumio kernel: sd 2:0:0:0: [sda] 7806976 512-byte logical blocks: (4.00 GB/3.72 GiB) Aug 27 16:50:24 volumio kernel: sd 2:0:0:0: [sda] Write Protect is off Aug 27 16:50:24 volumio kernel: sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00 Aug 27 16:50:24 volumio kernel: sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Aug 27 16:50:24 volumio kernel: sd 2:0:0:0: [sda] Preferred minimum I/O size 512 bytes Aug 27 16:50:24 volumio kernel: sda: sda1 sda2 sda3 Aug 27 16:50:24 volumio kernel: sd 2:0:0:0: [sda] Attached SCSI disk Aug 27 16:50:24 volumio kernel: bochs-drm 0000:00:02.0: vgaarb: deactivate vga console Aug 27 16:50:24 volumio kernel: Console: switching to colour dummy device 80x25 Aug 27 16:50:24 volumio kernel: [drm] Found bochs VGA, ID 0xb0c5. Aug 27 16:50:24 volumio kernel: [drm] Framebuffer size 16384 kB @ 0xfc000000, mmio @ 0xfea50000. Aug 27 16:50:24 volumio kernel: [drm] Found EDID data blob. Aug 27 16:50:24 volumio kernel: [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0 Aug 27 16:50:24 volumio kernel: fbcon: bochs-drmdrmfb (fb0) is primary device Aug 27 16:50:24 volumio kernel: Console: switching to colour frame buffer device 160x50 Aug 27 16:50:24 volumio kernel: bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device Aug 27 16:50:24 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 27 16:50:24 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Aug 27 16:50:24 volumio kernel: sdhci: Copyright(c) Pierre Ossman Aug 27 16:50:24 volumio kernel: fuse: init (API version 7.39) Aug 27 16:50:24 volumio kernel: usbcore: registered new interface driver usb-storage Aug 27 16:50:24 volumio kernel: usbcore: registered new interface driver uas Aug 27 16:50:24 volumio kernel: [drm] radeon kernel modesetting enabled. Aug 27 16:50:24 volumio kernel: random: crng init done Aug 27 16:50:24 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 27 16:50:24 volumio kernel: EXT4-fs (sda2): recovery complete Aug 27 16:50:24 volumio kernel: EXT4-fs (sda2): mounted filesystem 59352269-fffe-41bb-98b7-1413e085cd7a r/w with ordered data mode. Quota mode: none. Aug 27 16:50:24 volumio kernel: loop: module loaded Aug 27 16:50:24 volumio kernel: block device autoloading is deprecated and will be removed. Aug 27 16:50:24 volumio kernel: loop0: detected capacity change from 0 to 1724976 Aug 27 16:50:24 volumio kernel: EXT4-fs (sda3): recovery complete Aug 27 16:50:24 volumio kernel: EXT4-fs (sda3): mounted filesystem 61f275c2-d556-43d7-bb82-92a62431eff2 r/w with ordered data mode. Quota mode: none. Aug 27 16:50:24 volumio kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Aug 27 16:50:24 volumio systemd[1]: Inserted module 'autofs4' Aug 27 16:50:24 volumio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Aug 27 16:50:24 volumio systemd[1]: Detected virtualization kvm. Aug 27 16:50:24 volumio systemd[1]: Detected architecture x86-64. Aug 27 16:50:24 volumio systemd[1]: Set hostname to . Aug 27 16:50:24 volumio systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Aug 27 16:50:24 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Aug 27 16:50:24 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 27 16:50:24 volumio systemd[1]: Created slice User and Session Slice. Aug 27 16:50:24 volumio systemd[1]: Listening on fsck to fsckd communication Socket. Aug 27 16:50:24 volumio systemd[1]: Created slice system-getty.slice. Aug 27 16:50:24 volumio systemd-journald[434]: Journal started Aug 27 16:50:24 volumio systemd-journald[434]: Runtime journal (/run/log/journal/824b3a228a9d5af3920a62a7665f6735) is 3.7M, max 30.0M, 26.2M free. Aug 27 16:50:24 volumio systemd[1]: Mounted Huge Pages File System. Aug 27 16:50:24 volumio systemd[1]: Mounted POSIX Message Queue File System. Aug 27 16:50:24 volumio systemd[1]: Started Remount Root and Kernel File Systems. Aug 27 16:50:24 volumio systemd[1]: Mounted FUSE Control File System. Aug 27 16:50:24 volumio systemd[1]: Started Apply Kernel Variables. Aug 27 16:50:24 volumio systemd[1]: Mounted Kernel Configuration File System. Aug 27 16:50:24 volumio systemd[1]: Starting Load/Save Random Seed... Aug 27 16:50:24 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 27 16:50:24 volumio systemd[1]: Starting Create System Users... Aug 27 16:50:24 volumio fake-hwclock[437]: Current system time: 2024-08-27 14:50:24 Aug 27 16:50:24 volumio fake-hwclock[437]: fake-hwclock saved clock information is in the past: 2024-08-27 14:50:13 Aug 27 16:50:24 volumio fake-hwclock[437]: To set system time to this saved clock anyway, use "force" Aug 27 16:50:24 volumio systemd[1]: Started Restore / save the current clock. Aug 27 16:50:24 volumio kernel: RPC: Registered named UNIX socket transport module. Aug 27 16:50:24 volumio kernel: RPC: Registered udp transport module. Aug 27 16:50:24 volumio kernel: RPC: Registered tcp transport module. Aug 27 16:50:24 volumio kernel: RPC: Registered tcp-with-tls transport module. Aug 27 16:50:24 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 27 16:50:24 volumio systemd[1]: Mounted RPC Pipe File System. Aug 27 16:50:24 volumio systemd[1]: Started Load/Save Random Seed. Aug 27 16:50:24 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 27 16:50:24 volumio systemd[1]: Started Create System Users. Aug 27 16:50:25 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Aug 27 16:50:25 volumio systemd[1]: Started Create Static Device Nodes in /dev. Aug 27 16:50:25 volumio systemd[1]: Reached target Local File Systems (Pre). Aug 27 16:50:25 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 27 16:50:25 volumio systemd[1]: Mounting /tmp... Aug 27 16:50:25 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 27 16:50:25 volumio systemd[1]: Mounting /var/log... Aug 27 16:50:25 volumio systemd[1]: Mounting /var/spool/cups... Aug 27 16:50:25 volumio systemd[1]: Starting udev Kernel Device Manager... Aug 27 16:50:25 volumio systemd[1]: Mounted /tmp. Aug 27 16:50:25 volumio systemd[1]: Started udev Coldplug all Devices. Aug 27 16:50:25 volumio systemd[1]: Mounted /var/log. Aug 27 16:50:25 volumio systemd[1]: Mounted /var/spool/cups. Aug 27 16:50:25 volumio systemd[1]: Mounting /var/spool/cups/tmp... Aug 27 16:50:25 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Aug 27 16:50:25 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 27 16:50:25 volumio systemd[1]: Mounted /var/spool/cups/tmp. Aug 27 16:50:25 volumio systemd-journald[434]: Runtime journal (/run/log/journal/824b3a228a9d5af3920a62a7665f6735) is 3.7M, max 30.0M, 26.2M free. Aug 27 16:50:25 volumio systemd-udevd[465]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 27 16:50:25 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Aug 27 16:50:25 volumio systemd[1]: Started Flush Journal to Persistent Storage. Aug 27 16:50:25 volumio systemd[1]: Started udev Kernel Device Manager. Aug 27 16:50:25 volumio systemd[1]: Starting Show Plymouth Boot Screen... Aug 27 16:50:25 volumio systemd[1]: plymouth-start.service: Succeeded. Aug 27 16:50:25 volumio systemd[1]: Started Show Plymouth Boot Screen. Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 27 16:50:25 volumio systemd[1]: Reached target Local Encrypted Volumes. Aug 27 16:50:25 volumio systemd[1]: Reached target Paths. Aug 27 16:50:25 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 27 16:50:25 volumio kernel: sr 1:0:0:0: Attached scsi generic sg0 type 5 Aug 27 16:50:25 volumio kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Aug 27 16:50:25 volumio systemd-udevd[483]: Using default interface naming scheme 'v240'. Aug 27 16:50:25 volumio systemd-udevd[483]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 27 16:50:25 volumio systemd-udevd[471]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio systemd-udevd[477]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio systemd[1]: Found device QEMU_HARDDISK boot. Aug 27 16:50:25 volumio systemd-udevd[475]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio kernel: cryptd: max_cpu_qlen set to 1000 Aug 27 16:50:25 volumio kernel: SSE version of gcm_enc/dec engaged. Aug 27 16:50:25 volumio systemd-udevd[473]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 27 16:50:25 volumio systemd-udevd[478]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio systemd-udevd[470]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio systemd-udevd[485]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio kernel: usb 2-1: current rate 30464 is different from the runtime rate 96000 Aug 27 16:50:25 volumio kernel: usb 2-1: Warning! Unlikely big volume range (=8065), cval->res is probably wrong. Aug 27 16:50:25 volumio kernel: usb 2-1: [9] FU [Mic Playback Volume] ch = 2, val = -6144/1921/1 Aug 27 16:50:25 volumio kernel: usb 2-1: Warning! Unlikely big volume range (=8065), cval->res is probably wrong. Aug 27 16:50:25 volumio kernel: usb 2-1: [11] FU [Line Playback Volume] ch = 2, val = -6144/1921/1 Aug 27 16:50:25 volumio systemd-udevd[474]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio kernel: usb 2-1: Warning! Unlikely big volume range (=6928), cval->res is probably wrong. Aug 27 16:50:25 volumio kernel: usb 2-1: [8] FU [Mic Capture Volume] ch = 2, val = -4096/2832/1 Aug 27 16:50:25 volumio kernel: usb 2-1: Warning! Unlikely big volume range (=6928), cval->res is probably wrong. Aug 27 16:50:25 volumio kernel: usb 2-1: [15] FU [Line Capture Volume] ch = 2, val = -4096/2832/1 Aug 27 16:50:25 volumio systemd-udevd[482]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio kernel: usb 2-1: Warning! Unlikely big volume range (=6928), cval->res is probably wrong. Aug 27 16:50:25 volumio kernel: usb 2-1: [2] FU [PCM Capture Volume] ch = 2, val = -4096/2832/1 Aug 27 16:50:25 volumio kernel: usbcore: registered new interface driver snd-usb-audio Aug 27 16:50:25 volumio systemd-udevd[479]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 27 16:50:25 volumio systemd-udevd[480]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Aug 27 16:50:25 volumio systemd-udevd[485]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Aug 27 16:50:25 volumio kernel: alsactl[566]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in Virtio network device being skipped. Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 27 16:50:25 volumio systemd[1]: Starting Show Plymouth Boot Screen... Aug 27 16:50:25 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 27 16:50:25 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/75B5-D8CD... Aug 27 16:50:25 volumio systemd[1]: Started File System Check Daemon to report status. Aug 27 16:50:25 volumio systemd[1]: plymouth-start.service: Succeeded. Aug 27 16:50:25 volumio systemd[1]: Started Show Plymouth Boot Screen. Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 27 16:50:25 volumio systemd-fsck[571]: fsck.fat 4.1 (2017-01-24) Aug 27 16:50:25 volumio systemd-fsck[571]: There are differences between boot sector and its backup. Aug 27 16:50:25 volumio systemd-fsck[571]: This is mostly harmless. Differences: (offset:original/backup) Aug 27 16:50:25 volumio systemd-fsck[571]: 3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74 Aug 27 16:50:25 volumio systemd-fsck[571]: , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22 Aug 27 16:50:25 volumio systemd-fsck[571]: , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb Aug 27 16:50:25 volumio systemd-fsck[571]: , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb Aug 27 16:50:25 volumio systemd-fsck[571]: , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd Aug 27 16:50:25 volumio systemd-fsck[571]: , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69 Aug 27 16:50:25 volumio systemd-fsck[571]: , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e Aug 27 16:50:25 volumio systemd-fsck[571]: , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62 Aug 27 16:50:25 volumio systemd-fsck[571]: , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c Aug 27 16:50:25 volumio systemd-fsck[571]: , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b Aug 27 16:50:25 volumio systemd-fsck[571]: , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61 Aug 27 16:50:25 volumio systemd-fsck[571]: , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73 Aug 27 16:50:25 volumio systemd-fsck[571]: , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20 Aug 27 16:50:25 volumio systemd-fsck[571]: , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62 Aug 27 16:50:25 volumio systemd-fsck[571]: , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f Aug 27 16:50:25 volumio systemd-fsck[571]: , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e Aug 27 16:50:25 volumio systemd-fsck[571]: , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65 Aug 27 16:50:25 volumio systemd-fsck[571]: , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79 Aug 27 16:50:25 volumio systemd-fsck[571]: , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74 Aug 27 16:50:25 volumio systemd-fsck[571]: , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20 Aug 27 16:50:25 volumio systemd-fsck[571]: , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20 Aug 27 16:50:25 volumio systemd-fsck[571]: , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a Aug 27 16:50:25 volumio systemd-fsck[571]: , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 282:96/00, 283:9b/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00 Aug 27 16:50:25 volumio systemd-fsck[571]: , 508:18/00, 509:37/00 Aug 27 16:50:25 volumio systemd-fsck[571]: Not automatically fixing this. Aug 27 16:50:25 volumio systemd-fsck[571]: /dev/sda1: 20 files, 159622/344788 clusters Aug 27 16:50:25 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/75B5-D8CD. Aug 27 16:50:25 volumio systemd[1]: Mounting /boot... Aug 27 16:50:25 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 27 16:50:25 volumio systemd[1]: Mounted /boot. Aug 27 16:50:25 volumio systemd[1]: Reached target Local File Systems. Aug 27 16:50:25 volumio systemd[1]: Starting Create Volatile Files and Directories... Aug 27 16:50:25 volumio systemd[1]: Started ifup for eth0. Aug 27 16:50:25 volumio systemd[1]: Starting Preprocess NFS configuration... Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 27 16:50:25 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 27 16:50:25 volumio systemd[1]: Starting Raise network interfaces... Aug 27 16:50:25 volumio systemd[1]: nfs-config.service: Succeeded. Aug 27 16:50:25 volumio systemd[1]: Started Preprocess NFS configuration. Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 27 16:50:25 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 27 16:50:25 volumio systemd[1]: Reached target NFS client services. Aug 27 16:50:25 volumio systemd[1]: Received SIGRTMIN+20 from PID 327 (plymouthd). Aug 27 16:50:25 volumio systemd[1]: plymouth-read-write.service: Succeeded. Aug 27 16:50:25 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 27 16:50:25 volumio systemd[1]: Started Create Volatile Files and Directories. Aug 27 16:50:26 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 27 16:50:26 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 27 16:50:26 volumio systemd[1]: Starting RPC bind portmap service... Aug 27 16:50:26 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 27 16:50:26 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 27 16:50:26 volumio systemd[1]: Started RPC bind portmap service. Aug 27 16:50:26 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 27 16:50:26 volumio systemd[1]: Reached target System Initialization. Aug 27 16:50:26 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 27 16:50:26 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 27 16:50:26 volumio systemd[1]: Started Daily man-db regeneration. Aug 27 16:50:26 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 27 16:50:26 volumio systemd[1]: Started Daily apt download activities. Aug 27 16:50:26 volumio systemd[1]: Started Daily apt upgrade and clean activities. Aug 27 16:50:26 volumio systemd[1]: Reached target Timers. Aug 27 16:50:26 volumio systemd[1]: Listening on triggerhappy.socket. Aug 27 16:50:26 volumio systemd[1]: Reached target Sockets. Aug 27 16:50:26 volumio systemd[1]: Reached target Basic System. Aug 27 16:50:26 volumio systemd[1]: Starting Login Service... Aug 27 16:50:26 volumio systemd[1]: Started Manage Sound Card State (restore and store). Aug 27 16:50:26 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 27 16:50:26 volumio systemd[1]: Started Volumio Log Rotation Service. Aug 27 16:50:26 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 27 16:50:26 volumio alsactl[608]: alsactl 1.1.8 daemon started Aug 27 16:50:26 volumio systemd[1]: Started Volumio Iptables Module. Aug 27 16:50:26 volumio systemd[1]: Started volumio-remote-updater.service. Aug 27 16:50:26 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 27 16:50:26 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 27 16:50:26 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 27 16:50:26 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Aug 27 16:50:26 volumio systemd[1]: Starting Wireless Services... Aug 27 16:50:26 volumio systemd[1]: Starting Save/Restore Sound Card State... Aug 27 16:50:26 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Aug 27 16:50:26 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 27 16:50:26 volumio systemd[1]: Started D-Bus System Message Bus. Aug 27 16:50:26 volumio ifup[585]: command failed: No such device (-19) Aug 27 16:50:26 volumio systemd[1]: Starting WPA supplicant... Aug 27 16:50:26 volumio sh[579]: command failed: No such device (-19) Aug 27 16:50:26 volumio systemd[1]: Starting DHCP Client Daemon... Aug 27 16:50:26 volumio systemd[1]: Reached target Remote File Systems (Pre). Aug 27 16:50:26 volumio systemd[1]: Reached target Remote File Systems. Aug 27 16:50:26 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 27 16:50:26 volumio thd[619]: Unable to parse trigger line: Aug 27 16:50:26 volumio thd[619]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 27 16:50:26 volumio thd[619]: Unable to parse trigger line: Aug 27 16:50:26 volumio thd[619]: Found socket passed from systemd Aug 27 16:50:26 volumio systemd[1]: Reached target RPC Port Mapper. Aug 27 16:50:26 volumio systemd-logind[605]: New seat seat0. Aug 27 16:50:26 volumio systemd-logind[605]: Watching system buttons on /dev/input/event0 (Power Button) Aug 27 16:50:26 volumio systemd-logind[605]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Aug 27 16:50:26 volumio avahi-daemon[612]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Aug 27 16:50:26 volumio avahi-daemon[612]: Successfully dropped root privileges. Aug 27 16:50:26 volumio avahi-daemon[612]: avahi-daemon 0.7 starting up. Aug 27 16:50:26 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 27 16:50:26 volumio sh[579]: sending commands to master dhcpcd process Aug 27 16:50:26 volumio dhcpcd[655]: sending commands to master dhcpcd process Aug 27 16:50:26 volumio ifup[585]: command failed: No such device (-19) Aug 27 16:50:26 volumio volumio-remote-updater[611]: Error: No active session Aug 27 16:50:26 volumio volumio-remote-updater[611]: [2024-08-27 16:50:26] [info] asio async_connect error: system:111 (Connection refused) Aug 27 16:50:26 volumio volumio-remote-updater[611]: [2024-08-27 16:50:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 27 16:50:26 volumio volumio-remote-updater[611]: [2024-08-27 16:50:26] [error] handle_connect error: Underlying Transport Error Aug 27 16:50:26 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 27 16:50:26 volumio systemd[1]: Started Login Service. Aug 27 16:50:26 volumio systemd[1]: Started triggerhappy global hotkey daemon. Aug 27 16:50:26 volumio avahi-daemon[612]: Successfully called chroot(). Aug 27 16:50:26 volumio systemd[1]: Started Save/Restore Sound Card State. Aug 27 16:50:26 volumio avahi-daemon[612]: Successfully dropped remaining capabilities. Aug 27 16:50:26 volumio avahi-daemon[612]: No service file found in /etc/avahi/services. Aug 27 16:50:26 volumio avahi-daemon[612]: Network interface enumeration completed. Aug 27 16:50:26 volumio avahi-daemon[612]: Server startup complete. Host name is volumio.local. Local service cookie is 1722512623. Aug 27 16:50:26 volumio dhcpcd[625]: no interfaces have a carrier Aug 27 16:50:26 volumio dhcpcd[625]: no interfaces have a carrier Aug 27 16:50:26 volumio dhcpcd[716]: eth0: waiting for carrier Aug 27 16:50:26 volumio dhcpcd[625]: forked to background, child pid 716 Aug 27 16:50:26 volumio dhcpcd[716]: eth0: carrier acquired Aug 27 16:50:26 volumio dhcpcd[625]: forked to background, child pid 716 Aug 27 16:50:26 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Aug 27 16:50:26 volumio systemd[1]: Started DHCP Client Daemon. Aug 27 16:50:26 volumio sh[579]: eth0=eth0 Aug 27 16:50:26 volumio wpa_supplicant[624]: Successfully initialized wpa_supplicant Aug 27 16:50:26 volumio dhcpcd[716]: DUID 00:04:c5:6c:2a:b4:2b:e2:45:18:a6:3a:46:22:42:2b:c1:85 Aug 27 16:50:26 volumio dhcpcd[716]: eth0: IAID 11:31:51:8d Aug 27 16:50:26 volumio dhcpcd[716]: eth0: adding address fe80::dd3d:716:d85b:c73a Aug 27 16:50:26 volumio dhcpcd[716]: ipv6_addaddr1: Permission denied Aug 27 16:50:26 volumio dhcpcd[716]: control command: /sbin/dhcpcd eth0 Aug 27 16:50:26 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 27 16:50:26 volumio systemd[1]: Started WPA supplicant. Aug 27 16:50:26 volumio systemd[1]: Reached target Sound Card. Aug 27 16:50:26 volumio volumio[620]: Could not open config: /tmp/upmpdcli.conf Aug 27 16:50:26 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 16:50:26 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 16:50:26 volumio systemd[1]: iptables.service: Succeeded. Aug 27 16:50:26 volumio systemd[1]: Started Raise network interfaces. Aug 27 16:50:26 volumio systemd[1]: Reached target Network. Aug 27 16:50:26 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 27 16:50:26 volumio systemd[1]: Starting OpenBSD Secure Shell server... Aug 27 16:50:26 volumio systemd[1]: Starting Network Time Service... Aug 27 16:50:26 volumio systemd[1]: Starting Permit User Sessions... Aug 27 16:50:26 volumio systemd[1]: Reached target Network is Online. Aug 27 16:50:26 volumio haveged[598]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Aug 27 16:50:26 volumio haveged[598]: haveged: cpu: (L4 VC); data: 32K (L2 L4 V); inst: 32K (L2 L4 V); idx: 24/40; sz: 31410/52825 Aug 27 16:50:26 volumio haveged[598]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00188 Aug 27 16:50:26 volumio haveged[598]: haveged: fills: 0, generated: 0 Aug 27 16:50:26 volumio systemd[1]: Starting Samba NMB Daemon... Aug 27 16:50:26 volumio systemd[1]: Starting /etc/rc.local Compatibility... Aug 27 16:50:26 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Aug 27 16:50:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 16:50:26 volumio systemd[1]: Started Permit User Sessions. Aug 27 16:50:26 volumio loadcpufreq[626]: Loading cpufreq kernel modules...failed. Aug 27 16:50:26 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 27 16:50:26 volumio systemd[1]: Started /etc/rc.local Compatibility. Aug 27 16:50:26 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 27 16:50:26 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 27 16:50:26 volumio systemd[1]: Starting Hold until boot process finishes up... Aug 27 16:50:26 volumio systemd[1]: Received SIGRTMIN+21 from PID 327 (plymouthd). Aug 27 16:50:26 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 27 16:50:26 volumio systemd[1]: Started Hold until boot process finishes up. Aug 27 16:50:26 volumio systemd[1]: plymouth-quit.service: Succeeded. Aug 27 16:50:26 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Aug 27 16:50:26 volumio systemd[1]: Received SIGRTMIN+21 from PID 327 (n/a). Aug 27 16:50:26 volumio systemd[1]: Started Getty on tty1. Aug 27 16:50:26 volumio systemd[1]: Reached target Login Prompts. Aug 27 16:50:26 volumio ifplugd(eth0)[810]: ifplugd 0.28 initializing. Aug 27 16:50:26 volumio ifplugd(eth0)[810]: Using interface eth0/BC:24:11:31:51:8D with driver (version: 1.0.0) Aug 27 16:50:26 volumio ifplugd(eth0)[810]: Using detection mode: SIOCETHTOOL Aug 27 16:50:26 volumio ifplugd(eth0)[810]: Initialization complete, link beat detected. Aug 27 16:50:26 volumio cpufrequtils[791]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Aug 27 16:50:26 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 27 16:50:26 volumio sshd[811]: Server listening on 0.0.0.0 port 22. Aug 27 16:50:26 volumio systemd[1]: Started OpenBSD Secure Shell server. Aug 27 16:50:26 volumio sshd[811]: Server listening on :: port 22. Aug 27 16:50:26 volumio ntpd[772]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 27 16:50:26 volumio systemd[1]: Started Network Time Service. Aug 27 16:50:26 volumio ntpd[772]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 27 16:50:26 volumio ntpd[813]: proto: precision = 0.064 usec (-24) Aug 27 16:50:26 volumio ntpd[813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 27 16:50:26 volumio ntpd[813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 27 16:50:26 volumio ntpd[813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 609 days ago Aug 27 16:50:26 volumio ntpd[813]: Listen and drop on 0 v6wildcard [::]:123 Aug 27 16:50:26 volumio ntpd[813]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 27 16:50:26 volumio ntpd[813]: Listen normally on 2 lo 127.0.0.1:123 Aug 27 16:50:26 volumio ntpd[813]: Listening on routing socket on fd #19 for interface updates Aug 27 16:50:26 volumio ntpd[813]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 27 16:50:26 volumio ntpd[813]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 27 16:50:26 volumio dhcpcd[716]: eth0: soliciting an IPv6 router Aug 27 16:50:26 volumio ifplugd(eth0)[810]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 27 16:50:26 volumio ifplugd(eth0)[810]: client: ifup: interface eth0 already configured Aug 27 16:50:26 volumio ifplugd[778]: Network Interface Plugging Daemon...start eth0...done. Aug 27 16:50:26 volumio systemd[1]: Started LSB: Brings up/down network automatically. Aug 27 16:50:26 volumio ifplugd(eth0)[810]: Program executed successfully. Aug 27 16:50:26 volumio nmbd[785]: [2024/08/27 16:50:26.835281, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 27 16:50:26 volumio nmbd[785]: started asyncdns process 828 Aug 27 16:50:26 volumio nmbd[785]: [2024/08/27 16:50:26.835796, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 27 16:50:26 volumio nmbd[785]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 27 16:50:26 volumio nmbd[785]: [2024/08/27 16:50:26.835835, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 27 16:50:26 volumio nmbd[785]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 27 16:50:26 volumio wireless.js[614]: WIRELESS: No wireless interface, exiting Aug 27 16:50:26 volumio iw[833]: command failed: No such device (-19) Aug 27 16:50:26 volumio iwconfig[836]: Error for wireless request "Set Power Management" (8B2C) : Aug 27 16:50:26 volumio iwconfig[836]: SET failed on device wlan0 ; No such device. Aug 27 16:50:26 volumio systemd[1]: wireless.service: Succeeded. Aug 27 16:50:26 volumio systemd[1]: Started Wireless Services. Aug 27 16:50:26 volumio systemd[1]: Started Volumio Backend Module. Aug 27 16:50:26 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Aug 27 16:50:26 volumio systemd[1]: Started Volumio Cpu Tweaker. Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: Setting RT Priority for mpd Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: pid 35's current scheduling policy: SCHED_OTHER Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: pid 35's current scheduling priority: 0 Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: Not enough cores to set MPD affinity Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: Error setting new values. Common errors: Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: - Do you have proper administration rights? (super-user?) Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: - Is the governor you requested available and modprobed? Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: - Trying to set an invalid policy? Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: - Trying to set a specific frequency, but userspace governor is not available, Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: for example because of hardware which cannot be set to a specific frequency Aug 27 16:50:26 volumio volumio-cpu-tweak[841]: or because the userspace governor isn't loaded? Aug 27 16:50:26 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 27 16:50:27 volumio dhcpcd[716]: eth0: soliciting a DHCP lease Aug 27 16:50:27 volumio dhcpcd[716]: eth0: offered 192.168.101.59 from 192.168.101.1 Aug 27 16:50:27 volumio dhcpcd[716]: eth0: probing address 192.168.101.59/24 Aug 27 16:50:27 volumio systemd[1]: soundcard-init.service: Succeeded. Aug 27 16:50:27 volumio volumio[839]: info: ------------------------------------------- Aug 27 16:50:27 volumio volumio[839]: info: ----- Volumio3 ---- Aug 27 16:50:27 volumio volumio[839]: info: ------------------------------------------- Aug 27 16:50:27 volumio volumio[839]: info: ----- System startup ---- Aug 27 16:50:27 volumio volumio[839]: info: ------------------------------------------- Aug 27 16:50:27 volumio ntpd[813]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 27 16:50:27 volumio volumio[839]: info: MYVOLUMIO Environment detected Aug 27 16:50:27 volumio volumio[839]: info: Plugin folders cleanup Aug 27 16:50:27 volumio volumio[839]: info: Scanning into folder /volumio/app/plugins/ Aug 27 16:50:27 volumio volumio[839]: info: Scanning category audio_interface Aug 27 16:50:27 volumio volumio[839]: info: Scanning category miscellanea Aug 27 16:50:27 volumio volumio[839]: info: Scanning category music_service Aug 27 16:50:27 volumio volumio[839]: info: Scanning category plugins.json Aug 27 16:50:27 volumio volumio[839]: info: Scanning category system_controller Aug 27 16:50:27 volumio volumio[839]: info: Scanning category user_interface Aug 27 16:50:27 volumio volumio[839]: info: Scanning into folder /data/plugins/ Aug 27 16:50:27 volumio volumio[839]: info: Scanning category music_service Aug 27 16:50:27 volumio volumio[839]: info: Scanning category system_controller Aug 27 16:50:27 volumio volumio[839]: info: Scanning category user_interface Aug 27 16:50:27 volumio volumio[839]: info: Plugin folders cleanup completed Aug 27 16:50:27 volumio volumio[839]: info: ------------------------------------------- Aug 27 16:50:27 volumio volumio[839]: info: ----- Core plugins startup ---- Aug 27 16:50:27 volumio volumio[839]: info: ------------------------------------------- Aug 27 16:50:27 volumio volumio[839]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 16:50:27 volumio volumio[839]: info: Adding plugin upnp to MyMusic Plugins Aug 27 16:50:27 volumio volumio[839]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 16:50:27 volumio volumio[839]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 16:50:27 volumio volumio[839]: info: Loading plugins from folder /data/plugins/ Aug 27 16:50:27 volumio volumio[839]: info: Loading plugin "system"... Aug 27 16:50:27 volumio volumio[839]: info: Loading plugin "appearance"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "network"... Aug 27 16:50:28 volumio volumio[839]: info: Refreshing Cached IP Addresses Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "services"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "alsa_controller"... Aug 27 16:50:28 volumio sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 16:50:28 volumio sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 16:50:28 volumio sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:28 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "wizard"... Aug 27 16:50:28 volumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 16:50:28 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:28 volumio sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:28 volumio sudo[958]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "networkfs"... Aug 27 16:50:28 volumio sudo[960]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:28 volumio sudo[962]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:28 volumio volumio[839]: info: Starting Udev Watcher for removable devices Aug 27 16:50:28 volumio sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=apleitz,password=D45Oct9aNr,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.101.62/MusicDB /mnt/NAS/TrueNAS Aug 27 16:50:28 volumio sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:28 volumio volumio[839]: info: Ignoring mount for partition: boot Aug 27 16:50:28 volumio volumio[839]: info: Ignoring mount for partition: volumio Aug 27 16:50:28 volumio volumio[839]: info: Ignoring mount for partition: volumio_data Aug 27 16:50:28 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "volumio_command_line_client"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "upnp"... Aug 27 16:50:28 volumio kernel: FS-Cache: Loaded Aug 27 16:50:28 volumio volumio[839]: info: [1724770228293] Starting Upmpd Daemon Aug 27 16:50:28 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "my_music"... Aug 27 16:50:28 volumio kernel: Key type dns_resolver registered Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "mpd"... Aug 27 16:50:28 volumio kernel: Key type cifs.spnego registered Aug 27 16:50:28 volumio kernel: Key type cifs.idmap registered Aug 27 16:50:28 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Aug 27 16:50:28 volumio kernel: CIFS: Attempting to mount //192.168.101.62/MusicDB Aug 27 16:50:28 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 27 16:50:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 27 16:50:28 volumio sudo[973]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "upnp_browser"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "alarm-clock"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "airplay_emulation"... Aug 27 16:50:28 volumio volumio[839]: info: Starting Shairport Sync Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "last_100"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "webradio"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "i2s_dacs"... Aug 27 16:50:28 volumio volumio[839]: info: I2S DAC not set, start Auto-detection Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "volumiodiscovery"... Aug 27 16:50:28 volumio ntpd[813]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 27 16:50:28 volumio volumio[839]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 16:50:28 volumio volumio[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 16:50:28 volumio node[839]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 16:50:28 volumio volumio[839]: *** WARNING *** For more information see Aug 27 16:50:28 volumio volumio[839]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 16:50:28 volumio volumio[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 16:50:28 volumio volumio[839]: *** WARNING *** For more information see Aug 27 16:50:28 volumio node[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 16:50:28 volumio node[839]: *** WARNING *** For more information see Aug 27 16:50:28 volumio node[839]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 16:50:28 volumio node[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 16:50:28 volumio node[839]: *** WARNING *** For more information see Aug 27 16:50:28 volumio volumio[839]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 16:50:28 volumio volumio[839]: info: Discovery: Started advertising with name: Volumio Aug 27 16:50:28 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "ytmusic"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "outputs"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "albumart"... Aug 27 16:50:28 volumio volumio[839]: info: Plugin example_plugin is not enabled Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "inputs"... Aug 27 16:50:28 volumio volumio[839]: info: Loading plugin "updater_comm"... Aug 27 16:50:29 volumio volumio[839]: Forking 1 albumart workers Aug 27 16:50:29 volumio volumio[839]: info: Plugin mpdemulation is not enabled Aug 27 16:50:29 volumio volumio[839]: info: Loading plugin "rest_api"... Aug 27 16:50:29 volumio volumio[839]: info: Loading plugin "websocket"... Aug 27 16:50:29 volumio volumio[839]: info: Starting Socket.io Server version 2.3.0 Aug 27 16:50:29 volumio volumio[839]: info: Loading plugin "backup_restore"... Aug 27 16:50:29 volumio volumio[839]: Starting albumart workers Aug 27 16:50:29 volumio volumio[839]: info: Applying required configuration parameters for plugin backup_restore Aug 27 16:50:29 volumio volumio[839]: info: Plugin peppymeterbasic is not enabled Aug 27 16:50:29 volumio volumio[839]: info: Loading i18n strings for locale de Aug 27 16:50:29 volumio volumio[839]: Updating browse sources language Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::initPlayerControls Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 16:50:29 volumio volumio[839]: Express server listening on port 3000 Aug 27 16:50:29 volumio volumio[839]: [Metrics] WebUI: 1s 879.12ms Aug 27 16:50:29 volumio volumio[839]: info: Setting Device type: x86 Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::resetVolumioState Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::getcurrentVolume Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:29 volumio volumio[839]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 27 16:50:29 volumio volumio[839]: info: Completed loading Core Plugins Aug 27 16:50:29 volumio volumio[839]: info: Preparing to generate the ALSA configuration file Aug 27 16:50:29 volumio volumio[839]: info: Cannot mount NAS TrueNAS at system boot, trial number 1 ,retrying in 5 seconds Aug 27 16:50:29 volumio volumio[839]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 27 16:50:29 volumio volumio[839]: wlan0 Interface doesn't support scanning. Aug 27 16:50:29 volumio volumio[839]: info: Cannot use regular scanning, forcing with ap-force Aug 27 16:50:29 volumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 27 16:50:29 volumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio sudo[1016]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:29 volumio volumio[839]: command failed: No such device (-19) Aug 27 16:50:29 volumio volumio[839]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Aug 27 16:50:29 volumio volumio[839]: command failed: No such device (-19) Aug 27 16:50:29 volumio volumio[839]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 27 16:50:29 volumio volumio[839]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 16:50:29 volumio volumio[839]: info: Reading ALSA contributions from plugins. Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::pushState Aug 27 16:50:29 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock Aug 27 16:50:29 volumio volumio[839]: info: CorePlayQueue::getTrackBlock Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 16:50:29 volumio volumio[839]: info: Reloading queue from file Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::setRepeat false single undefined Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::pushState Aug 27 16:50:29 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::setRandom false Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::pushState Aug 27 16:50:29 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Aug 27 16:50:29 volumio volumio[839]: info: CoreStateMachine::pushState Aug 27 16:50:29 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Aug 27 16:50:29 volumio volumio[839]: info: Asound.conf file unchanged, so no further update is needed Aug 27 16:50:29 volumio volumio[839]: info: Output device has changed, restarting MPD Aug 27 16:50:29 volumio sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 16:50:29 volumio volumio[839]: info: Output device has changed, restarting Shairport Sync Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:29 volumio sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 16:50:29 volumio sudo[1031]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:29 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 16:50:29 volumio volumio[839]: info: ___________ START PLUGINS ___________ Aug 27 16:50:29 volumio volumio[839]: info: ControllerMpd::onStart: Initializing MPD Aug 27 16:50:29 volumio volumio[839]: info: Creating MPD Configuration file Aug 27 16:50:29 volumio systemd[1]: Listening on mpd.socket. Aug 27 16:50:29 volumio sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 16:50:29 volumio sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 16:50:29 volumio volumio[839]: info: [1724770229339] CoreMusicLibrary::Adding element Medienserver Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:29 volumio systemd[1]: Starting Music Player Daemon... Aug 27 16:50:29 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 16:50:29 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio sudo[1041]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:29 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 27 16:50:29 volumio systemd[1]: mpd.service: Succeeded. Aug 27 16:50:29 volumio systemd[1]: Stopped Music Player Daemon. Aug 27 16:50:29 volumio systemd[1]: Starting Music Player Daemon... Aug 27 16:50:29 volumio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 16:50:29 volumio volumio[839]: info: [1724770229376] CoreMusicLibrary::Adding element Last_100 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 16:50:29 volumio volumio[839]: info: [1724770229377] CoreMusicLibrary::Adding element Webradio Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 16:50:29 volumio volumio[839]: info: Initializing BBC Radios Aug 27 16:50:29 volumio sudo[1049]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 16:50:29 volumio sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio sudo[1049]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 16:50:29 volumio sudo[1049]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 16:50:29 volumio volumio[839]: info: [1724770229401] CoreMusicLibrary::Adding element YouTube Music Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 16:50:29 volumio volumio[839]: Cannot find translation for source YouTube Music Aug 27 16:50:29 volumio volumio[839]: info: Volumio Calling Home Aug 27 16:50:29 volumio volumio[839]: Unhandled rejection Error: No sockets available, cannot start. Aug 27 16:50:29 volumio volumio[839]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 27 16:50:29 volumio volumio[839]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 27 16:50:29 volumio volumio[839]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 27 16:50:29 volumio volumio[839]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 27 16:50:29 volumio volumio[839]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 27 16:50:29 volumio volumio[839]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 27 16:50:29 volumio volumio[839]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 27 16:50:29 volumio volumio[839]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 27 16:50:29 volumio volumio[839]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 27 16:50:29 volumio volumio[839]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 27 16:50:29 volumio volumio[839]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 27 16:50:29 volumio volumio[839]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 27 16:50:29 volumio volumio[839]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 27 16:50:29 volumio volumio[839]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 27 16:50:29 volumio volumio[839]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 27 16:50:29 volumio volumio[839]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 27 16:50:29 volumio volumio[839]: info: MPD Permissions set Aug 27 16:50:29 volumio volumio[839]: info: MPD Permissions set Aug 27 16:50:29 volumio volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 16:50:29 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:29 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:29 volumio volumio[839]: info: Starting Shairport Sync Aug 27 16:50:29 volumio volumio[839]: info: Starting Shairport Sync Aug 27 16:50:29 volumio sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 16:50:29 volumio volumio[839]: info: Starting Shairport Sync Aug 27 16:50:29 volumio sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 16:50:29 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 16:50:29 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 27 16:50:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 16:50:29 volumio sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 16:50:29 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 16:50:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 16:50:29 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:29 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 27 16:50:29 volumio volumio[839]: info: Shairport-Sync Started Aug 27 16:50:29 volumio volumio[839]: Error adding Membership: Error: addMembership EINVAL Aug 27 16:50:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 16:50:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 16:50:29 volumio sudo[1081]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:29 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:29 volumio volumio[839]: info: Shairport-Sync Started Aug 27 16:50:29 volumio volumio[839]: info: Shairport-Sync Started Aug 27 16:50:29 volumio ntpd[813]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 27 16:50:30 volumio mpd[1056]: Aug 27 16:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 16:50:30 volumio systemd[1]: Started Music Player Daemon. Aug 27 16:50:30 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:30 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:30 volumio volumio[839]: info: Completed starting Core Plugins Aug 27 16:50:30 volumio volumio[839]: info: ------------------------------------------- Aug 27 16:50:30 volumio volumio[839]: info: ----- MyVolumio plugins startup ---- Aug 27 16:50:30 volumio volumio[839]: info: ------------------------------------------- Aug 27 16:50:30 volumio volumio[839]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 16:50:30 volumio volumio[839]: error: MPD error: The expression evaluated to a falsy value: Aug 27 16:50:30 volumio volumio[839]: assert.ok(self.idling) Aug 27 16:50:30 volumio volumio[839]: error: The expression evaluated to a falsy value: Aug 27 16:50:30 volumio volumio[839]: assert.ok(self.idling) Aug 27 16:50:30 volumio volumio[839]: info: MPD running with PID1056 Aug 27 16:50:30 volumio volumio[839]: ,establishing connection Aug 27 16:50:30 volumio volumio[839]: error: updateQueue error: null Aug 27 16:50:30 volumio volumio[839]: error: updateQueue error: null Aug 27 16:50:30 volumio ntpd[813]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 27 16:50:31 volumio volumio-remote-updater[611]: [2024-08-27 16:50:31] [connect] Successful connection Aug 27 16:50:31 volumio volumio-remote-updater[611]: [2024-08-27 16:50:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724770231 101 Aug 27 16:50:31 volumio volumio[839]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Aug 27 16:50:32 volumio dhcpcd[716]: eth0: leased 192.168.101.59 for 864000 seconds Aug 27 16:50:32 volumio dhcpcd[716]: eth0: adding route to 192.168.101.0/24 Aug 27 16:50:32 volumio dhcpcd[716]: eth0: adding default route via 192.168.101.1 Aug 27 16:50:32 volumio avahi-daemon[612]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.101.59. Aug 27 16:50:32 volumio avahi-daemon[612]: New relevant interface eth0.IPv4 for mDNS. Aug 27 16:50:32 volumio avahi-daemon[612]: Registering new address record for 192.168.101.59 on eth0.IPv4. Aug 27 16:50:32 volumio volumio[839]: verbose: New Socket.io Connection to 192.168.101.59 from 192.168.101.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 3 Aug 27 16:50:32 volumio volumio[839]: verbose: New Socket.io Connection to 192.168.101.59 from 192.168.101.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 27 16:50:32 volumio volumio[839]: verbose: New Socket.io Connection to 192.168.101.59 from 192.168.101.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 5 Aug 27 16:50:32 volumio volumio[839]: verbose: New Socket.io Connection to 192.168.101.59 from 192.168.101.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 6 Aug 27 16:50:32 volumio systemd[1]: Stopping Network Time Service... Aug 27 16:50:32 volumio ntpd[813]: ntpd exiting on signal 15 (Terminated) Aug 27 16:50:32 volumio systemd[1]: ntp.service: Succeeded. Aug 27 16:50:32 volumio systemd[1]: Stopped Network Time Service. Aug 27 16:50:32 volumio systemd[1]: Starting Network Time Service... Aug 27 16:50:32 volumio ntpd[1132]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 27 16:50:32 volumio ntpd[1132]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 27 16:50:32 volumio systemd[1]: Started Network Time Service. Aug 27 16:50:32 volumio ntpd[1138]: proto: precision = 0.064 usec (-24) Aug 27 16:50:32 volumio ntpd[1138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 27 16:50:32 volumio ntpd[1138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 27 16:50:32 volumio ntpd[1138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 609 days ago Aug 27 16:50:32 volumio ntpd[1138]: Listen and drop on 0 v6wildcard [::]:123 Aug 27 16:50:32 volumio ntpd[1138]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 27 16:50:32 volumio ntpd[1138]: Listen normally on 2 lo 127.0.0.1:123 Aug 27 16:50:32 volumio ntpd[1138]: Listen normally on 3 eth0 192.168.101.59:123 Aug 27 16:50:32 volumio ntpd[1138]: Listening on routing socket on fd #20 for interface updates Aug 27 16:50:32 volumio ntpd[1138]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 27 16:50:32 volumio ntpd[1138]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 27 16:50:32 volumio volumio[839]: verbose: New Socket.io Connection to 192.168.101.59 from 192.168.101.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 7 Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:32 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:32 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 16:50:32 volumio volumio[839]: info: Received Get System Info Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 16:50:32 volumio volumio[839]: info: Discovery: Getting this device information Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:32 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:32 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:32 volumio volumio[839]: info: Listing playlists Aug 27 16:50:32 volumio volumio[839]: info: CoreCommandRouter::volumioGetQueue Aug 27 16:50:32 volumio volumio[839]: info: CoreStateMachine::getQueue Aug 27 16:50:32 volumio volumio[839]: info: CorePlayQueue::getQueue Aug 27 16:50:33 volumio volumio[839]: verbose: New Socket.io Connection to volumio.local:3000 from 192.168.101.60 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 27 16:50:33 volumio volumio[839]: info: Discovery: adding a80aca55-4941-43cc-8495-9325d53447ff Aug 27 16:50:33 volumio volumio[839]: info: Discovery: Found device Volumio Aug 27 16:50:33 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:33 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:33 volumio ntpd[1138]: Soliciting pool server 85.214.38.116 Aug 27 16:50:34 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=apleitz,password=D45Oct9aNr,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.101.62/MusicDB /mnt/NAS/TrueNAS Aug 27 16:50:34 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:34 volumio kernel: CIFS: Attempting to mount //192.168.101.62/MusicDB Aug 27 16:50:34 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:34 volumio ntpd[1138]: Soliciting pool server 161.97.164.152 Aug 27 16:50:34 volumio ntpd[1138]: Soliciting pool server 167.71.55.144 Aug 27 16:50:35 volumio volumio[839]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 16:50:35 volumio ntpd[1138]: Soliciting pool server 185.13.148.71 Aug 27 16:50:35 volumio ntpd[1138]: Soliciting pool server 162.159.200.1 Aug 27 16:50:35 volumio ntpd[1138]: Soliciting pool server 78.47.56.71 Aug 27 16:50:36 volumio ntpd[1138]: Soliciting pool server 194.50.19.204 Aug 27 16:50:36 volumio ntpd[1138]: Soliciting pool server 176.9.44.212 Aug 27 16:50:36 volumio ntpd[1138]: Soliciting pool server 78.46.102.180 Aug 27 16:50:36 volumio ntpd[1138]: Soliciting pool server 144.91.116.85 Aug 27 16:50:36 volumio nmbd[785]: [2024/08/27 16:50:36.837713, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 27 16:50:36 volumio nmbd[785]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 27 16:50:36 volumio systemd[1]: Started Samba NMB Daemon. Aug 27 16:50:36 volumio systemd[1]: Starting Samba Winbind Daemon... Aug 27 16:50:36 volumio nmbd[785]: [2024/08/27 16:50:36.843994, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 27 16:50:36 volumio nmbd[785]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.101.59 for name WORKGROUP<1d>. Aug 27 16:50:36 volumio nmbd[785]: This response was from IP 192.168.101.42, reporting an IP address of 192.168.101.42. Aug 27 16:50:36 volumio winbindd[1166]: [2024/08/27 16:50:36.924052, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 27 16:50:36 volumio winbindd[1166]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 27 16:50:36 volumio systemd[1]: Started Samba Winbind Daemon. Aug 27 16:50:36 volumio winbindd[1166]: [2024/08/27 16:50:36.929038, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 27 16:50:36 volumio winbindd[1166]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 27 16:50:36 volumio systemd[1]: Starting Samba SMB Daemon... Aug 27 16:50:37 volumio smbd[1171]: [2024/08/27 16:50:37.069895, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 27 16:50:37 volumio systemd[1]: Started Samba SMB Daemon. Aug 27 16:50:37 volumio smbd[1171]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 27 16:50:37 volumio systemd[1]: Reached target Multi-User System. Aug 27 16:50:37 volumio systemd[1]: Reached target Graphical Interface. Aug 27 16:50:37 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 27 16:50:37 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 27 16:50:37 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 27 16:50:37 volumio systemd[1]: Startup finished in 6.388s (kernel) + 12.560s (userspace) = 18.949s. Aug 27 16:50:37 volumio ntpd[1138]: Soliciting pool server 81.169.204.195 Aug 27 16:50:37 volumio ntpd[1138]: Soliciting pool server 88.99.86.9 Aug 27 16:50:37 volumio ntpd[1138]: Soliciting pool server 75.119.140.230 Aug 27 16:50:37 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:37 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:37 volumio volumio[839]: info: Listing playlists Aug 27 16:50:37 volumio volumio[839]: info: Listing playlists Aug 27 16:50:38 volumio volumio[839]: info: CoreCommandRouter::volumioGetState Aug 27 16:50:38 volumio volumio[839]: info: CorePlayQueue::getTrack 0 Aug 27 16:50:38 volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 16:50:38 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:38 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:38 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 16:50:38 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:38 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:38 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 16:50:38 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 27 16:50:38 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:38 volumio volumio[839]: info: Upmpdcli Daemon Started Aug 27 16:50:38 volumio volumio[1191]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 27 16:50:38 volumio ntpd[1138]: Soliciting pool server 148.251.5.46 Aug 27 16:50:38 volumio ntpd[1138]: Soliciting pool server 212.18.3.18 Aug 27 16:50:38 volumio volumio[1191]: ..........................................++++ Aug 27 16:50:38 volumio volumio[1191]: ............++++ Aug 27 16:50:38 volumio volumio[1191]: e is 65537 (0x010001) Aug 27 16:50:38 volumio volumio[1191]: writing RSA key Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 16:50:38 volumio volumio[839]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 16:50:38 volumio volumio[839]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 16:50:38 volumio volumio[839]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 16:50:38 volumio volumio[839]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 16:50:38 volumio volumio[839]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 16:50:38 volumio volumio[839]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 16:50:38 volumio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 16:50:39 volumio volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 16:50:39 volumio volumio[839]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 16:50:39 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:39 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 16:50:39 volumio volumio[839]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 16:50:39 volumio volumio[839]: info: MyVolumio login type: Token Aug 27 16:50:39 volumio volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 16:50:39 volumio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 27 16:50:39 volumio volumio[839]: info: Starting Streaming Service Transparent Proxy Aug 27 16:50:39 volumio volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 27 16:50:39 volumio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 27 16:50:39 volumio volumio[839]: info: Streaming services startup Aug 27 16:50:39 volumio volumio[839]: info: Starting Streaming Daemon Aug 27 16:50:39 volumio sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 16:50:39 volumio sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:39 volumio sudo[1229]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:39 volumio volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 27 16:50:39 volumio volumio[839]: error: Cannot start Volumio Streaming Daemon Aug 27 16:50:39 volumio volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 16:50:39 volumio volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 16:50:39 volumio volumio[839]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.742&uuid=2f64e30d4ae6e162362ece0ca0ecc5b9" http://updates.volumio.org/downloader-v1/track-device Aug 27 16:50:39 volumio volumio[839]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 27 16:50:39 volumio volumio[839]: Dload Upload Total Spent Left Speed Aug 27 16:50:39 volumio volumio[839]: [132B blob data] Aug 27 16:50:39 volumio volumio[839]: retrying in 5 seconds, trial 0 Aug 27 16:50:39 volumio volumio[839]: info: Volumio Calling Home Aug 27 16:50:39 volumio volumio[839]: STREAMING PROXY: Starting server on port 3245 Aug 27 16:50:39 volumio volumio[839]: Node JS runtime: 14 Aug 27 16:50:39 volumio ntpd[1138]: Soliciting pool server 178.63.67.56 Aug 27 16:50:39 volumio ntpd[1138]: Soliciting pool server 2603:c020:800c:5600:2d86:2529:ebb0:376a Aug 27 16:50:39 volumio volumio[839]: info: Volumio called home Aug 27 16:50:39 volumio volumio[839]: error: MyVolumio Custom Token format not valid, refreshing it Aug 27 16:50:40 volumio volumio[839]: info: MyVolumio login type: Token Aug 27 16:50:40 volumio volumio[839]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 27 16:50:41 volumio volumio[839]: info: MyVolumio token set successfully Aug 27 16:50:41 volumio volumio[839]: info: MYVOLUMIO: Adding device Aug 27 16:50:41 volumio volumio[839]: info: MYVOLUMIO: Evaluating Server Aug 27 16:50:41 volumio volumio[839]: info: MyVolumio status changed Aug 27 16:50:41 volumio volumio[839]: info: Streaming services startup Aug 27 16:50:41 volumio volumio[839]: info: Starting Streaming Daemon Aug 27 16:50:41 volumio volumio[839]: info: Removing browser output: myVolumio user plan is not superstar Aug 27 16:50:41 volumio volumio[839]: info: Removing audio output: Aug 27 16:50:41 volumio volumio[839]: info: Stoppping Tunnel 1 Aug 27 16:50:41 volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 16:50:41 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:41 volumio sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 27 16:50:41 volumio sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 16:50:41 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:41 volumio volumio[839]: error: Cannot start Volumio Streaming Daemon Aug 27 16:50:41 volumio volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 16:50:41 volumio volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 16:50:41 volumio sudo[1260]: pam_unix(sudo:session): session closed for user root Aug 27 16:50:41 volumio volumio[839]: info: Remote SSH Stopped Aug 27 16:50:41 volumio ntpd[1138]: receive: Unexpected origin timestamp 0xea786641.845a8ce7 does not match aorg 0000000000.00000000 from server@148.251.5.46 xmt 0xea786641.20f19660 Aug 27 16:50:41 volumio ntpd[1138]: receive: Unexpected origin timestamp 0xea786641.845ba400 does not match aorg 0000000000.00000000 from server@78.46.102.180 xmt 0xea786641.210e26e2 Aug 27 16:50:41 volumio ntpd[1138]: receive: Unexpected origin timestamp 0xea786641.845bff94 does not match aorg 0000000000.00000000 from server@176.9.44.212 xmt 0xea786641.20f22006 Aug 27 16:50:41 volumio ntpd[1138]: receive: Unexpected origin timestamp 0xea786641.8457efaf does not match aorg 0000000000.00000000 from server@212.18.3.18 xmt 0xea786641.210edbd8 Aug 27 16:50:41 volumio ntpd[1138]: receive: Unexpected origin timestamp 0xea786641.845b399e does not match aorg 0000000000.00000000 from server@144.91.116.85 xmt 0xea786641.2ab7edbe Aug 27 16:50:41 volumio volumio[839]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Aug 27 16:50:41 volumio volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 16:50:41 volumio volumio[839]: Error: Unable to resolve or reject the same promise twice Aug 27 16:50:41 volumio volumio[839]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 27 16:50:41 volumio volumio[839]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Aug 27 16:50:41 volumio volumio[839]: at Socket.emit (events.js:412:35) Aug 27 16:50:41 volumio volumio[839]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 27 16:50:41 volumio volumio[839]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 27 16:50:41 volumio volumio[839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 16:50:41 volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-27 16:49 Aug 27 16:50:41 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"