-- Logs begin at Wed 2024-10-16 15:23:50 BST, end at Wed 2024-10-16 15:24:31 BST. -- Oct 16 15:23:50 volumiovm 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 Oct 16 15:23:50 volumiovm kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=e0f53ba4-066a-4701-a5d4-ead7076192e5 bootpart=UUID=4752-A5A7 datapart=UUID=82a1940a-f5a8-4717-ad17-0730260e88c8 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 Oct 16 15:23:50 volumiovm kernel: KERNEL supported cpus: Oct 16 15:23:50 volumiovm kernel: Intel GenuineIntel Oct 16 15:23:50 volumiovm kernel: AMD AuthenticAMD Oct 16 15:23:50 volumiovm kernel: Hygon HygonGenuine Oct 16 15:23:50 volumiovm kernel: Centaur CentaurHauls Oct 16 15:23:50 volumiovm kernel: zhaoxin Shanghai Oct 16 15:23:50 volumiovm kernel: [Firmware Bug]: TSC doesn't count with P0 frequency! Oct 16 15:23:50 volumiovm kernel: BIOS-provided physical RAM map: Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ffeffff] usable Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x000000003fff0000-0x000000003fffffff] ACPI data Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved Oct 16 15:23:50 volumiovm kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Oct 16 15:23:50 volumiovm kernel: NX (Execute Disable) protection: active Oct 16 15:23:50 volumiovm kernel: APIC: Static calls initialized Oct 16 15:23:50 volumiovm kernel: SMBIOS 2.5 present. Oct 16 15:23:50 volumiovm kernel: DMI: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 Oct 16 15:23:50 volumiovm kernel: Hypervisor detected: KVM Oct 16 15:23:50 volumiovm kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Oct 16 15:23:50 volumiovm kernel: kvm-clock: using sched offset of 3601592557 cycles Oct 16 15:23:50 volumiovm kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 16 15:23:50 volumiovm kernel: tsc: Detected 3892.668 MHz processor Oct 16 15:23:50 volumiovm kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 16 15:23:50 volumiovm kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 16 15:23:50 volumiovm kernel: last_pfn = 0x3fff0 max_arch_pfn = 0x400000000 Oct 16 15:23:50 volumiovm kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 35), built from 16 variable MTRRs Oct 16 15:23:50 volumiovm kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 16 15:23:50 volumiovm kernel: found SMP MP-table at [mem 0x0009fff0-0x0009ffff] Oct 16 15:23:50 volumiovm kernel: RAMDISK: [mem 0x3bd6d000-0x3ffeffff] Oct 16 15:23:50 volumiovm kernel: ACPI: Early table checksum verification disabled Oct 16 15:23:50 volumiovm kernel: ACPI: RSDP 0x00000000000E0000 000024 (v02 VBOX ) Oct 16 15:23:50 volumiovm kernel: ACPI: XSDT 0x000000003FFF0030 00003C (v01 VBOX VBOXXSDT 00000001 ASL 00000061) Oct 16 15:23:50 volumiovm kernel: ACPI: FACP 0x000000003FFF00F0 0000F4 (v04 VBOX VBOXFACP 00000001 ASL 00000061) Oct 16 15:23:50 volumiovm kernel: ACPI: DSDT 0x000000003FFF0610 002353 (v02 VBOX VBOXBIOS 00000002 INTL 20230628) Oct 16 15:23:50 volumiovm kernel: ACPI: FACS 0x000000003FFF0200 000040 Oct 16 15:23:50 volumiovm kernel: ACPI: FACS 0x000000003FFF0200 000040 Oct 16 15:23:50 volumiovm kernel: ACPI: APIC 0x000000003FFF0240 000054 (v02 VBOX VBOXAPIC 00000001 ASL 00000061) Oct 16 15:23:50 volumiovm kernel: ACPI: SSDT 0x000000003FFF02A0 00036C (v01 VBOX VBOXCPUT 00000002 INTL 20230628) Oct 16 15:23:50 volumiovm kernel: ACPI: Reserving FACP table memory at [mem 0x3fff00f0-0x3fff01e3] Oct 16 15:23:50 volumiovm kernel: ACPI: Reserving DSDT table memory at [mem 0x3fff0610-0x3fff2962] Oct 16 15:23:50 volumiovm kernel: ACPI: Reserving FACS table memory at [mem 0x3fff0200-0x3fff023f] Oct 16 15:23:50 volumiovm kernel: ACPI: Reserving FACS table memory at [mem 0x3fff0200-0x3fff023f] Oct 16 15:23:50 volumiovm kernel: ACPI: Reserving APIC table memory at [mem 0x3fff0240-0x3fff0293] Oct 16 15:23:50 volumiovm kernel: ACPI: Reserving SSDT table memory at [mem 0x3fff02a0-0x3fff060b] Oct 16 15:23:50 volumiovm kernel: Zone ranges: Oct 16 15:23:50 volumiovm kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 16 15:23:50 volumiovm kernel: DMA32 [mem 0x0000000001000000-0x000000003ffeffff] Oct 16 15:23:50 volumiovm kernel: Normal empty Oct 16 15:23:50 volumiovm kernel: Movable zone start for each node Oct 16 15:23:50 volumiovm kernel: Early memory node ranges Oct 16 15:23:50 volumiovm kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Oct 16 15:23:50 volumiovm kernel: node 0: [mem 0x0000000000100000-0x000000003ffeffff] Oct 16 15:23:50 volumiovm kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffeffff] Oct 16 15:23:50 volumiovm kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 16 15:23:50 volumiovm kernel: On node 0, zone DMA: 97 pages in unavailable ranges Oct 16 15:23:50 volumiovm kernel: On node 0, zone DMA32: 16 pages in unavailable ranges Oct 16 15:23:50 volumiovm kernel: ACPI: PM-Timer IO Port: 0x4008 Oct 16 15:23:50 volumiovm kernel: IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23 Oct 16 15:23:50 volumiovm kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Oct 16 15:23:50 volumiovm kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level) Oct 16 15:23:50 volumiovm kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 16 15:23:50 volumiovm kernel: smpboot: Allowing 1 CPUs, 0 hotplug CPUs Oct 16 15:23:50 volumiovm kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Oct 16 15:23:50 volumiovm kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] Oct 16 15:23:50 volumiovm kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] Oct 16 15:23:50 volumiovm kernel: PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] Oct 16 15:23:50 volumiovm kernel: [mem 0x40000000-0xfebfffff] available for PCI devices Oct 16 15:23:50 volumiovm kernel: Booting paravirtualized kernel on KVM Oct 16 15:23:50 volumiovm kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Oct 16 15:23:50 volumiovm kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 Oct 16 15:23:50 volumiovm kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u2097152 Oct 16 15:23:50 volumiovm kernel: pcpu-alloc: s191400 r8192 d29784 u2097152 alloc=1*2097152 Oct 16 15:23:50 volumiovm kernel: pcpu-alloc: [0] 0 Oct 16 15:23:50 volumiovm kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=e0f53ba4-066a-4701-a5d4-ead7076192e5 bootpart=UUID=4752-A5A7 datapart=UUID=82a1940a-f5a8-4717-ad17-0730260e88c8 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 Oct 16 15:23:50 volumiovm kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=e0f53ba4-066a-4701-a5d4-ead7076192e5 bootpart=UUID=4752-A5A7 datapart=UUID=82a1940a-f5a8-4717-ad17-0730260e88c8 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Oct 16 15:23:50 volumiovm kernel: random: crng init done Oct 16 15:23:50 volumiovm kernel: Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 16 15:23:50 volumiovm kernel: Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 16 15:23:50 volumiovm kernel: Built 1 zonelists, mobility grouping on. Total pages: 257776 Oct 16 15:23:50 volumiovm kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off Oct 16 15:23:50 volumiovm kernel: Memory: 934268K/1048120K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 113596K reserved, 0K cma-reserved) Oct 16 15:23:50 volumiovm kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 Oct 16 15:23:50 volumiovm kernel: ftrace: allocating 40433 entries in 158 pages Oct 16 15:23:50 volumiovm kernel: ftrace: allocated 158 pages with 5 groups Oct 16 15:23:50 volumiovm kernel: Dynamic Preempt: voluntary Oct 16 15:23:50 volumiovm kernel: rcu: Preemptible hierarchical RCU implementation. Oct 16 15:23:50 volumiovm kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1. Oct 16 15:23:50 volumiovm kernel: Trampoline variant of Tasks RCU enabled. Oct 16 15:23:50 volumiovm kernel: Rude variant of Tasks RCU enabled. Oct 16 15:23:50 volumiovm kernel: Tracing variant of Tasks RCU enabled. Oct 16 15:23:50 volumiovm kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Oct 16 15:23:50 volumiovm kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 Oct 16 15:23:50 volumiovm kernel: NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16 Oct 16 15:23:50 volumiovm kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 16 15:23:50 volumiovm kernel: Console: colour VGA+ 80x25 Oct 16 15:23:50 volumiovm kernel: printk: console [tty0] enabled Oct 16 15:23:50 volumiovm kernel: ACPI: Core revision 20230628 Oct 16 15:23:50 volumiovm kernel: APIC: Switch to symmetric I/O mode setup Oct 16 15:23:50 volumiovm kernel: x2apic enabled Oct 16 15:23:50 volumiovm kernel: APIC: Switched APIC routing to: physical x2apic Oct 16 15:23:50 volumiovm kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Oct 16 15:23:50 volumiovm kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x70389a1c830, max_idle_ns: 881590702409 ns Oct 16 15:23:50 volumiovm kernel: Calibrating delay loop (skipped) preset value.. 7785.33 BogoMIPS (lpj=15570672) Oct 16 15:23:50 volumiovm kernel: CPU0: Hyper-Threading is disabled Oct 16 15:23:50 volumiovm kernel: process: using mwait in idle threads Oct 16 15:23:50 volumiovm kernel: Last level iTLB entries: 4KB 512, 2MB 512, 4MB 256 Oct 16 15:23:50 volumiovm kernel: Last level dTLB entries: 4KB 2048, 2MB 2048, 4MB 1024, 1GB 0 Oct 16 15:23:50 volumiovm kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 16 15:23:50 volumiovm kernel: Spectre V2 : Mitigation: Retpolines Oct 16 15:23:50 volumiovm kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Oct 16 15:23:50 volumiovm kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Oct 16 15:23:50 volumiovm kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Oct 16 15:23:50 volumiovm kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Oct 16 15:23:50 volumiovm kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Oct 16 15:23:50 volumiovm kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 16 15:23:50 volumiovm kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 16 15:23:50 volumiovm kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 16 15:23:50 volumiovm kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 16 15:23:50 volumiovm kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 16 15:23:50 volumiovm kernel: Freeing SMP alternatives memory: 32K Oct 16 15:23:50 volumiovm kernel: pid_max: default: 32768 minimum: 301 Oct 16 15:23:50 volumiovm kernel: LSM: initializing lsm=capability,yama,integrity Oct 16 15:23:50 volumiovm kernel: Yama: becoming mindful. Oct 16 15:23:50 volumiovm kernel: Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) Oct 16 15:23:50 volumiovm kernel: Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) Oct 16 15:23:50 volumiovm kernel: smpboot: CPU0: AMD Ryzen 5 5600G with Radeon Graphics (family: 0x19, model: 0x50, stepping: 0x0) Oct 16 15:23:50 volumiovm kernel: RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1. Oct 16 15:23:50 volumiovm kernel: RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1. Oct 16 15:23:50 volumiovm kernel: RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1. Oct 16 15:23:50 volumiovm kernel: Performance Events: PMU not available due to virtualization, using software events only. Oct 16 15:23:50 volumiovm kernel: signal: max sigframe size: 1776 Oct 16 15:23:50 volumiovm kernel: rcu: Hierarchical SRCU implementation. Oct 16 15:23:50 volumiovm kernel: rcu: Max phase no-delay instances is 1000. Oct 16 15:23:50 volumiovm kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 16 15:23:50 volumiovm kernel: smp: Bringing up secondary CPUs ... Oct 16 15:23:50 volumiovm kernel: smp: Brought up 1 node, 1 CPU Oct 16 15:23:50 volumiovm kernel: smpboot: Max logical packages: 1 Oct 16 15:23:50 volumiovm kernel: smpboot: Total of 1 processors activated (7785.33 BogoMIPS) Oct 16 15:23:50 volumiovm kernel: devtmpfs: initialized Oct 16 15:23:50 volumiovm kernel: x86/mm: Memory block size: 128MB Oct 16 15:23:50 volumiovm kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Oct 16 15:23:50 volumiovm kernel: futex hash table entries: 256 (order: 2, 16384 bytes, linear) Oct 16 15:23:50 volumiovm kernel: pinctrl core: initialized pinctrl subsystem Oct 16 15:23:50 volumiovm kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 16 15:23:50 volumiovm kernel: audit: initializing netlink subsys (disabled) Oct 16 15:23:50 volumiovm kernel: audit: type=2000 audit(1729088631.361:1): state=initialized audit_enabled=0 res=1 Oct 16 15:23:50 volumiovm kernel: thermal_sys: Registered thermal governor 'fair_share' Oct 16 15:23:50 volumiovm kernel: thermal_sys: Registered thermal governor 'bang_bang' Oct 16 15:23:50 volumiovm kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 16 15:23:50 volumiovm kernel: thermal_sys: Registered thermal governor 'user_space' Oct 16 15:23:50 volumiovm kernel: EISA bus registered Oct 16 15:23:50 volumiovm kernel: cpuidle: using governor ladder Oct 16 15:23:50 volumiovm kernel: cpuidle: using governor menu Oct 16 15:23:50 volumiovm kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 16 15:23:50 volumiovm kernel: PCI: Using configuration type 1 for base access Oct 16 15:23:50 volumiovm kernel: PCI: Using configuration type 1 for extended access Oct 16 15:23:50 volumiovm kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 16 15:23:50 volumiovm kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 16 15:23:50 volumiovm kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 16 15:23:50 volumiovm kernel: ACPI: Added _OSI(Module Device) Oct 16 15:23:50 volumiovm kernel: ACPI: Added _OSI(Processor Device) Oct 16 15:23:50 volumiovm kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 16 15:23:50 volumiovm kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 16 15:23:50 volumiovm kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded Oct 16 15:23:50 volumiovm kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 16 15:23:50 volumiovm kernel: ACPI: Interpreter enabled Oct 16 15:23:50 volumiovm kernel: ACPI: PM: (supports S0 S5) Oct 16 15:23:50 volumiovm kernel: ACPI: Using IOAPIC for interrupt routing Oct 16 15:23:50 volumiovm kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 16 15:23:50 volumiovm kernel: PCI: Using E820 reservations for host bridge windows Oct 16 15:23:50 volumiovm kernel: ACPI: Enabled 2 GPEs in block 00 to 07 Oct 16 15:23:50 volumiovm kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 16 15:23:50 volumiovm kernel: acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 16 15:23:50 volumiovm kernel: acpi PNP0A03:00: _OSC: platform does not support [PCIeCapability LTR] Oct 16 15:23:50 volumiovm kernel: acpi PNP0A03:00: _OSC: not requesting control; platform does not support [PCIeCapability] Oct 16 15:23:50 volumiovm kernel: acpi PNP0A03:00: _OSC: OS requested [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR] Oct 16 15:23:50 volumiovm kernel: acpi PNP0A03:00: _OSC: platform willing to grant [PCIeHotplug SHPCHotplug PME AER] Oct 16 15:23:50 volumiovm kernel: acpi PNP0A03:00: _OSC: platform retains control of PCIe features (AE_SUPPORT) Oct 16 15:23:50 volumiovm kernel: PCI host bridge to bus 0000:00 Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: root bus resource [mem 0x40000000-0xfdffffff window] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: [15ad:0405] type 00 class 0x030000 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: reg 0x10: [io 0xd000-0xd00f] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: reg 0x14: [mem 0xe0000000-0xe0ffffff pref] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: reg 0x18: [mem 0xf0000000-0xf01fffff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:03.0: reg 0x10: [mem 0xf0200000-0xf021ffff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:03.0: reg 0x18: [io 0xd010-0xd017] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:04.0: reg 0x10: [io 0xd020-0xd03f] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:04.0: reg 0x14: [mem 0xf0400000-0xf07fffff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:04.0: reg 0x18: [mem 0xf0800000-0xf0803fff pref] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:05.0: [8086:2415] type 00 class 0x040100 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:05.0: reg 0x10: [io 0xd100-0xd1ff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:05.0: reg 0x14: [io 0xd200-0xd23f] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:06.0: [106b:003f] type 00 class 0x0c0310 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:06.0: reg 0x10: [mem 0xf0804000-0xf0804fff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:07.0: [8086:7113] type 00 class 0x068000 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:07.0: quirk: [io 0x4000-0x403f] claimed by PIIX4 ACPI Oct 16 15:23:50 volumiovm kernel: pci 0000:00:07.0: quirk: [io 0x4100-0x410f] claimed by PIIX4 SMB Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0b.0: [8086:265c] type 00 class 0x0c0320 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0b.0: reg 0x10: [mem 0xf0805000-0xf0805fff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0d.0: [8086:2829] type 00 class 0x010601 Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0d.0: reg 0x10: [io 0xd240-0xd247] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0d.0: reg 0x14: [io 0xd248-0xd24b] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0d.0: reg 0x18: [io 0xd250-0xd257] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0d.0: reg 0x1c: [io 0xd258-0xd25b] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0d.0: reg 0x20: [io 0xd260-0xd26f] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:0d.0: reg 0x24: [mem 0xf0806000-0xf0807fff] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: on NUMA node 0 Oct 16 15:23:50 volumiovm kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 11 Oct 16 15:23:50 volumiovm kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 16 15:23:50 volumiovm kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 9 Oct 16 15:23:50 volumiovm kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 16 15:23:50 volumiovm kernel: iommu: Default domain type: Translated Oct 16 15:23:50 volumiovm kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 16 15:23:50 volumiovm kernel: SCSI subsystem initialized Oct 16 15:23:50 volumiovm kernel: libata version 3.00 loaded. Oct 16 15:23:50 volumiovm kernel: ACPI: bus type USB registered Oct 16 15:23:50 volumiovm kernel: usbcore: registered new interface driver usbfs Oct 16 15:23:50 volumiovm kernel: usbcore: registered new interface driver hub Oct 16 15:23:50 volumiovm kernel: usbcore: registered new device driver usb Oct 16 15:23:50 volumiovm kernel: PCI: Using ACPI for IRQ routing Oct 16 15:23:50 volumiovm kernel: PCI: pci_cache_line_size set to 64 bytes Oct 16 15:23:50 volumiovm kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Oct 16 15:23:50 volumiovm kernel: e820: reserve RAM buffer [mem 0x3fff0000-0x3fffffff] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: vgaarb: bridge control possible Oct 16 15:23:50 volumiovm kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 16 15:23:50 volumiovm kernel: vgaarb: loaded Oct 16 15:23:50 volumiovm kernel: clocksource: Switched to clocksource kvm-clock Oct 16 15:23:50 volumiovm kernel: VFS: Disk quotas dquot_6.6.0 Oct 16 15:23:50 volumiovm kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 16 15:23:50 volumiovm kernel: pnp: PnP ACPI init Oct 16 15:23:50 volumiovm kernel: pnp: PnP ACPI: found 2 devices Oct 16 15:23:50 volumiovm kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 16 15:23:50 volumiovm kernel: NET: Registered PF_INET protocol family Oct 16 15:23:50 volumiovm kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 16 15:23:50 volumiovm kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) Oct 16 15:23:50 volumiovm kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 16 15:23:50 volumiovm kernel: TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 16 15:23:50 volumiovm kernel: TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) Oct 16 15:23:50 volumiovm kernel: TCP: Hash tables configured (established 8192 bind 8192) Oct 16 15:23:50 volumiovm kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Oct 16 15:23:50 volumiovm kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Oct 16 15:23:50 volumiovm kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Oct 16 15:23:50 volumiovm kernel: pci_bus 0000:00: resource 7 [mem 0x40000000-0xfdffffff window] Oct 16 15:23:50 volumiovm kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Oct 16 15:23:50 volumiovm kernel: PCI: CLS 0 bytes, default 64 Oct 16 15:23:50 volumiovm kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x70389a1c830, max_idle_ns: 881590702409 ns Oct 16 15:23:50 volumiovm kernel: clocksource: Switched to clocksource tsc Oct 16 15:23:50 volumiovm kernel: platform rtc_cmos: registered platform RTC device (no PNP device found) Oct 16 15:23:50 volumiovm kernel: Initialise system trusted keyrings Oct 16 15:23:50 volumiovm kernel: workingset: timestamp_bits=46 max_order=18 bucket_order=0 Oct 16 15:23:50 volumiovm kernel: zbud: loaded Oct 16 15:23:50 volumiovm kernel: Key type asymmetric registered Oct 16 15:23:50 volumiovm kernel: Asymmetric key parser 'x509' registered Oct 16 15:23:50 volumiovm kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Oct 16 15:23:50 volumiovm kernel: io scheduler mq-deadline registered Oct 16 15:23:50 volumiovm kernel: io scheduler kyber registered Oct 16 15:23:50 volumiovm kernel: Trying to unpack rootfs image as initramfs... Oct 16 15:23:50 volumiovm kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Oct 16 15:23:50 volumiovm kernel: ACPI: AC: AC Adapter [AC] (on-line) Oct 16 15:23:50 volumiovm kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Oct 16 15:23:50 volumiovm kernel: ACPI: button: Power Button [PWRF] Oct 16 15:23:50 volumiovm kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1 Oct 16 15:23:50 volumiovm kernel: ACPI: button: Sleep Button [SLPF] Oct 16 15:23:50 volumiovm kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Oct 16 15:23:50 volumiovm kernel: Linux agpgart interface v0.103 Oct 16 15:23:50 volumiovm kernel: ohci-pci 0000:00:06.0: OHCI PCI host controller Oct 16 15:23:50 volumiovm kernel: ohci-pci 0000:00:06.0: new USB bus registered, assigned bus number 1 Oct 16 15:23:50 volumiovm kernel: ohci-pci 0000:00:06.0: irq 22, io mem 0xf0804000 Oct 16 15:23:50 volumiovm kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06 Oct 16 15:23:50 volumiovm kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 16 15:23:50 volumiovm kernel: usb usb1: Product: OHCI PCI host controller Oct 16 15:23:50 volumiovm kernel: usb usb1: Manufacturer: Linux 6.6.32-volumio ohci_hcd Oct 16 15:23:50 volumiovm kernel: usb usb1: SerialNumber: 0000:00:06.0 Oct 16 15:23:50 volumiovm kernel: hub 1-0:1.0: USB hub found Oct 16 15:23:50 volumiovm kernel: hub 1-0:1.0: 12 ports detected Oct 16 15:23:50 volumiovm kernel: i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12 Oct 16 15:23:50 volumiovm kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Oct 16 15:23:50 volumiovm kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Oct 16 15:23:50 volumiovm kernel: ehci-pci 0000:00:0b.0: EHCI Host Controller Oct 16 15:23:50 volumiovm kernel: ehci-pci 0000:00:0b.0: new USB bus registered, assigned bus number 2 Oct 16 15:23:50 volumiovm kernel: ehci-pci 0000:00:0b.0: irq 19, io mem 0xf0805000 Oct 16 15:23:50 volumiovm kernel: mousedev: PS/2 mouse device common for all mice Oct 16 15:23:50 volumiovm kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 Oct 16 15:23:50 volumiovm kernel: rtc_cmos rtc_cmos: registered as rtc0 Oct 16 15:23:50 volumiovm kernel: rtc_cmos rtc_cmos: setting system clock to 2024-10-16T14:23:45 UTC (1729088625) Oct 16 15:23:50 volumiovm kernel: rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram Oct 16 15:23:50 volumiovm kernel: i2c_dev: i2c /dev entries driver Oct 16 15:23:50 volumiovm kernel: platform eisa.0: Probing EISA bus 0 Oct 16 15:23:50 volumiovm kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Oct 16 15:23:50 volumiovm kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 16 15:23:50 volumiovm kernel: NET: Registered PF_INET6 protocol family Oct 16 15:23:50 volumiovm kernel: Segment Routing with IPv6 Oct 16 15:23:50 volumiovm kernel: In-situ OAM (IOAM) with IPv6 Oct 16 15:23:50 volumiovm kernel: mip6: Mobile IPv6 Oct 16 15:23:50 volumiovm kernel: NET: Registered PF_PACKET protocol family Oct 16 15:23:50 volumiovm kernel: mpls_gso: MPLS GSO support Oct 16 15:23:50 volumiovm kernel: IPI shorthand broadcast: enabled Oct 16 15:23:50 volumiovm kernel: sched_clock: Marking stable (256019745, 8571413)->(267111024, -2519866) Oct 16 15:23:50 volumiovm kernel: Loading compiled-in X.509 certificates Oct 16 15:23:50 volumiovm kernel: ehci-pci 0000:00:0b.0: USB 2.0 started, EHCI 1.00 Oct 16 15:23:50 volumiovm kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Oct 16 15:23:50 volumiovm kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 16 15:23:50 volumiovm kernel: usb usb2: Product: EHCI Host Controller Oct 16 15:23:50 volumiovm kernel: usb usb2: Manufacturer: Linux 6.6.32-volumio ehci_hcd Oct 16 15:23:50 volumiovm kernel: usb usb2: SerialNumber: 0000:00:0b.0 Oct 16 15:23:50 volumiovm kernel: hub 2-0:1.0: USB hub found Oct 16 15:23:50 volumiovm kernel: hub 2-0:1.0: 12 ports detected Oct 16 15:23:50 volumiovm kernel: clk: Disabling unused clocks Oct 16 15:23:50 volumiovm kernel: usb 1-1: new full-speed USB device number 2 using ohci-pci Oct 16 15:23:50 volumiovm kernel: Freeing initrd memory: 68108K Oct 16 15:23:50 volumiovm kernel: Freeing unused kernel image (initmem) memory: 2988K Oct 16 15:23:50 volumiovm kernel: Write protecting the kernel read-only data: 20480k Oct 16 15:23:50 volumiovm kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K Oct 16 15:23:50 volumiovm kernel: Run /init as init process Oct 16 15:23:50 volumiovm kernel: with arguments: Oct 16 15:23:50 volumiovm kernel: /init Oct 16 15:23:50 volumiovm kernel: splash Oct 16 15:23:50 volumiovm kernel: with environment: Oct 16 15:23:50 volumiovm kernel: HOME=/ Oct 16 15:23:50 volumiovm kernel: TERM=linux Oct 16 15:23:50 volumiovm kernel: BOOT_IMAGE=vmlinuz Oct 16 15:23:50 volumiovm kernel: biosdevname=0 Oct 16 15:23:50 volumiovm kernel: imgpart=UUID=e0f53ba4-066a-4701-a5d4-ead7076192e5 Oct 16 15:23:50 volumiovm kernel: bootpart=UUID=4752-A5A7 Oct 16 15:23:50 volumiovm kernel: datapart=UUID=82a1940a-f5a8-4717-ad17-0730260e88c8 Oct 16 15:23:50 volumiovm kernel: hwdevice=x86 Oct 16 15:23:50 volumiovm kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Oct 16 15:23:50 volumiovm kernel: imgfile=/volumio_current.sqsh Oct 16 15:23:50 volumiovm kernel: use_kmsg=no Oct 16 15:23:50 volumiovm kernel: usb 1-1: New USB device found, idVendor=80ee, idProduct=0021, bcdDevice= 1.00 Oct 16 15:23:50 volumiovm kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 16 15:23:50 volumiovm kernel: usb 1-1: Product: USB Tablet Oct 16 15:23:50 volumiovm kernel: usb 1-1: Manufacturer: VirtualBox Oct 16 15:23:50 volumiovm kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Oct 16 15:23:50 volumiovm kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input4 Oct 16 15:23:50 volumiovm kernel: e1000: Intel(R) PRO/1000 Network Driver Oct 16 15:23:50 volumiovm kernel: e1000: Copyright (c) 1999-2006 Intel Corporation. Oct 16 15:23:50 volumiovm kernel: piix4_smbus 0000:00:07.0: SMBus Host Controller at 0x4100, revision 0 Oct 16 15:23:50 volumiovm kernel: ahci 0000:00:0d.0: version 3.0 Oct 16 15:23:50 volumiovm kernel: ahci 0000:00:0d.0: SSS flag set, parallel bus scan disabled Oct 16 15:23:50 volumiovm kernel: ahci 0000:00:0d.0: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode Oct 16 15:23:50 volumiovm kernel: ahci 0000:00:0d.0: flags: 64bit ncq stag only ccc Oct 16 15:23:50 volumiovm kernel: scsi host0: ahci Oct 16 15:23:50 volumiovm kernel: ata1: SATA max UDMA/133 abar m8192@0xf0806000 port 0xf0806100 irq 21 Oct 16 15:23:50 volumiovm kernel: hid: raw HID events driver (C) Jiri Kosina Oct 16 15:23:50 volumiovm kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 Oct 16 15:23:50 volumiovm kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Oct 16 15:23:50 volumiovm kernel: ata1.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133 Oct 16 15:23:50 volumiovm kernel: ata1.00: 16767888 sectors, multi 128: LBA48 NCQ (depth 32) Oct 16 15:23:50 volumiovm kernel: ata1.00: configured for UDMA/133 Oct 16 15:23:50 volumiovm kernel: scsi 0:0:0:0: Direct-Access ATA VBOX HARDDISK 1.0 PQ: 0 ANSI: 5 Oct 16 15:23:50 volumiovm kernel: sd 0:0:0:0: [sda] 16767888 512-byte logical blocks: (8.59 GB/8.00 GiB) Oct 16 15:23:50 volumiovm kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 16 15:23:50 volumiovm kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 Oct 16 15:23:50 volumiovm kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Oct 16 15:23:50 volumiovm kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Oct 16 15:23:50 volumiovm kernel: sda: sda1 sda2 sda3 Oct 16 15:23:50 volumiovm kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 16 15:23:50 volumiovm kernel: usbcore: registered new interface driver usbhid Oct 16 15:23:50 volumiovm kernel: usbhid: USB HID core driver Oct 16 15:23:50 volumiovm kernel: input: VirtualBox USB Tablet as /devices/pci0000:00/0000:00:06.0/usb1/1-1/1-1:1.0/0003:80EE:0021.0001/input/input6 Oct 16 15:23:50 volumiovm kernel: hid-generic 0003:80EE:0021.0001: input,hidraw0: USB HID v1.10 Mouse [VirtualBox USB Tablet] on usb-0000:00:06.0-1/input0 Oct 16 15:23:50 volumiovm kernel: e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 08:00:27:c8:a7:7d Oct 16 15:23:50 volumiovm kernel: e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection Oct 16 15:23:50 volumiovm kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 16 15:23:50 volumiovm kernel: sdhci: Secure Digital Host Controller Interface driver Oct 16 15:23:50 volumiovm kernel: sdhci: Copyright(c) Pierre Ossman Oct 16 15:23:50 volumiovm kernel: fuse: init (API version 7.39) Oct 16 15:23:50 volumiovm kernel: usbcore: registered new interface driver usb-storage Oct 16 15:23:50 volumiovm kernel: usbcore: registered new interface driver uas Oct 16 15:23:50 volumiovm kernel: ACPI: bus type drm_connector registered Oct 16 15:23:50 volumiovm kernel: [drm] radeon kernel modesetting enabled. Oct 16 15:23:50 volumiovm kernel: scsi host1: pata_legacy Oct 16 15:23:50 volumiovm kernel: ata2: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 Oct 16 15:23:50 volumiovm kernel: scsi host1: pata_legacy Oct 16 15:23:50 volumiovm kernel: ata3: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 Oct 16 15:23:50 volumiovm kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 16 15:23:50 volumiovm kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 16 15:23:50 volumiovm kernel: EXT4-fs (sda2): recovery complete Oct 16 15:23:50 volumiovm kernel: EXT4-fs (sda2): mounted filesystem e0f53ba4-066a-4701-a5d4-ead7076192e5 r/w with ordered data mode. Quota mode: none. Oct 16 15:23:50 volumiovm kernel: loop: module loaded Oct 16 15:23:50 volumiovm kernel: block device autoloading is deprecated and will be removed. Oct 16 15:23:50 volumiovm kernel: loop0: detected capacity change from 0 to 1725048 Oct 16 15:23:50 volumiovm kernel: EXT4-fs (sda3): 2 orphan inodes deleted Oct 16 15:23:50 volumiovm kernel: EXT4-fs (sda3): recovery complete Oct 16 15:23:50 volumiovm kernel: EXT4-fs (sda3): mounted filesystem 82a1940a-f5a8-4717-ad17-0730260e88c8 r/w with ordered data mode. Quota mode: none. Oct 16 15:23:50 volumiovm kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Oct 16 15:23:50 volumiovm systemd[1]: Inserted module 'autofs4' Oct 16 15:23:50 volumiovm 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) Oct 16 15:23:50 volumiovm systemd[1]: Detected virtualization oracle. Oct 16 15:23:50 volumiovm systemd[1]: Detected architecture x86-64. Oct 16 15:23:50 volumiovm systemd[1]: Set hostname to . Oct 16 15:23:50 volumiovm 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. Oct 16 15:23:50 volumiovm systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Oct 16 15:23:50 volumiovm 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. Oct 16 15:23:50 volumiovm systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 16 15:23:50 volumiovm systemd[1]: Listening on Journal Socket (/dev/log). Oct 16 15:23:50 volumiovm systemd[1]: Listening on udev Kernel Socket. Oct 16 15:23:50 volumiovm systemd-journald[405]: Journal started Oct 16 15:23:50 volumiovm systemd-journald[405]: Runtime journal (/run/log/journal/0e6b56adafe35577e8d7f03062cc3453) is 3.7M, max 30.0M, 26.2M free. Oct 16 15:23:50 volumiovm fake-hwclock[395]: Current system time: 2024-10-16 14:23:50 Oct 16 15:23:50 volumiovm fake-hwclock[395]: fake-hwclock saved clock information is in the past: 2024-10-16 14:22:34 Oct 16 15:23:50 volumiovm fake-hwclock[395]: To set system time to this saved clock anyway, use "force" Oct 16 15:23:50 volumiovm systemd[1]: Mounted FUSE Control File System. Oct 16 15:23:50 volumiovm systemd[1]: Started Create System Users. Oct 16 15:23:50 volumiovm systemd[1]: Starting Create Static Device Nodes in /dev... Oct 16 15:23:50 volumiovm systemd[1]: dynamicswap.service: Succeeded. Oct 16 15:23:50 volumiovm systemd[1]: Mounted RPC Pipe File System. Oct 16 15:23:50 volumiovm systemd[1]: Started Apply Kernel Variables. Oct 16 15:23:50 volumiovm kernel: RPC: Registered named UNIX socket transport module. Oct 16 15:23:50 volumiovm kernel: RPC: Registered udp transport module. Oct 16 15:23:50 volumiovm kernel: RPC: Registered tcp transport module. Oct 16 15:23:50 volumiovm kernel: RPC: Registered tcp-with-tls transport module. Oct 16 15:23:50 volumiovm kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 16 15:23:50 volumiovm systemd[1]: Started Create Static Device Nodes in /dev. Oct 16 15:23:50 volumiovm systemd[1]: Reached target Local File Systems (Pre). Oct 16 15:23:50 volumiovm systemd[1]: Mounting /var/spool/cups... Oct 16 15:23:50 volumiovm systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 16 15:23:50 volumiovm systemd[1]: Mounting /var/log... Oct 16 15:23:50 volumiovm systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 16 15:23:50 volumiovm systemd[1]: Mounting /tmp... Oct 16 15:23:50 volumiovm systemd[1]: Starting udev Kernel Device Manager... Oct 16 15:23:50 volumiovm systemd[1]: Mounted /var/spool/cups. Oct 16 15:23:50 volumiovm systemd[1]: Mounted /var/log. Oct 16 15:23:50 volumiovm systemd[1]: Mounted /tmp. Oct 16 15:23:50 volumiovm systemd[1]: Starting Flush Journal to Persistent Storage... Oct 16 15:23:50 volumiovm systemd[1]: Mounting /var/spool/cups/tmp... Oct 16 15:23:50 volumiovm systemd[1]: Started udev Coldplug all Devices. Oct 16 15:23:50 volumiovm systemd[1]: Mounted /var/spool/cups/tmp. Oct 16 15:23:50 volumiovm systemd-udevd[431]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 16 15:23:50 volumiovm systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 16 15:23:50 volumiovm systemd[1]: Started udev Kernel Device Manager. Oct 16 15:23:50 volumiovm systemd-journald[405]: Runtime journal (/run/log/journal/0e6b56adafe35577e8d7f03062cc3453) is 3.7M, max 30.0M, 26.2M free. Oct 16 15:23:50 volumiovm systemd[1]: Starting Show Plymouth Boot Screen... Oct 16 15:23:50 volumiovm systemd[1]: Started Flush Journal to Persistent Storage. Oct 16 15:23:50 volumiovm systemd[1]: plymouth-start.service: Succeeded. Oct 16 15:23:50 volumiovm systemd[1]: Started Show Plymouth Boot Screen. Oct 16 15:23:50 volumiovm systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 16 15:23:50 volumiovm systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 16 15:23:50 volumiovm systemd[1]: Reached target Local Encrypted Volumes. Oct 16 15:23:50 volumiovm systemd[1]: Reached target Paths. Oct 16 15:23:50 volumiovm systemd-udevd[438]: Using default interface naming scheme 'v240'. Oct 16 15:23:50 volumiovm systemd-udevd[438]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 16 15:23:50 volumiovm kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 16 15:23:50 volumiovm systemd-udevd[445]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd-udevd[443]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd[1]: Found device 82540EM Gigabit Ethernet Controller (PRO/1000 MT Desktop Adapter). Oct 16 15:23:50 volumiovm systemd-udevd[441]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd-udevd[443]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd-udevd[438]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd-udevd[440]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd-udevd[436]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd[1]: Found device VBOX_HARDDISK boot. Oct 16 15:23:50 volumiovm kernel: cryptd: max_cpu_qlen set to 1000 Oct 16 15:23:50 volumiovm kernel: AVX2 version of gcm_enc/dec engaged. Oct 16 15:23:50 volumiovm kernel: AES CTR mode by8 optimization enabled Oct 16 15:23:50 volumiovm kernel: snd_intel8x0 0000:00:05.0: allow list rate for 1028:0177 is 48000 Oct 16 15:23:50 volumiovm systemd-udevd[446]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 16 15:23:50 volumiovm systemd-udevd[435]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:50 volumiovm systemd-udevd[446]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 15:23:51 volumiovm systemd[1]: Starting Show Plymouth Boot Screen... Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 16 15:23:51 volumiovm systemd[1]: Starting File System Check on /dev/disk/by-uuid/4752-A5A7... Oct 16 15:23:51 volumiovm systemd[1]: Started File System Check Daemon to report status. Oct 16 15:23:51 volumiovm systemd[1]: plymouth-start.service: Succeeded. Oct 16 15:23:51 volumiovm systemd[1]: Started Show Plymouth Boot Screen. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 16 15:23:51 volumiovm kernel: alsactl[523]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Oct 16 15:23:51 volumiovm systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 16 15:23:51 volumiovm systemd-fsck[525]: fsck.fat 4.1 (2017-01-24) Oct 16 15:23:51 volumiovm systemd-fsck[525]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Oct 16 15:23:51 volumiovm systemd-fsck[525]: Automatically removing dirty bit. Oct 16 15:23:51 volumiovm systemd-fsck[525]: Performing changes. Oct 16 15:23:51 volumiovm systemd-fsck[525]: /dev/sda1: 21 files, 19973/43727 clusters Oct 16 15:23:51 volumiovm systemd[1]: Started File System Check on /dev/disk/by-uuid/4752-A5A7. Oct 16 15:23:51 volumiovm systemd[1]: Mounting /boot... Oct 16 15:23:51 volumiovm kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 16 15:23:51 volumiovm systemd[1]: Mounted /boot. Oct 16 15:23:51 volumiovm systemd[1]: Reached target Local File Systems. Oct 16 15:23:51 volumiovm systemd[1]: Started ifup for eth0. Oct 16 15:23:51 volumiovm systemd[1]: Starting Create Volatile Files and Directories... Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 16 15:23:51 volumiovm systemd[1]: Starting Raise network interfaces... Oct 16 15:23:51 volumiovm systemd[1]: Starting Preprocess NFS configuration... Oct 16 15:23:51 volumiovm systemd[1]: Received SIGRTMIN+20 from PID 292 (plymouthd). Oct 16 15:23:51 volumiovm systemd[1]: plymouth-read-write.service: Succeeded. Oct 16 15:23:51 volumiovm systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 16 15:23:51 volumiovm systemd[1]: nfs-config.service: Succeeded. Oct 16 15:23:51 volumiovm systemd[1]: Started Preprocess NFS configuration. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Reached target NFS client services. Oct 16 15:23:51 volumiovm systemd[1]: Started Create Volatile Files and Directories. Oct 16 15:23:51 volumiovm systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 16 15:23:51 volumiovm systemd[1]: Starting RPC bind portmap service... Oct 16 15:23:51 volumiovm systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 16 15:23:51 volumiovm systemd[1]: Reached target System Initialization. Oct 16 15:23:51 volumiovm systemd[1]: Started Daily apt download activities. Oct 16 15:23:51 volumiovm kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 16 15:23:51 volumiovm systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 16 15:23:51 volumiovm systemd[1]: Started Daily apt upgrade and clean activities. Oct 16 15:23:51 volumiovm systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 16 15:23:51 volumiovm systemd[1]: Started Daily man-db regeneration. Oct 16 15:23:51 volumiovm systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 16 15:23:51 volumiovm systemd[1]: Reached target Timers. Oct 16 15:23:51 volumiovm systemd[1]: Listening on triggerhappy.socket. Oct 16 15:23:51 volumiovm systemd[1]: Reached target Sockets. Oct 16 15:23:51 volumiovm systemd[1]: Reached target Basic System. Oct 16 15:23:51 volumiovm systemd[1]: Started volumio-remote-updater.service. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Starting Wireless Services... Oct 16 15:23:51 volumiovm systemd[1]: Starting Login Service... Oct 16 15:23:51 volumiovm kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 16 15:23:51 volumiovm kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Oct 16 15:23:51 volumiovm systemd[1]: Started Volumio Iptables Module. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 16 15:23:51 volumiovm systemd[1]: Started Manage Sound Card State (restore and store). Oct 16 15:23:51 volumiovm alsactl[571]: alsactl 1.1.8 daemon started Oct 16 15:23:51 volumiovm systemd[1]: Starting Save/Restore Sound Card State... Oct 16 15:23:51 volumiovm systemd[1]: Starting DHCP Client Daemon... Oct 16 15:23:51 volumiovm systemd[1]: Started D-Bus System Message Bus. Oct 16 15:23:51 volumiovm sh[532]: command failed: No such device (-19) Oct 16 15:23:51 volumiovm ifup[537]: command failed: No such device (-19) Oct 16 15:23:51 volumiovm systemd[1]: Started Volumio Log Rotation Service. Oct 16 15:23:51 volumiovm systemd[1]: Started UPnP Renderer front-end to MPD. Oct 16 15:23:51 volumiovm systemd[1]: Starting triggerhappy global hotkey daemon... Oct 16 15:23:51 volumiovm systemd[1]: Starting WPA supplicant... Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Started RPC bind portmap service. Oct 16 15:23:51 volumiovm systemd[1]: Reached target RPC Port Mapper. Oct 16 15:23:51 volumiovm systemd-logind[565]: Watching system buttons on /dev/input/event0 (Power Button) Oct 16 15:23:51 volumiovm systemd-logind[565]: Watching system buttons on /dev/input/event1 (Sleep Button) Oct 16 15:23:51 volumiovm avahi-daemon[570]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Oct 16 15:23:51 volumiovm systemd[1]: Reached target Remote File Systems (Pre). Oct 16 15:23:51 volumiovm systemd[1]: Reached target Remote File Systems. Oct 16 15:23:51 volumiovm avahi-daemon[570]: Successfully dropped root privileges. Oct 16 15:23:51 volumiovm avahi-daemon[570]: avahi-daemon 0.7 starting up. Oct 16 15:23:51 volumiovm systemd-logind[565]: Watching system buttons on /dev/input/event2 (AT Translated Set 2 keyboard) Oct 16 15:23:51 volumiovm systemd-logind[565]: New seat seat0. Oct 16 15:23:51 volumiovm systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 16 15:23:51 volumiovm systemd[1]: Started Save/Restore Sound Card State. Oct 16 15:23:51 volumiovm thd[581]: Unable to parse trigger line: Oct 16 15:23:51 volumiovm thd[581]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 16 15:23:51 volumiovm thd[581]: Unable to parse trigger line: Oct 16 15:23:51 volumiovm thd[581]: Found socket passed from systemd Oct 16 15:23:51 volumiovm systemd[1]: Reached target Sound Card. Oct 16 15:23:51 volumiovm sh[532]: sending commands to master dhcpcd process Oct 16 15:23:51 volumiovm dhcpcd[600]: sending commands to master dhcpcd process Oct 16 15:23:51 volumiovm kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 16 15:23:51 volumiovm ifup[537]: command failed: No such device (-19) Oct 16 15:23:51 volumiovm dbus-daemon[576]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 16 15:23:51 volumiovm systemd[1]: Started Login Service. Oct 16 15:23:51 volumiovm systemd[1]: Started triggerhappy global hotkey daemon. Oct 16 15:23:51 volumiovm avahi-daemon[570]: Successfully called chroot(). Oct 16 15:23:51 volumiovm avahi-daemon[570]: Successfully dropped remaining capabilities. Oct 16 15:23:51 volumiovm avahi-daemon[570]: Loading service file /services/volumio.service. Oct 16 15:23:51 volumiovm avahi-daemon[570]: Network interface enumeration completed. Oct 16 15:23:51 volumiovm avahi-daemon[570]: Server startup complete. Host name is volumiovm.local. Local service cookie is 1312174220. Oct 16 15:23:51 volumiovm avahi-daemon[570]: Service "Volumiovm" (/services/volumio.service) successfully established. Oct 16 15:23:51 volumiovm systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 16 15:23:51 volumiovm systemd[1]: Started WPA supplicant. Oct 16 15:23:51 volumiovm wpa_supplicant[584]: Successfully initialized wpa_supplicant Oct 16 15:23:51 volumiovm dhcpcd[575]: no interfaces have a carrier Oct 16 15:23:51 volumiovm dhcpcd[575]: no interfaces have a carrier Oct 16 15:23:51 volumiovm dhcpcd[575]: forked to background, child pid 670 Oct 16 15:23:51 volumiovm dhcpcd[575]: forked to background, child pid 670 Oct 16 15:23:51 volumiovm systemd[1]: Started DHCP Client Daemon. Oct 16 15:23:51 volumiovm kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Oct 16 15:23:51 volumiovm kernel: e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Oct 16 15:23:51 volumiovm dhcpcd[670]: eth0: waiting for carrier Oct 16 15:23:51 volumiovm sh[532]: eth0=eth0 Oct 16 15:23:51 volumiovm dhcpcd[670]: control command: /sbin/dhcpcd eth0 Oct 16 15:23:51 volumiovm dhcpcd[670]: eth0: carrier acquired Oct 16 15:23:51 volumiovm dhcpcd[670]: DUID 00:04:f5:c9:52:aa:2f:fe:bf:48:8e:66:4e:8c:a0:17:83:22 Oct 16 15:23:51 volumiovm dhcpcd[670]: eth0: IAID 27:c8:a7:7d Oct 16 15:23:51 volumiovm dhcpcd[670]: eth0: adding address fe80::a00:27ff:fec8:a77d Oct 16 15:23:51 volumiovm dhcpcd[670]: ipv6_addaddr1: Permission denied Oct 16 15:23:51 volumiovm ifup[537]: command failed: No such device (-19) Oct 16 15:23:51 volumiovm systemd[1]: iptables.service: Succeeded. Oct 16 15:23:51 volumiovm volumio-remote-updater[560]: Error: No active session Oct 16 15:23:51 volumiovm volumio-remote-updater[560]: [2024-10-16 15:23:51] [info] asio async_connect error: system:111 (Connection refused) Oct 16 15:23:51 volumiovm volumio-remote-updater[560]: [2024-10-16 15:23:51] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 16 15:23:51 volumiovm volumio-remote-updater[560]: [2024-10-16 15:23:51] [error] handle_connect error: Underlying Transport Error Oct 16 15:23:51 volumiovm volumio[580]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:23:51 volumiovm loadcpufreq[590]: Loading cpufreq kernel modules...failed. Oct 16 15:23:51 volumiovm systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 16 15:23:51 volumiovm systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 16 15:23:51 volumiovm systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:23:51 volumiovm systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:23:51 volumiovm cpufrequtils[752]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Oct 16 15:23:51 volumiovm systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 16 15:23:51 volumiovm systemd[1]: Started Raise network interfaces. Oct 16 15:23:51 volumiovm systemd[1]: Reached target Network. Oct 16 15:23:51 volumiovm systemd[1]: Starting Logitech Media Server Daemon... Oct 16 15:23:51 volumiovm systemd[1]: Starting OpenBSD Secure Shell server... Oct 16 15:23:51 volumiovm systemd[1]: Starting Network Time Service... Oct 16 15:23:51 volumiovm systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 16 15:23:51 volumiovm systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 16 15:23:51 volumiovm systemd[1]: Starting Permit User Sessions... Oct 16 15:23:51 volumiovm systemd[1]: Reached target Network is Online. Oct 16 15:23:51 volumiovm systemd[1]: Starting /etc/rc.local Compatibility... Oct 16 15:23:51 volumiovm haveged[555]: haveged: ver: 1.9.1; arch: x86; vend: ; build: (gcc 8.3.0 ITV); collect: 128K Oct 16 15:23:51 volumiovm haveged[555]: haveged: cpu: (VC); data: 32K (V); inst: 32K (V); idx: 24/40; sz: 31410/52825 Oct 16 15:23:51 volumiovm haveged[555]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9986 Oct 16 15:23:51 volumiovm haveged[555]: haveged: fills: 0, generated: 0 Oct 16 15:23:51 volumiovm systemd[1]: Starting Samba NMB Daemon... Oct 16 15:23:51 volumiovm systemd[1]: Starting LSB: Brings up/down network automatically... Oct 16 15:23:51 volumiovm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 15:23:51 volumiovm systemd[1]: Started Permit User Sessions. Oct 16 15:23:51 volumiovm systemd[1]: Started /etc/rc.local Compatibility. Oct 16 15:23:51 volumiovm systemd[1]: Started Logitech Media Server Daemon. Oct 16 15:23:51 volumiovm systemd[1]: Starting Hold until boot process finishes up... Oct 16 15:23:51 volumiovm ntpd[786]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 16 15:23:51 volumiovm ntpd[786]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 16 15:23:51 volumiovm systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 16 15:23:51 volumiovm systemd[1]: Started Network Time Service. Oct 16 15:23:52 volumiovm ntpd[811]: proto: precision = 0.030 usec (-25) Oct 16 15:23:52 volumiovm dhcpcd[670]: eth0: soliciting an IPv6 router Oct 16 15:23:52 volumiovm systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 16 15:23:52 volumiovm systemd[1]: Started Hold until boot process finishes up. Oct 16 15:23:52 volumiovm systemd[1]: plymouth-quit.service: Succeeded. Oct 16 15:23:52 volumiovm systemd[1]: Started Terminate Plymouth Boot Screen. Oct 16 15:23:52 volumiovm systemd[1]: Received SIGRTMIN+21 from PID 292 (n/a). Oct 16 15:23:52 volumiovm ntpd[811]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 16 15:23:52 volumiovm ntpd[811]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 16 15:23:52 volumiovm ntpd[811]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 659 days ago Oct 16 15:23:52 volumiovm ntpd[811]: Listen and drop on 0 v6wildcard [::]:123 Oct 16 15:23:52 volumiovm ntpd[811]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 16 15:23:52 volumiovm ntpd[811]: Listen normally on 2 lo 127.0.0.1:123 Oct 16 15:23:52 volumiovm ntpd[811]: Listening on routing socket on fd #19 for interface updates Oct 16 15:23:52 volumiovm ntpd[811]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 15:23:52 volumiovm ntpd[811]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 15:23:52 volumiovm sshd[812]: Server listening on 0.0.0.0 port 22. Oct 16 15:23:52 volumiovm sshd[812]: Server listening on :: port 22. Oct 16 15:23:52 volumiovm systemd[1]: Started Getty on tty1. Oct 16 15:23:52 volumiovm systemd[1]: Reached target Login Prompts. Oct 16 15:23:52 volumiovm systemd[1]: Started OpenBSD Secure Shell server. Oct 16 15:23:52 volumiovm ifplugd(eth0)[825]: ifplugd 0.28 initializing. Oct 16 15:23:52 volumiovm ifplugd(eth0)[825]: Using interface eth0/08:00:27:C8:A7:7D with driver (version: 6.6.32-volumio) Oct 16 15:23:52 volumiovm ifplugd(eth0)[825]: Using detection mode: SIOCETHTOOL Oct 16 15:23:52 volumiovm ifplugd(eth0)[825]: Initialization complete, link beat detected. Oct 16 15:23:52 volumiovm ifplugd(eth0)[825]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 16 15:23:52 volumiovm ifplugd(eth0)[825]: client: ifup: interface eth0 already configured Oct 16 15:23:52 volumiovm ifplugd(eth0)[825]: Program executed successfully. Oct 16 15:23:52 volumiovm ifplugd[795]: Network Interface Plugging Daemon...start eth0...done. Oct 16 15:23:52 volumiovm systemd[1]: Started LSB: Brings up/down network automatically. Oct 16 15:23:52 volumiovm dhcpcd[670]: eth0: rebinding lease of 192.168.42.23 Oct 16 15:23:52 volumiovm dhcpcd[670]: eth0: probing address 192.168.42.23/24 Oct 16 15:23:52 volumiovm nmbd[801]: [2024/10/16 15:23:52.500277, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 16 15:23:52 volumiovm nmbd[801]: started asyncdns process 832 Oct 16 15:23:52 volumiovm nmbd[801]: [2024/10/16 15:23:52.500703, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 16 15:23:52 volumiovm nmbd[801]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 16 15:23:52 volumiovm nmbd[801]: [2024/10/16 15:23:52.500733, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 16 15:23:52 volumiovm nmbd[801]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 16 15:23:52 volumiovm wireless.js[564]: WIRELESS: No wireless interface, exiting Oct 16 15:23:52 volumiovm iw[837]: command failed: No such device (-19) Oct 16 15:23:52 volumiovm iwconfig[840]: Error for wireless request "Set Power Management" (8B2C) : Oct 16 15:23:52 volumiovm iwconfig[840]: SET failed on device wlan0 ; No such device. Oct 16 15:23:52 volumiovm systemd[1]: wireless.service: Succeeded. Oct 16 15:23:52 volumiovm systemd[1]: Started Wireless Services. Oct 16 15:23:52 volumiovm systemd[1]: Started Volumio Backend Module. Oct 16 15:23:52 volumiovm systemd[1]: Started Intel SST and HDA soundcard init service. Oct 16 15:23:52 volumiovm systemd[1]: Started Volumio Cpu Tweaker. Oct 16 15:23:52 volumiovm systemd[1]: Started Start Volumio Kiosk. Oct 16 15:23:52 volumiovm systemd[1]: minidlna.service: Succeeded. Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: Setting RT Priority for mpd Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: pid 35's current scheduling policy: SCHED_OTHER Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: pid 35's current scheduling priority: 0 Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: Not enough cores to set MPD affinity Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: Error setting new values. Common errors: Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: - Do you have proper administration rights? (super-user?) Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: - Is the governor you requested available and modprobed? Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: - Trying to set an invalid policy? Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: - Trying to set a specific frequency, but userspace governor is not available, Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: for example because of hardware which cannot be set to a specific frequency Oct 16 15:23:52 volumiovm volumio-cpu-tweak[845]: or because the userspace governor isn't loaded? Oct 16 15:23:52 volumiovm systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 16 15:23:52 volumiovm startx[847]: X.Org X Server 1.20.4 Oct 16 15:23:52 volumiovm startx[847]: X Protocol Version 11, Revision 0 Oct 16 15:23:52 volumiovm startx[847]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Oct 16 15:23:52 volumiovm startx[847]: Current Operating System: Linux volumiovm 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Oct 16 15:23:52 volumiovm startx[847]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=e0f53ba4-066a-4701-a5d4-ead7076192e5 bootpart=UUID=4752-A5A7 datapart=UUID=82a1940a-f5a8-4717-ad17-0730260e88c8 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 Oct 16 15:23:52 volumiovm startx[847]: Build Date: 18 December 2021 09:05:36AM Oct 16 15:23:52 volumiovm startx[847]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Oct 16 15:23:52 volumiovm startx[847]: Current version of pixman: 0.36.0 Oct 16 15:23:52 volumiovm startx[847]: Before reporting problems, check http://wiki.x.org Oct 16 15:23:52 volumiovm startx[847]: to make sure that you have the latest version. Oct 16 15:23:52 volumiovm startx[847]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 16 15:23:52 volumiovm startx[847]: (++) from command line, (!!) notice, (II) informational, Oct 16 15:23:52 volumiovm startx[847]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 16 15:23:52 volumiovm startx[847]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Oct 16 15:23:52 2024 Oct 16 15:23:52 volumiovm startx[847]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 16 15:23:53 volumiovm ntpd[811]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 16 15:23:53 volumiovm systemd[1]: soundcard-init.service: Succeeded. Oct 16 15:23:53 volumiovm ntpd[811]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 16 15:23:54 volumiovm volumio[843]: info: ------------------------------------------- Oct 16 15:23:54 volumiovm volumio[843]: info: ----- Volumio3 ---- Oct 16 15:23:54 volumiovm volumio[843]: info: ------------------------------------------- Oct 16 15:23:54 volumiovm volumio[843]: info: ----- System startup ---- Oct 16 15:23:54 volumiovm volumio[843]: info: ------------------------------------------- Oct 16 15:23:54 volumiovm volumio[843]: info: MYVOLUMIO Environment detected Oct 16 15:23:54 volumiovm volumio[843]: info: Plugin folders cleanup Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning into folder /volumio/app/plugins/ Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category audio_interface Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category miscellanea Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category music_service Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category plugins.json Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category system_controller Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category user_interface Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning into folder /data/plugins/ Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category music_service Oct 16 15:23:54 volumiovm volumio[843]: info: Scanning category user_interface Oct 16 15:23:54 volumiovm volumio[843]: info: Plugin folders cleanup completed Oct 16 15:23:54 volumiovm volumio[843]: info: ------------------------------------------- Oct 16 15:23:54 volumiovm volumio[843]: info: ----- Core plugins startup ---- Oct 16 15:23:54 volumiovm volumio[843]: info: ------------------------------------------- Oct 16 15:23:54 volumiovm volumio[843]: info: Loading plugins from folder /volumio/app/plugins/ Oct 16 15:23:54 volumiovm volumio[843]: info: Adding plugin upnp to MyMusic Plugins Oct 16 15:23:54 volumiovm volumio[843]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 16 15:23:54 volumiovm volumio[843]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 16 15:23:54 volumiovm volumio[843]: info: Loading plugins from folder /data/plugins/ Oct 16 15:23:54 volumiovm volumio[843]: info: Loading plugin "system"... Oct 16 15:23:54 volumiovm volumio[843]: info: Loading plugin "appearance"... Oct 16 15:23:54 volumiovm ntpd[811]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "network"... Oct 16 15:23:55 volumiovm volumio[843]: info: Refreshing Cached IP Addresses Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "services"... Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "alsa_controller"... Oct 16 15:23:55 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 15:23:55 volumiovm sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 15:23:55 volumiovm sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:55 volumiovm sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "wizard"... Oct 16 15:23:55 volumiovm sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:55 volumiovm sudo[986]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:55 volumiovm sudo[988]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "networkfs"... Oct 16 15:23:55 volumiovm volumio[843]: info: Starting Udev Watcher for removable devices Oct 16 15:23:55 volumiovm sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas5media,password=nas5media1234!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.225/music /mnt/NAS/nas5 Oct 16 15:23:55 volumiovm sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:55 volumiovm volumio[843]: info: Ignoring mount for partition: boot Oct 16 15:23:55 volumiovm volumio[843]: info: Ignoring mount for partition: volumio Oct 16 15:23:55 volumiovm volumio[843]: info: Ignoring mount for partition: volumio_data Oct 16 15:23:55 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "volumio_command_line_client"... Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "upnp"... Oct 16 15:23:55 volumiovm volumio[843]: info: [1729088635636] Starting Upmpd Daemon Oct 16 15:23:55 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "my_music"... Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "mpd"... Oct 16 15:23:55 volumiovm kernel: FS-Cache: Loaded Oct 16 15:23:55 volumiovm kernel: Key type dns_resolver registered Oct 16 15:23:55 volumiovm kernel: Key type cifs.spnego registered Oct 16 15:23:55 volumiovm kernel: Key type cifs.idmap registered Oct 16 15:23:55 volumiovm sudo[996]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:55 volumiovm 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. Oct 16 15:23:55 volumiovm kernel: CIFS: Attempting to mount //192.168.42.225/music Oct 16 15:23:55 volumiovm kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 16 15:23:55 volumiovm kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:54.3133] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 - x86_64-linux-gnu-thread-multi Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:54.8766] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.6897] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.6900] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.6901] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.6955] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.6959] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.6961] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.7045] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.7305] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.7310] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Oct 16 15:23:55 volumiovm squeezeboxserver[803]: [24-10-16 15:23:55.8524] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Oct 16 15:23:55 volumiovm volumio[843]: info: Loading plugin "upnp_browser"... Oct 16 15:23:55 volumiovm ntpd[811]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "alarm-clock"... Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "airplay_emulation"... Oct 16 15:23:56 volumiovm volumio[843]: info: Starting Shairport Sync Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "last_100"... Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "webradio"... Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "i2s_dacs"... Oct 16 15:23:56 volumiovm volumio[843]: info: I2S DAC not set, start Auto-detection Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "volumiodiscovery"... Oct 16 15:23:56 volumiovm volumio[843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 15:23:56 volumiovm node[843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 15:23:56 volumiovm volumio[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:23:56 volumiovm volumio[843]: *** WARNING *** For more information see Oct 16 15:23:56 volumiovm volumio[843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 15:23:56 volumiovm volumio[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:23:56 volumiovm volumio[843]: *** WARNING *** For more information see Oct 16 15:23:56 volumiovm node[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:23:56 volumiovm node[843]: *** WARNING *** For more information see Oct 16 15:23:56 volumiovm node[843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 15:23:56 volumiovm node[843]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:23:56 volumiovm node[843]: *** WARNING *** For more information see Oct 16 15:23:56 volumiovm volumio[843]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 16 15:23:56 volumiovm volumio[843]: info: Discovery: Started advertising with name: Volumiovm Oct 16 15:23:56 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "outputs"... Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "albumart"... Oct 16 15:23:56 volumiovm volumio[843]: info: Plugin example_plugin is not enabled Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "inputs"... Oct 16 15:23:56 volumiovm volumio[843]: info: Loading plugin "updater_comm"... Oct 16 15:23:56 volumiovm squeezeboxserver[803]: [24-10-16 15:23:56.6773] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 Oct 16 15:23:56 volumiovm volumio[843]: Forking 1 albumart workers Oct 16 15:23:56 volumiovm volumio-remote-updater[560]: [2024-10-16 15:23:56] [connect] Successful connection Oct 16 15:23:57 volumiovm volumio[843]: Starting albumart workers Oct 16 15:23:57 volumiovm volumio[843]: info: Plugin mpdemulation is not enabled Oct 16 15:23:57 volumiovm volumio[843]: info: Loading plugin "rest_api"... Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.1671] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (System error) Oct 16 15:23:57 volumiovm volumio[843]: info: Loading plugin "websocket"... Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.1927] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.1929] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.000 seconds. Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.1934] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.1981] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (System error) Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.1996] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2000] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2010] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2014] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Oct 16 15:23:57 volumiovm volumio[843]: info: Starting Socket.io Server version 2.3.0 Oct 16 15:23:57 volumiovm volumio[843]: info: Loading plugin "lms"... Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2658] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2703] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2713] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2728] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2827] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2843] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.085 seconds. Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2846] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2847] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.000 seconds. Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2857] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2860] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.000 seconds. Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2876] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2884] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.001 seconds. Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.2886] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Oct 16 15:23:57 volumiovm dhcpcd[670]: eth0: leased 192.168.42.23 for 172800 seconds Oct 16 15:23:57 volumiovm dhcpcd[670]: eth0: adding route to 192.168.42.0/24 Oct 16 15:23:57 volumiovm dhcpcd[670]: eth0: adding default route via 192.168.42.254 Oct 16 15:23:57 volumiovm avahi-daemon[570]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.42.23. Oct 16 15:23:57 volumiovm avahi-daemon[570]: New relevant interface eth0.IPv4 for mDNS. Oct 16 15:23:57 volumiovm avahi-daemon[570]: Registering new address record for 192.168.42.23 on eth0.IPv4. Oct 16 15:23:57 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.3788] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.090 seconds. Oct 16 15:23:57 volumiovm systemd[1]: Stopping Network Time Service... Oct 16 15:23:57 volumiovm ntpd[811]: ntpd exiting on signal 15 (Terminated) Oct 16 15:23:57 volumiovm systemd[1]: ntp.service: Succeeded. Oct 16 15:23:57 volumiovm systemd[1]: Stopped Network Time Service. Oct 16 15:23:57 volumiovm systemd[1]: Starting Network Time Service... Oct 16 15:23:57 volumiovm ntpd[1122]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 16 15:23:57 volumiovm ntpd[1122]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 16 15:23:57 volumiovm systemd[1]: Started Network Time Service. Oct 16 15:23:57 volumiovm ntpd[1130]: proto: precision = 0.030 usec (-25) Oct 16 15:23:57 volumiovm ntpd[1130]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 16 15:23:57 volumiovm ntpd[1130]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 16 15:23:57 volumiovm ntpd[1130]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 659 days ago Oct 16 15:23:57 volumiovm ntpd[1130]: Listen and drop on 0 v6wildcard [::]:123 Oct 16 15:23:57 volumiovm ntpd[1130]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 16 15:23:57 volumiovm ntpd[1130]: Listen normally on 2 lo 127.0.0.1:123 Oct 16 15:23:57 volumiovm ntpd[1130]: Listen normally on 3 eth0 192.168.42.23:123 Oct 16 15:23:57 volumiovm ntpd[1130]: Listening on routing socket on fd #20 for interface updates Oct 16 15:23:57 volumiovm ntpd[1130]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 15:23:57 volumiovm ntpd[1130]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 15:23:57 volumiovm nmbd[801]: [2024/10/16 15:23:57.506768, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 16 15:23:57 volumiovm nmbd[801]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 16 15:23:57 volumiovm systemd[1]: Started Samba NMB Daemon. Oct 16 15:23:57 volumiovm systemd[1]: Starting Samba Winbind Daemon... Oct 16 15:23:57 volumiovm volumio[843]: info: Loading plugin "minidlna"... Oct 16 15:23:57 volumiovm volumio[843]: info: Applying required configuration parameters for plugin minidlna Oct 16 15:23:57 volumiovm winbindd[1135]: [2024/10/16 15:23:57.680717, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 16 15:23:57 volumiovm winbindd[1135]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 16 15:23:57 volumiovm systemd[1]: Started Samba Winbind Daemon. Oct 16 15:23:57 volumiovm winbindd[1135]: [2024/10/16 15:23:57.688974, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 16 15:23:57 volumiovm winbindd[1135]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 16 15:23:57 volumiovm systemd[1]: Starting Samba SMB Daemon... Oct 16 15:23:57 volumiovm volumio[843]: info: Loading plugin "radio_paradise"... Oct 16 15:23:57 volumiovm winbindd[1135]: [2024/10/16 15:23:57.923913, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Oct 16 15:23:57 volumiovm winbindd[1135]: res_names->count = 3, expected 4 Oct 16 15:23:57 volumiovm systemd[1]: Started Samba SMB Daemon. Oct 16 15:23:57 volumiovm smbd[1140]: [2024/10/16 15:23:57.937667, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 16 15:23:57 volumiovm systemd[1]: Reached target Multi-User System. Oct 16 15:23:57 volumiovm smbd[1140]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 16 15:23:57 volumiovm systemd[1]: Reached target Graphical Interface. Oct 16 15:23:57 volumiovm systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 16 15:23:57 volumiovm systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 16 15:23:57 volumiovm systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 16 15:23:57 volumiovm systemd[1]: Startup finished in 4.652s (kernel) + 8.075s (userspace) = 12.727s. Oct 16 15:23:57 volumiovm volumio[843]: info: Applying required configuration parameters for plugin radio_paradise Oct 16 15:23:57 volumiovm volumio[843]: info: [1729088637974] [RadioParadise] API delay: 5 Oct 16 15:23:57 volumiovm volumio[843]: info: Loading plugin "Systeminfo"... Oct 16 15:23:58 volumiovm volumio[843]: info: Loading i18n strings for locale en Oct 16 15:23:58 volumiovm volumio[843]: Updating browse sources language Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::initPlayerControls Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:23:58 volumiovm volumio[843]: Express server listening on port 3000 Oct 16 15:23:58 volumiovm volumio[843]: [Metrics] WebUI: 4s 679.84ms Oct 16 15:23:58 volumiovm volumio[843]: info: Setting Device type: x86 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::resetVolumioState Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::getcurrentVolume Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:23:58 volumiovm volumio[843]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 16 15:23:58 volumiovm volumio[843]: info: Completed loading Core Plugins Oct 16 15:23:58 volumiovm volumio[843]: info: Preparing to generate the ALSA configuration file Oct 16 15:23:58 volumiovm volumio[843]: info: Cannot mount NAS nas5 at system boot, trial number 1 ,retrying in 5 seconds Oct 16 15:23:58 volumiovm volumio[843]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 16 15:23:58 volumiovm volumio[843]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 16 15:23:58 volumiovm volumio[843]: info: Reading ALSA contributions from plugins. Oct 16 15:23:58 volumiovm volumio-remote-updater[560]: [2024-10-16 15:23:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729088636 101 Oct 16 15:23:58 volumiovm volumio[843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::pushState Oct 16 15:23:58 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioPushState Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::updateTrackBlock Oct 16 15:23:58 volumiovm volumio[843]: info: CorePlayQueue::getTrackBlock Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 15:23:58 volumiovm ntpd[1130]: Soliciting pool server 131.111.8.60 Oct 16 15:23:58 volumiovm volumio[843]: info: Reloading queue from file Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::setRepeat true single undefined Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::pushState Oct 16 15:23:58 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioPushState Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::setRandom null Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::pushState Oct 16 15:23:58 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioPushState Oct 16 15:23:58 volumiovm volumio[843]: info: CoreStateMachine::pushState Oct 16 15:23:58 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioPushState Oct 16 15:23:58 volumiovm volumio[843]: info: Asound.conf file unchanged, so no further update is needed Oct 16 15:23:58 volumiovm volumio[843]: info: Output device has changed, restarting MPD Oct 16 15:23:58 volumiovm volumio[843]: info: Output device has changed, restarting Shairport Sync Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:23:58 volumiovm sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 15:23:58 volumiovm sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 15:23:58 volumiovm sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:58 volumiovm sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:58 volumiovm sudo[1170]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:58 volumiovm volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 15:23:58 volumiovm volumio[843]: info: ___________ START PLUGINS ___________ Oct 16 15:23:58 volumiovm volumio[843]: info: ControllerMpd::onStart: Initializing MPD Oct 16 15:23:58 volumiovm volumio[843]: info: Creating MPD Configuration file Oct 16 15:23:58 volumiovm systemd[1]: Listening on mpd.socket. Oct 16 15:23:58 volumiovm systemd[1]: Starting Music Player Daemon... Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:23:58 volumiovm sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 15:23:58 volumiovm sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 15:23:58 volumiovm volumio[843]: info: [1729088638708] CoreMusicLibrary::Adding element Media Servers Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:58 volumiovm sudo[1180]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:58 volumiovm sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 15:23:58 volumiovm sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:23:58 volumiovm sudo[1179]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 15:23:58 volumiovm sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:58 volumiovm sudo[1179]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 16 15:23:58 volumiovm sudo[1179]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:58 volumiovm volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:58 volumiovm systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 16 15:23:58 volumiovm systemd[1]: mpd.service: Succeeded. Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:23:58 volumiovm systemd[1]: Stopped Music Player Daemon. Oct 16 15:23:58 volumiovm systemd[1]: Starting Music Player Daemon... Oct 16 15:23:58 volumiovm volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 15:23:58 volumiovm volumio[843]: info: [1729088638806] CoreMusicLibrary::Adding element Last_100 Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 15:23:58 volumiovm volumio[843]: info: [1729088638806] CoreMusicLibrary::Adding element Webradio Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 15:23:58 volumiovm volumio[843]: info: Initializing BBC Radios Oct 16 15:23:58 volumiovm sudo[1191]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 15:23:58 volumiovm sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:58 volumiovm sudo[1191]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 16 15:23:58 volumiovm sudo[1191]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:23:58 volumiovm volumio[843]: info: Loading i18n strings for locale en Oct 16 15:23:58 volumiovm sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Oct 16 15:23:58 volumiovm sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:58 volumiovm systemd[1]: Stopping Logitech Media Server Daemon... Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 15:23:58 volumiovm volumio[843]: info: [1729088638930] CoreMusicLibrary::Adding element Radio Paradise Oct 16 15:23:58 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:23:58 volumiovm volumio[843]: Cannot find translation for source Radio Paradise Oct 16 15:23:58 volumiovm volumio[843]: info: Volumio Calling Home Oct 16 15:23:58 volumiovm squeezeboxserver[803]: [24-10-16 15:23:57.5583] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Oct 16 15:23:59 volumiovm volumio[843]: info: Discovery: adding 5971dde3-bdb8-4d5a-a3da-ef32139f83d4 Oct 16 15:23:59 volumiovm volumio[843]: info: Discovery: Found device Volumiovm Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:23:59 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:23:59 volumiovm systemd[1]: logitechmediaserver.service: Succeeded. Oct 16 15:23:59 volumiovm systemd[1]: Stopped Logitech Media Server Daemon. Oct 16 15:23:59 volumiovm systemd[1]: Starting Logitech Media Server Daemon... Oct 16 15:23:59 volumiovm volumio[843]: info: MPD Permissions set Oct 16 15:23:59 volumiovm volumio[843]: info: MPD Permissions set Oct 16 15:23:59 volumiovm systemd[1]: Started Logitech Media Server Daemon. Oct 16 15:23:59 volumiovm sudo[1207]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:59 volumiovm volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:23:59 volumiovm volumio[843]: info: logitechmediaserver started Oct 16 15:23:59 volumiovm volumio[843]: info: minidlna: Found miniDLNA version 1.2.1 Oct 16 15:23:59 volumiovm volumio[843]: info: minidlna: Starting minidlna.service Oct 16 15:23:59 volumiovm volumio[843]: info: Volumio called home Oct 16 15:23:59 volumiovm sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 16 15:23:59 volumiovm volumio[843]: info: Starting Shairport Sync Oct 16 15:23:59 volumiovm sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:59 volumiovm volumio[843]: info: Starting Shairport Sync Oct 16 15:23:59 volumiovm sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 15:23:59 volumiovm volumio[843]: info: Starting Shairport Sync Oct 16 15:23:59 volumiovm sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:59 volumiovm systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 16 15:23:59 volumiovm ntpd[1130]: Soliciting pool server 77.104.162.218 Oct 16 15:23:59 volumiovm ntpd[1130]: Soliciting pool server 193.57.144.50 Oct 16 15:23:59 volumiovm sudo[1237]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:59 volumiovm sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 15:23:59 volumiovm sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:59 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:23:59 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:23:59 volumiovm sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 15:23:59 volumiovm sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:23:59 volumiovm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 15:23:59 volumiovm systemd[1]: shairport-sync.service: Succeeded. Oct 16 15:23:59 volumiovm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 15:23:59 volumiovm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 15:23:59 volumiovm volumio[843]: info: minidlna: systemctl start minidlna.service succeeded. Oct 16 15:23:59 volumiovm sudo[1240]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:59 volumiovm sudo[1242]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:59 volumiovm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 15:23:59 volumiovm systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 16 15:23:59 volumiovm systemd[1]: shairport-sync.service: Succeeded. Oct 16 15:23:59 volumiovm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 15:23:59 volumiovm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 15:23:59 volumiovm sudo[1246]: pam_unix(sudo:session): session closed for user root Oct 16 15:23:59 volumiovm volumio[843]: info: Shairport-Sync Started Oct 16 15:23:59 volumiovm volumio[843]: Error adding Membership: Error: addMembership EINVAL Oct 16 15:23:59 volumiovm volumio[843]: info: Shairport-Sync Started Oct 16 15:23:59 volumiovm dbus-daemon[576]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.11' (uid=0 pid=1058 comm="/usr/lib/chromium/chromium --show-component-extens") Oct 16 15:23:59 volumiovm systemd[1]: Starting Bluetooth service... Oct 16 15:24:00 volumiovm mpd[1199]: Oct 16 15:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 15:24:00 volumiovm systemd[1]: Started Music Player Daemon. Oct 16 15:24:00 volumiovm sudo[1182]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:00 volumiovm sudo[1173]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:00 volumiovm volumio[843]: info: Completed starting Core Plugins Oct 16 15:24:00 volumiovm volumio[843]: info: ------------------------------------------- Oct 16 15:24:00 volumiovm volumio[843]: info: ----- MyVolumio plugins startup ---- Oct 16 15:24:00 volumiovm volumio[843]: info: ------------------------------------------- Oct 16 15:24:00 volumiovm volumio[843]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 16 15:24:00 volumiovm kernel: Bluetooth: Core ver 2.22 Oct 16 15:24:00 volumiovm kernel: NET: Registered PF_BLUETOOTH protocol family Oct 16 15:24:00 volumiovm kernel: Bluetooth: HCI device and connection manager initialized Oct 16 15:24:00 volumiovm kernel: Bluetooth: HCI socket layer initialized Oct 16 15:24:00 volumiovm kernel: Bluetooth: L2CAP socket layer initialized Oct 16 15:24:00 volumiovm kernel: Bluetooth: SCO socket layer initialized Oct 16 15:24:00 volumiovm bluetoothd[1261]: Bluetooth daemon 5.50 Oct 16 15:24:00 volumiovm bluetoothd[1261]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 16 15:24:00 volumiovm dbus-daemon[576]: [system] Successfully activated service 'org.bluez' Oct 16 15:24:00 volumiovm systemd[1]: Started Bluetooth service. Oct 16 15:24:00 volumiovm bluetoothd[1261]: Starting SDP server Oct 16 15:24:00 volumiovm bluetoothd[1261]: Excluding (cli) sap Oct 16 15:24:00 volumiovm kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 16 15:24:00 volumiovm kernel: Bluetooth: BNEP filters: protocol multicast Oct 16 15:24:00 volumiovm kernel: Bluetooth: BNEP socket layer initialized Oct 16 15:24:00 volumiovm bluetoothd[1261]: Bluetooth management interface 1.22 initialized Oct 16 15:24:00 volumiovm dbus-daemon[576]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.12' (uid=0 pid=1261 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 16 15:24:00 volumiovm volumio[843]: error: MPD error: The expression evaluated to a falsy value: Oct 16 15:24:00 volumiovm volumio[843]: assert.ok(self.idling) Oct 16 15:24:00 volumiovm volumio[843]: error: The expression evaluated to a falsy value: Oct 16 15:24:00 volumiovm volumio[843]: assert.ok(self.idling) Oct 16 15:24:00 volumiovm volumio[843]: info: MPD running with PID1199 Oct 16 15:24:00 volumiovm volumio[843]: ,establishing connection Oct 16 15:24:00 volumiovm volumio[843]: error: updateQueue error: null Oct 16 15:24:00 volumiovm volumio[843]: error: updateQueue error: null Oct 16 15:24:00 volumiovm systemd[1]: Starting Hostname Service... Oct 16 15:24:00 volumiovm dbus-daemon[576]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 16 15:24:00 volumiovm systemd[1]: Started Hostname Service. Oct 16 15:24:00 volumiovm ntpd[1130]: Soliciting pool server 85.199.214.98 Oct 16 15:24:00 volumiovm ntpd[1130]: Soliciting pool server 185.53.45.5 Oct 16 15:24:00 volumiovm ntpd[1130]: Soliciting pool server 178.62.250.107 Oct 16 15:24:01 volumiovm ntpd[1130]: Soliciting pool server 139.162.219.252 Oct 16 15:24:01 volumiovm ntpd[1130]: Soliciting pool server 80.87.128.222 Oct 16 15:24:01 volumiovm ntpd[1130]: Soliciting pool server 213.210.39.123 Oct 16 15:24:01 volumiovm ntpd[1130]: Soliciting pool server 178.215.228.24 Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:00.6399] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 - x86_64-linux-gnu-thread-multi Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.3978] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.3980] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.3981] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.4012] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.4015] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.4016] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.4020] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.4046] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.4050] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Oct 16 15:24:01 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:01.4937] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.3784] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 Oct 16 15:24:02 volumiovm ntpd[1130]: Soliciting pool server 213.5.132.231 Oct 16 15:24:02 volumiovm ntpd[1130]: Soliciting pool server 132.226.210.133 Oct 16 15:24:02 volumiovm ntpd[1130]: Soliciting pool server 46.101.52.249 Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9478] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (No address associated with hostname) Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9647] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9685] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9692] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9764] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (No address associated with hostname) Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9778] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9779] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.000 seconds. Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9781] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9786] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9787] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Oct 16 15:24:02 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:02.9791] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0353] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0374] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0377] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0440] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0517] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0518] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.074 seconds. Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0519] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0522] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.000 seconds. Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0526] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0527] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.000 seconds. Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0528] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Oct 16 15:24:03 volumiovm squeezeboxserver[1215]: [24-10-16 15:24:03.0982] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.045 seconds. Oct 16 15:24:03 volumiovm volumio[843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 16 15:24:03 volumiovm sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas5media,password=nas5media1234!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.225/music /mnt/NAS/nas5 Oct 16 15:24:03 volumiovm sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:03 volumiovm ntpd[1130]: Soliciting pool server 82.219.4.30 Oct 16 15:24:03 volumiovm ntpd[1130]: Soliciting pool server 217.114.59.3 Oct 16 15:24:03 volumiovm kernel: CIFS: Attempting to mount //192.168.42.225/music Oct 16 15:24:03 volumiovm volumio[843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:03 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 15:24:03 volumiovm volumio[843]: info: Received Get System Info Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 15:24:03 volumiovm volumio[843]: info: Discovery: Getting this device information Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:03 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 15:24:03 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:03 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:03 volumiovm volumio[843]: info: Listing playlists Oct 16 15:24:04 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 15:24:04 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 15:24:04 volumiovm ntpd[1130]: Soliciting pool server 131.111.8.63 Oct 16 15:24:04 volumiovm ntpd[1130]: Soliciting pool server 2a03:b0c0:1:d0::b1d:6001 Oct 16 15:24:04 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:04 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:05 volumiovm volumio[843]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 16 15:24:07 volumiovm ntpd[1130]: receive: Unexpected origin timestamp 0xeaba4b05.7ee5e8c6 does not match aorg 0000000000.00000000 from server@131.111.8.63 xmt 0xeaba4b07.dd3a3075 Oct 16 15:24:07 volumiovm ntpd[1130]: receive: Unexpected origin timestamp 0xeaba4b05.7eee0896 does not match aorg 0000000000.00000000 from server@178.62.250.107 xmt 0xeaba4b07.dd4739a9 Oct 16 15:24:07 volumiovm ntpd[1130]: receive: Unexpected origin timestamp 0xeaba4b05.7ee9bb38 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeaba4b07.de0c01b6 Oct 16 15:24:07 volumiovm ntpd[1130]: receive: Unexpected origin timestamp 0xeaba4b05.7ef26bd7 does not match aorg 0000000000.00000000 from server@77.104.162.218 xmt 0xeaba4b07.de1ada46 Oct 16 15:24:08 volumiovm sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 15:24:08 volumiovm sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:08 volumiovm sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 15:24:08 volumiovm sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:08 volumiovm sudo[1555]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 15:24:08 volumiovm sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 15:24:08 volumiovm sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:08 volumiovm volumio[843]: info: Received Get System Info Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 15:24:08 volumiovm volumio[843]: info: Discovery: Getting this device information Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:08 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 15:24:08 volumiovm sudo[1557]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:08 volumiovm systemd[1]: Started UPnP Renderer front-end to MPD. Oct 16 15:24:08 volumiovm sudo[1559]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:08 volumiovm volumio[843]: info: Upmpdcli Daemon Started Oct 16 15:24:08 volumiovm volumio[1565]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 16 15:24:08 volumiovm volumio[1565]: ...........++++ Oct 16 15:24:08 volumiovm volumio[1565]: .............................................................................++++ Oct 16 15:24:08 volumiovm volumio[1565]: e is 65537 (0x010001) Oct 16 15:24:08 volumiovm volumio[1565]: writing RSA key Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 15:24:08 volumiovm volumio[843]: info: Received Get System Info Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 15:24:08 volumiovm volumio[843]: info: Discovery: Getting this device information Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:08 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 15:24:08 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 15:24:10 volumiovm volumio[843]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 15:24:10 volumiovm volumio[843]: info: Adding plugin multiroom to MyMusic Plugins Oct 16 15:24:10 volumiovm volumio[843]: info: Adding plugin metavolumio to MyMusic Plugins Oct 16 15:24:10 volumiovm volumio[843]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 15:24:10 volumiovm volumio[843]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 15:24:10 volumiovm volumio[843]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 15:24:10 volumiovm volumio[843]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 15:24:11 volumiovm volumio[843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 15:24:11 volumiovm volumio[843]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 15:24:11 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:24:11 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:24:11 volumiovm volumio[843]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 15:24:11 volumiovm volumio[843]: info: MyVolumio login type: Token Oct 16 15:24:11 volumiovm volumio[843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 15:24:11 volumiovm volumio[843]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 15:24:11 volumiovm volumio[843]: info: Starting Streaming Service Transparent Proxy Oct 16 15:24:11 volumiovm volumio[843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 15:24:11 volumiovm volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 15:24:11 volumiovm volumio[843]: info: Streaming services startup Oct 16 15:24:11 volumiovm volumio[843]: info: Starting Streaming Daemon Oct 16 15:24:11 volumiovm sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 15:24:11 volumiovm sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:11 volumiovm volumio[843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 16 15:24:11 volumiovm sudo[1597]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:11 volumiovm volumio[843]: error: Cannot start Volumio Streaming Daemon Oct 16 15:24:11 volumiovm volumio[843]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 15:24:11 volumiovm volumio[843]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 15:24:12 volumiovm sudo[1546]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:12 volumiovm kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 16 15:24:12 volumiovm kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 16 15:24:12 volumiovm volumio[843]: info: Cannot mount NAS nas5 at system boot, trial number 2 ,retrying in 5 seconds Oct 16 15:24:12 volumiovm volumio[843]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 16 15:24:12 volumiovm volumio[843]: STREAMING PROXY: Starting server on port 3245 Oct 16 15:24:12 volumiovm volumio[843]: Node JS runtime: 14 Oct 16 15:24:12 volumiovm volumio[843]: info: MyVolumio token set successfully Oct 16 15:24:12 volumiovm volumio[843]: info: MYVOLUMIO: Adding device Oct 16 15:24:12 volumiovm volumio[843]: info: MYVOLUMIO: Evaluating Server Oct 16 15:24:12 volumiovm volumio[843]: info: MyVolumio status changed Oct 16 15:24:12 volumiovm volumio[843]: info: Streaming services startup Oct 16 15:24:12 volumiovm volumio[843]: info: Starting Streaming Daemon Oct 16 15:24:12 volumiovm volumio[843]: info: Removing browser output: myVolumio user plan is not superstar Oct 16 15:24:12 volumiovm volumio[843]: info: Removing audio output: Oct 16 15:24:12 volumiovm volumio[843]: info: Stoppping Tunnel 1 Oct 16 15:24:12 volumiovm sudo[1629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 16 15:24:12 volumiovm sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 15:24:12 volumiovm sudo[1629]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:12 volumiovm sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:12 volumiovm sudo[1627]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:12 volumiovm volumio[843]: error: Cannot start Volumio Streaming Daemon Oct 16 15:24:12 volumiovm volumio[843]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 15:24:12 volumiovm volumio[843]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 15:24:12 volumiovm sudo[1629]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:12 volumiovm volumio[843]: info: Remote SSH Stopped Oct 16 15:24:12 volumiovm volumio[843]: info: Setting Geolocation for MyVolumio to eu3 Oct 16 15:24:12 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: Successfully Added MyVolumio device Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 15:24:13 volumiovm volumio[843]: info: Updating MyVolumio device info Oct 16 15:24:13 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:24:14 volumiovm volumio[843]: info: Successfully Updated MyVolumio device Oct 16 15:24:16 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 15:24:17 volumiovm sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas5media,password=nas5media1234!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.225/music /mnt/NAS/nas5 Oct 16 15:24:17 volumiovm sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:17 volumiovm kernel: CIFS: Attempting to mount //192.168.42.225/music Oct 16 15:24:21 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:21 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:23 volumiovm sudo[1637]: pam_unix(sudo:session): session closed for user root Oct 16 15:24:23 volumiovm volumio[843]: info: Cannot mount NAS nas5 at system boot, trial number 3 ,retrying in 5 seconds Oct 16 15:24:23 volumiovm kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 16 15:24:23 volumiovm kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 16 15:24:23 volumiovm systemd[1]: systemd-fsckd.service: Succeeded. Oct 16 15:24:23 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:24:23 volumiovm volumio[843]: info: CoreStateMachine::getQueue Oct 16 15:24:23 volumiovm volumio[843]: info: CorePlayQueue::getQueue Oct 16 15:24:24 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 15:24:24 volumiovm volumio[843]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 16 15:24:24 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 16 15:24:24 volumiovm volumio[843]: info: Received Get System Version Oct 16 15:24:24 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 15:24:24 volumiovm volumio[843]: info: Received Get System Info Oct 16 15:24:24 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 15:24:24 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 15:24:24 volumiovm volumio[843]: info: Discovery: Getting this device information Oct 16 15:24:24 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:24 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:24 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 15:24:26 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 15:24:26 volumiovm volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 16 15:24:26 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetState Oct 16 15:24:26 volumiovm volumio[843]: info: CorePlayQueue::getTrack 0 Oct 16 15:24:28 volumiovm sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas5media,password=nas5media1234!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.225/music /mnt/NAS/nas5 Oct 16 15:24:28 volumiovm sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:24:28 volumiovm kernel: CIFS: Attempting to mount //192.168.42.225/music Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 16 15:24:31 volumiovm volumio-remote-updater[560]: No test mode Oct 16 15:24:31 volumiovm volumio-remote-updater[560]: No alpha test mode Oct 16 15:24:31 volumiovm volumio[843]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:24:31 volumiovm volumio[843]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 16 15:24:31 volumiovm volumio[843]: info: Completed starting MyVolumio Plugin Oct 16 15:24:31 volumiovm volumio[843]: info: BOOT COMPLETED Oct 16 15:24:31 volumiovm volumio[843]: [Metrics] CommandRouter: 35s 216.12ms Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::volumiosetStartupVolume Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::Close All Modals sent Oct 16 15:24:31 volumiovm volumio[843]: info: CoreCommandRouter::Close All Modals sent Oct 16 15:24:31 volumiovm volumio[843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:24:31 volumiovm volumio[843]: TypeError: Cannot read property 'length' of undefined Oct 16 15:24:31 volumiovm volumio[843]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 16 15:24:31 volumiovm volumio[843]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 16 15:24:31 volumiovm volumio[843]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 16 15:24:31 volumiovm volumio[843]: at Parser.emit (events.js:400:28) Oct 16 15:24:31 volumiovm volumio[843]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 16 15:24:31 volumiovm volumio[843]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 16 15:24:31 volumiovm volumio[843]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 16 15:24:31 volumiovm volumio[843]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 16 15:24:31 volumiovm volumio[843]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 16 15:24:31 volumiovm volumio[843]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 16 15:24:31 volumiovm volumio[843]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 16 15:24:31 volumiovm volumio[843]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 16 15:24:31 volumiovm volumio[843]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 16 15:24:31 volumiovm volumio[843]: at IncomingMessage.emit (events.js:412:35) Oct 16 15:24:31 volumiovm volumio[843]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 16 15:24:31 volumiovm volumio[843]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 16 15:24:31 volumiovm volumio[843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:24:31 volumiovm sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-16 15:23 Oct 16 15:24:31 volumiovm sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"