-- Logs begin at Fri 2024-07-26 21:27:18 UTC, end at Sat 2024-07-27 05:27:08 UTC. -- Jul 27 05:26:51 hanvolumio kernel: Linux version 6.6.31-volumio (gkkpch@UbuntuVM24.04) (gcc (Ubuntu 13.2.0-23ubuntu4) 13.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Fri May 17 18:26:28 CEST 2024 Jul 27 05:26:51 hanvolumio kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=668afdde-eb8b-48b3-b8ac-1f0a643965c0 bootpart=UUID=9561-A5F0 datapart=UUID=0f08d23f-00cb-42b9-914c-9463e5fce58b 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 Jul 27 05:26:51 hanvolumio kernel: KERNEL supported cpus: Jul 27 05:26:51 hanvolumio kernel: Intel GenuineIntel Jul 27 05:26:51 hanvolumio kernel: AMD AuthenticAMD Jul 27 05:26:51 hanvolumio kernel: Hygon HygonGenuine Jul 27 05:26:51 hanvolumio kernel: Centaur CentaurHauls Jul 27 05:26:51 hanvolumio kernel: zhaoxin Shanghai Jul 27 05:26:51 hanvolumio kernel: BIOS-provided physical RAM map: Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffeffff] usable Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x000000007fff0000-0x000000007fffffff] ACPI data Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved Jul 27 05:26:51 hanvolumio kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Jul 27 05:26:51 hanvolumio kernel: NX (Execute Disable) protection: active Jul 27 05:26:51 hanvolumio kernel: APIC: Static calls initialized Jul 27 05:26:51 hanvolumio kernel: SMBIOS 2.5 present. Jul 27 05:26:51 hanvolumio kernel: DMI: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 Jul 27 05:26:51 hanvolumio kernel: Hypervisor detected: KVM Jul 27 05:26:51 hanvolumio kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Jul 27 05:26:51 hanvolumio kernel: kvm-clock: using sched offset of 5403836095 cycles Jul 27 05:26:51 hanvolumio kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jul 27 05:26:51 hanvolumio kernel: tsc: Detected 3417.598 MHz processor Jul 27 05:26:51 hanvolumio kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 27 05:26:51 hanvolumio kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 27 05:26:51 hanvolumio kernel: last_pfn = 0x7fff0 max_arch_pfn = 0x400000000 Jul 27 05:26:51 hanvolumio kernel: MTRRs disabled by BIOS Jul 27 05:26:51 hanvolumio kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 27 05:26:51 hanvolumio kernel: found SMP MP-table at [mem 0x0009fff0-0x0009ffff] Jul 27 05:26:51 hanvolumio kernel: Incomplete global flushes, disabling PCID Jul 27 05:26:51 hanvolumio kernel: RAMDISK: [mem 0x7bd6d000-0x7ffeffff] Jul 27 05:26:51 hanvolumio kernel: ACPI: Early table checksum verification disabled Jul 27 05:26:51 hanvolumio kernel: ACPI: RSDP 0x00000000000E0000 000024 (v02 VBOX ) Jul 27 05:26:51 hanvolumio kernel: ACPI: XSDT 0x000000007FFF0030 00003C (v01 VBOX VBOXXSDT 00000001 ASL 00000061) Jul 27 05:26:51 hanvolumio kernel: ACPI: FACP 0x000000007FFF00F0 0000F4 (v04 VBOX VBOXFACP 00000001 ASL 00000061) Jul 27 05:26:51 hanvolumio kernel: ACPI: DSDT 0x000000007FFF0610 002353 (v02 VBOX VBOXBIOS 00000002 INTL 20100528) Jul 27 05:26:51 hanvolumio kernel: ACPI: FACS 0x000000007FFF0200 000040 Jul 27 05:26:51 hanvolumio kernel: ACPI: FACS 0x000000007FFF0200 000040 Jul 27 05:26:51 hanvolumio kernel: ACPI: APIC 0x000000007FFF0240 000054 (v02 VBOX VBOXAPIC 00000001 ASL 00000061) Jul 27 05:26:51 hanvolumio kernel: ACPI: SSDT 0x000000007FFF02A0 00036C (v01 VBOX VBOXCPUT 00000002 INTL 20100528) Jul 27 05:26:51 hanvolumio kernel: ACPI: Reserving FACP table memory at [mem 0x7fff00f0-0x7fff01e3] Jul 27 05:26:51 hanvolumio kernel: ACPI: Reserving DSDT table memory at [mem 0x7fff0610-0x7fff2962] Jul 27 05:26:51 hanvolumio kernel: ACPI: Reserving FACS table memory at [mem 0x7fff0200-0x7fff023f] Jul 27 05:26:51 hanvolumio kernel: ACPI: Reserving FACS table memory at [mem 0x7fff0200-0x7fff023f] Jul 27 05:26:51 hanvolumio kernel: ACPI: Reserving APIC table memory at [mem 0x7fff0240-0x7fff0293] Jul 27 05:26:51 hanvolumio kernel: ACPI: Reserving SSDT table memory at [mem 0x7fff02a0-0x7fff060b] Jul 27 05:26:51 hanvolumio kernel: Zone ranges: Jul 27 05:26:51 hanvolumio kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 27 05:26:51 hanvolumio kernel: DMA32 [mem 0x0000000001000000-0x000000007ffeffff] Jul 27 05:26:51 hanvolumio kernel: Normal empty Jul 27 05:26:51 hanvolumio kernel: Movable zone start for each node Jul 27 05:26:51 hanvolumio kernel: Early memory node ranges Jul 27 05:26:51 hanvolumio kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Jul 27 05:26:51 hanvolumio kernel: node 0: [mem 0x0000000000100000-0x000000007ffeffff] Jul 27 05:26:51 hanvolumio kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffeffff] Jul 27 05:26:51 hanvolumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 27 05:26:51 hanvolumio kernel: On node 0, zone DMA: 97 pages in unavailable ranges Jul 27 05:26:51 hanvolumio kernel: On node 0, zone DMA32: 16 pages in unavailable ranges Jul 27 05:26:51 hanvolumio kernel: ACPI: PM-Timer IO Port: 0x4008 Jul 27 05:26:51 hanvolumio kernel: IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23 Jul 27 05:26:51 hanvolumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jul 27 05:26:51 hanvolumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level) Jul 27 05:26:51 hanvolumio kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 27 05:26:51 hanvolumio kernel: smpboot: Allowing 1 CPUs, 0 hotplug CPUs Jul 27 05:26:51 hanvolumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Jul 27 05:26:51 hanvolumio kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] Jul 27 05:26:51 hanvolumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] Jul 27 05:26:51 hanvolumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] Jul 27 05:26:51 hanvolumio kernel: [mem 0x80000000-0xfebfffff] available for PCI devices Jul 27 05:26:51 hanvolumio kernel: Booting paravirtualized kernel on KVM Jul 27 05:26:51 hanvolumio kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Jul 27 05:26:51 hanvolumio kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 Jul 27 05:26:51 hanvolumio kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u2097152 Jul 27 05:26:51 hanvolumio kernel: pcpu-alloc: s191400 r8192 d29784 u2097152 alloc=1*2097152 Jul 27 05:26:51 hanvolumio kernel: pcpu-alloc: [0] 0 Jul 27 05:26:51 hanvolumio kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=668afdde-eb8b-48b3-b8ac-1f0a643965c0 bootpart=UUID=9561-A5F0 datapart=UUID=0f08d23f-00cb-42b9-914c-9463e5fce58b 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 Jul 27 05:26:51 hanvolumio kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=668afdde-eb8b-48b3-b8ac-1f0a643965c0 bootpart=UUID=9561-A5F0 datapart=UUID=0f08d23f-00cb-42b9-914c-9463e5fce58b hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space. Jul 27 05:26:51 hanvolumio kernel: random: crng init done Jul 27 05:26:51 hanvolumio kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 515824 Jul 27 05:26:51 hanvolumio kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off Jul 27 05:26:51 hanvolumio kernel: Memory: 1964924K/2096696K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 131516K reserved, 0K cma-reserved) Jul 27 05:26:51 hanvolumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 Jul 27 05:26:51 hanvolumio kernel: ftrace: allocating 40432 entries in 158 pages Jul 27 05:26:51 hanvolumio kernel: ftrace: allocated 158 pages with 5 groups Jul 27 05:26:51 hanvolumio kernel: Dynamic Preempt: voluntary Jul 27 05:26:51 hanvolumio kernel: rcu: Preemptible hierarchical RCU implementation. Jul 27 05:26:51 hanvolumio kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1. Jul 27 05:26:51 hanvolumio kernel: Trampoline variant of Tasks RCU enabled. Jul 27 05:26:51 hanvolumio kernel: Rude variant of Tasks RCU enabled. Jul 27 05:26:51 hanvolumio kernel: Tracing variant of Tasks RCU enabled. Jul 27 05:26:51 hanvolumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Jul 27 05:26:51 hanvolumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 Jul 27 05:26:51 hanvolumio kernel: NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16 Jul 27 05:26:51 hanvolumio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 27 05:26:51 hanvolumio kernel: Console: colour VGA+ 80x25 Jul 27 05:26:51 hanvolumio kernel: printk: console [tty0] enabled Jul 27 05:26:51 hanvolumio kernel: ACPI: Core revision 20230628 Jul 27 05:26:51 hanvolumio kernel: APIC: Switch to symmetric I/O mode setup Jul 27 05:26:51 hanvolumio kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Jul 27 05:26:51 hanvolumio kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31433e83898, max_idle_ns: 440795269386 ns Jul 27 05:26:51 hanvolumio kernel: Calibrating delay loop (skipped) preset value.. 6835.19 BogoMIPS (lpj=13670392) Jul 27 05:26:51 hanvolumio kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Jul 27 05:26:51 hanvolumio kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Jul 27 05:26:51 hanvolumio kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 27 05:26:51 hanvolumio kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on vm exit Jul 27 05:26:51 hanvolumio kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on syscall Jul 27 05:26:51 hanvolumio kernel: Spectre V2 : Mitigation: Enhanced / Automatic IBRS Jul 27 05:26:51 hanvolumio kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Jul 27 05:26:51 hanvolumio kernel: Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT Jul 27 05:26:51 hanvolumio kernel: RETBleed: Mitigation: Enhanced IBRS Jul 27 05:26:51 hanvolumio kernel: Speculative Store Bypass: Vulnerable Jul 27 05:26:51 hanvolumio kernel: Register File Data Sampling: Vulnerable: No microcode Jul 27 05:26:51 hanvolumio kernel: x86/fpu: x87 FPU will use FXSAVE Jul 27 05:26:51 hanvolumio kernel: Freeing SMP alternatives memory: 32K Jul 27 05:26:51 hanvolumio kernel: pid_max: default: 32768 minimum: 301 Jul 27 05:26:51 hanvolumio kernel: LSM: initializing lsm=capability,yama,integrity Jul 27 05:26:51 hanvolumio kernel: Yama: becoming mindful. Jul 27 05:26:51 hanvolumio kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: APIC calibration not consistent with PM-Timer: 101ms instead of 100ms Jul 27 05:26:51 hanvolumio kernel: APIC delta adjusted to PM-Timer: 6212714 (6278824) Jul 27 05:26:51 hanvolumio kernel: smpboot: CPU0: 13th Gen Intel(R) Core(TM) i7-13700K (family: 0x6, model: 0xb7, stepping: 0x1) Jul 27 05:26:51 hanvolumio kernel: RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1. Jul 27 05:26:51 hanvolumio kernel: RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1. Jul 27 05:26:51 hanvolumio kernel: RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1. Jul 27 05:26:51 hanvolumio kernel: Performance Events: unsupported p6 CPU model 183 no PMU driver, software events only. Jul 27 05:26:51 hanvolumio kernel: signal: max sigframe size: 1440 Jul 27 05:26:51 hanvolumio kernel: rcu: Hierarchical SRCU implementation. Jul 27 05:26:51 hanvolumio kernel: rcu: Max phase no-delay instances is 1000. Jul 27 05:26:51 hanvolumio kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 27 05:26:51 hanvolumio kernel: smp: Bringing up secondary CPUs ... Jul 27 05:26:51 hanvolumio kernel: smp: Brought up 1 node, 1 CPU Jul 27 05:26:51 hanvolumio kernel: smpboot: Max logical packages: 1 Jul 27 05:26:51 hanvolumio kernel: smpboot: Total of 1 processors activated (6835.19 BogoMIPS) Jul 27 05:26:51 hanvolumio kernel: devtmpfs: initialized Jul 27 05:26:51 hanvolumio kernel: x86/mm: Memory block size: 128MB Jul 27 05:26:51 hanvolumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jul 27 05:26:51 hanvolumio kernel: futex hash table entries: 256 (order: 2, 16384 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: pinctrl core: initialized pinctrl subsystem Jul 27 05:26:51 hanvolumio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 27 05:26:51 hanvolumio kernel: audit: initializing netlink subsys (disabled) Jul 27 05:26:51 hanvolumio kernel: audit: type=2000 audit(1722029209.418:1): state=initialized audit_enabled=0 res=1 Jul 27 05:26:51 hanvolumio kernel: thermal_sys: Registered thermal governor 'fair_share' Jul 27 05:26:51 hanvolumio kernel: thermal_sys: Registered thermal governor 'bang_bang' Jul 27 05:26:51 hanvolumio kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 27 05:26:51 hanvolumio kernel: thermal_sys: Registered thermal governor 'user_space' Jul 27 05:26:51 hanvolumio kernel: EISA bus registered Jul 27 05:26:51 hanvolumio kernel: cpuidle: using governor ladder Jul 27 05:26:51 hanvolumio kernel: cpuidle: using governor menu Jul 27 05:26:51 hanvolumio kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 27 05:26:51 hanvolumio kernel: PCI: Using configuration type 1 for base access Jul 27 05:26:51 hanvolumio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 27 05:26:51 hanvolumio kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 27 05:26:51 hanvolumio kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 27 05:26:51 hanvolumio kernel: ACPI: Added _OSI(Module Device) Jul 27 05:26:51 hanvolumio kernel: ACPI: Added _OSI(Processor Device) Jul 27 05:26:51 hanvolumio kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jul 27 05:26:51 hanvolumio kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 27 05:26:51 hanvolumio kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded Jul 27 05:26:51 hanvolumio kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jul 27 05:26:51 hanvolumio kernel: ACPI: Interpreter enabled Jul 27 05:26:51 hanvolumio kernel: ACPI: PM: (supports S0 S5) Jul 27 05:26:51 hanvolumio kernel: ACPI: Using IOAPIC for interrupt routing Jul 27 05:26:51 hanvolumio kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 27 05:26:51 hanvolumio kernel: PCI: Using E820 reservations for host bridge windows Jul 27 05:26:51 hanvolumio kernel: ACPI: Enabled 2 GPEs in block 00 to 07 Jul 27 05:26:51 hanvolumio kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 27 05:26:51 hanvolumio kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Jul 27 05:26:51 hanvolumio kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Jul 27 05:26:51 hanvolumio kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Jul 27 05:26:51 hanvolumio kernel: PCI host bridge to bus 0000:00 Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfdffffff window] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: [15ad:0405] type 00 class 0x030000 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: reg 0x10: [io 0xd000-0xd00f] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: reg 0x14: [mem 0xe0000000-0xe7ffffff pref] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: reg 0x18: [mem 0xf0000000-0xf01fffff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:03.0: reg 0x10: [mem 0xf0200000-0xf021ffff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:03.0: reg 0x18: [io 0xd010-0xd017] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:04.0: reg 0x10: [io 0xd020-0xd03f] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:04.0: reg 0x14: [mem 0xf0400000-0xf07fffff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:04.0: reg 0x18: [mem 0xf0800000-0xf0803fff pref] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:05.0: [8086:2668] type 00 class 0x040300 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:05.0: reg 0x10: [mem 0xf0804000-0xf0807fff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:07.0: [8086:7113] type 00 class 0x068000 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:07.0: quirk: [io 0x4000-0x403f] claimed by PIIX4 ACPI Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:07.0: quirk: [io 0x4100-0x410f] claimed by PIIX4 SMB Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0c.0: [8086:1e31] type 00 class 0x0c0330 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0c.0: reg 0x10: [mem 0xf0810000-0xf081ffff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0d.0: [8086:2829] type 00 class 0x010601 Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0d.0: reg 0x10: [io 0xd040-0xd047] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0d.0: reg 0x14: [io 0xd048-0xd04b] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0d.0: reg 0x18: [io 0xd050-0xd057] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0d.0: reg 0x1c: [io 0xd058-0xd05b] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0d.0: reg 0x20: [io 0xd060-0xd06f] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:0d.0: reg 0x24: [mem 0xf0820000-0xf0821fff] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: on NUMA node 0 Jul 27 05:26:51 hanvolumio kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 11 Jul 27 05:26:51 hanvolumio kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Jul 27 05:26:51 hanvolumio kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 9 Jul 27 05:26:51 hanvolumio kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Jul 27 05:26:51 hanvolumio kernel: iommu: Default domain type: Translated Jul 27 05:26:51 hanvolumio kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 27 05:26:51 hanvolumio kernel: SCSI subsystem initialized Jul 27 05:26:51 hanvolumio kernel: libata version 3.00 loaded. Jul 27 05:26:51 hanvolumio kernel: ACPI: bus type USB registered Jul 27 05:26:51 hanvolumio kernel: usbcore: registered new interface driver usbfs Jul 27 05:26:51 hanvolumio kernel: usbcore: registered new interface driver hub Jul 27 05:26:51 hanvolumio kernel: usbcore: registered new device driver usb Jul 27 05:26:51 hanvolumio kernel: PCI: Using ACPI for IRQ routing Jul 27 05:26:51 hanvolumio kernel: PCI: pci_cache_line_size set to 64 bytes Jul 27 05:26:51 hanvolumio kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Jul 27 05:26:51 hanvolumio kernel: e820: reserve RAM buffer [mem 0x7fff0000-0x7fffffff] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: vgaarb: bridge control possible Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jul 27 05:26:51 hanvolumio kernel: vgaarb: loaded Jul 27 05:26:51 hanvolumio kernel: clocksource: Switched to clocksource kvm-clock Jul 27 05:26:51 hanvolumio kernel: VFS: Disk quotas dquot_6.6.0 Jul 27 05:26:51 hanvolumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 27 05:26:51 hanvolumio kernel: pnp: PnP ACPI init Jul 27 05:26:51 hanvolumio kernel: pnp: PnP ACPI: found 2 devices Jul 27 05:26:51 hanvolumio kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 27 05:26:51 hanvolumio kernel: NET: Registered PF_INET protocol family Jul 27 05:26:51 hanvolumio kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: TCP: Hash tables configured (established 16384 bind 16384) Jul 27 05:26:51 hanvolumio kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Jul 27 05:26:51 hanvolumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jul 27 05:26:51 hanvolumio kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfdffffff window] Jul 27 05:26:51 hanvolumio kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Jul 27 05:26:51 hanvolumio kernel: PCI: CLS 0 bytes, default 64 Jul 27 05:26:51 hanvolumio kernel: Trying to unpack rootfs image as initramfs... Jul 27 05:26:51 hanvolumio kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 335544320 ms ovfl timer Jul 27 05:26:51 hanvolumio kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31433e83898, max_idle_ns: 440795269386 ns Jul 27 05:26:51 hanvolumio kernel: clocksource: Switched to clocksource tsc Jul 27 05:26:51 hanvolumio kernel: platform rtc_cmos: registered platform RTC device (no PNP device found) Jul 27 05:26:51 hanvolumio kernel: Initialise system trusted keyrings Jul 27 05:26:51 hanvolumio kernel: workingset: timestamp_bits=46 max_order=19 bucket_order=0 Jul 27 05:26:51 hanvolumio kernel: zbud: loaded Jul 27 05:26:51 hanvolumio kernel: Key type asymmetric registered Jul 27 05:26:51 hanvolumio kernel: Asymmetric key parser 'x509' registered Jul 27 05:26:51 hanvolumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Jul 27 05:26:51 hanvolumio kernel: io scheduler mq-deadline registered Jul 27 05:26:51 hanvolumio kernel: io scheduler kyber registered Jul 27 05:26:51 hanvolumio kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Jul 27 05:26:51 hanvolumio kernel: ACPI: AC: AC Adapter [AC] (on-line) Jul 27 05:26:51 hanvolumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Jul 27 05:26:51 hanvolumio kernel: ACPI: button: Power Button [PWRF] Jul 27 05:26:51 hanvolumio kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1 Jul 27 05:26:51 hanvolumio kernel: ACPI: button: Sleep Button [SLPF] Jul 27 05:26:51 hanvolumio kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Jul 27 05:26:51 hanvolumio kernel: Linux agpgart interface v0.103 Jul 27 05:26:51 hanvolumio kernel: xhci_hcd 0000:00:0c.0: xHCI Host Controller Jul 27 05:26:51 hanvolumio kernel: xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 1 Jul 27 05:26:51 hanvolumio kernel: xhci_hcd 0000:00:0c.0: hcc params 0x04000000 hci version 0x100 quirks 0x000000000000b930 Jul 27 05:26:51 hanvolumio kernel: xhci_hcd 0000:00:0c.0: xHCI Host Controller Jul 27 05:26:51 hanvolumio kernel: xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 2 Jul 27 05:26:51 hanvolumio kernel: xhci_hcd 0000:00:0c.0: Host supports USB 3.0 SuperSpeed Jul 27 05:26:51 hanvolumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Jul 27 05:26:51 hanvolumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 27 05:26:51 hanvolumio kernel: usb usb1: Product: xHCI Host Controller Jul 27 05:26:51 hanvolumio kernel: usb usb1: Manufacturer: Linux 6.6.31-volumio xhci-hcd Jul 27 05:26:51 hanvolumio kernel: usb usb1: SerialNumber: 0000:00:0c.0 Jul 27 05:26:51 hanvolumio kernel: hub 1-0:1.0: USB hub found Jul 27 05:26:51 hanvolumio kernel: hub 1-0:1.0: 8 ports detected Jul 27 05:26:51 hanvolumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Jul 27 05:26:51 hanvolumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 27 05:26:51 hanvolumio kernel: usb usb2: Product: xHCI Host Controller Jul 27 05:26:51 hanvolumio kernel: usb usb2: Manufacturer: Linux 6.6.31-volumio xhci-hcd Jul 27 05:26:51 hanvolumio kernel: usb usb2: SerialNumber: 0000:00:0c.0 Jul 27 05:26:51 hanvolumio kernel: hub 2-0:1.0: USB hub found Jul 27 05:26:51 hanvolumio kernel: hub 2-0:1.0: 6 ports detected Jul 27 05:26:51 hanvolumio kernel: i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12 Jul 27 05:26:51 hanvolumio kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Jul 27 05:26:51 hanvolumio kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Jul 27 05:26:51 hanvolumio kernel: mousedev: PS/2 mouse device common for all mice Jul 27 05:26:51 hanvolumio kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 Jul 27 05:26:51 hanvolumio kernel: rtc_cmos rtc_cmos: registered as rtc0 Jul 27 05:26:51 hanvolumio kernel: rtc_cmos rtc_cmos: setting system clock to 2024-07-27T05:26:43 UTC (1722058003) Jul 27 05:26:51 hanvolumio kernel: rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram Jul 27 05:26:51 hanvolumio kernel: i2c_dev: i2c /dev entries driver Jul 27 05:26:51 hanvolumio kernel: platform eisa.0: Probing EISA bus 0 Jul 27 05:26:51 hanvolumio kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Jul 27 05:26:51 hanvolumio kernel: intel_pstate: CPU model not supported Jul 27 05:26:51 hanvolumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Jul 27 05:26:51 hanvolumio kernel: NET: Registered PF_INET6 protocol family Jul 27 05:26:51 hanvolumio kernel: Segment Routing with IPv6 Jul 27 05:26:51 hanvolumio kernel: In-situ OAM (IOAM) with IPv6 Jul 27 05:26:51 hanvolumio kernel: mip6: Mobile IPv6 Jul 27 05:26:51 hanvolumio kernel: NET: Registered PF_PACKET protocol family Jul 27 05:26:51 hanvolumio kernel: mpls_gso: MPLS GSO support Jul 27 05:26:51 hanvolumio kernel: IPI shorthand broadcast: enabled Jul 27 05:26:51 hanvolumio kernel: sched_clock: Marking stable (516618444, 76695433)->(602190813, -8876936) Jul 27 05:26:51 hanvolumio kernel: Loading compiled-in X.509 certificates Jul 27 05:26:51 hanvolumio kernel: clk: Disabling unused clocks Jul 27 05:26:51 hanvolumio kernel: usb 1-1: new full-speed USB device number 2 using xhci_hcd Jul 27 05:26:51 hanvolumio kernel: usb 1-1: New USB device found, idVendor=80ee, idProduct=0021, bcdDevice= 1.00 Jul 27 05:26:51 hanvolumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jul 27 05:26:51 hanvolumio kernel: usb 1-1: Product: USB Tablet Jul 27 05:26:51 hanvolumio kernel: usb 1-1: Manufacturer: VirtualBox Jul 27 05:26:51 hanvolumio kernel: Freeing initrd memory: 68108K Jul 27 05:26:51 hanvolumio kernel: Freeing unused kernel image (initmem) memory: 2988K Jul 27 05:26:51 hanvolumio kernel: Write protecting the kernel read-only data: 20480k Jul 27 05:26:51 hanvolumio kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K Jul 27 05:26:51 hanvolumio kernel: Run /init as init process Jul 27 05:26:51 hanvolumio kernel: with arguments: Jul 27 05:26:51 hanvolumio kernel: /init Jul 27 05:26:51 hanvolumio kernel: splash Jul 27 05:26:51 hanvolumio kernel: with environment: Jul 27 05:26:51 hanvolumio kernel: HOME=/ Jul 27 05:26:51 hanvolumio kernel: TERM=linux Jul 27 05:26:51 hanvolumio kernel: BOOT_IMAGE=vmlinuz Jul 27 05:26:51 hanvolumio kernel: biosdevname=0 Jul 27 05:26:51 hanvolumio kernel: imgpart=UUID=668afdde-eb8b-48b3-b8ac-1f0a643965c0 Jul 27 05:26:51 hanvolumio kernel: bootpart=UUID=9561-A5F0 Jul 27 05:26:51 hanvolumio kernel: datapart=UUID=0f08d23f-00cb-42b9-914c-9463e5fce58b Jul 27 05:26:51 hanvolumio kernel: hwdevice=x86 Jul 27 05:26:51 hanvolumio kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg Jul 27 05:26:51 hanvolumio kernel: imgfile=/volumio_current.sqsh Jul 27 05:26:51 hanvolumio kernel: use_kmsg=no Jul 27 05:26:51 hanvolumio kernel: e1000: Intel(R) PRO/1000 Network Driver Jul 27 05:26:51 hanvolumio kernel: e1000: Copyright (c) 1999-2006 Intel Corporation. Jul 27 05:26:51 hanvolumio kernel: piix4_smbus 0000:00:07.0: SMBus Host Controller at 0x4100, revision 0 Jul 27 05:26:51 hanvolumio kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) Jul 27 05:26:51 hanvolumio kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input4 Jul 27 05:26:51 hanvolumio kernel: ahci 0000:00:0d.0: version 3.0 Jul 27 05:26:51 hanvolumio kernel: ahci 0000:00:0d.0: SSS flag set, parallel bus scan disabled Jul 27 05:26:51 hanvolumio kernel: ahci 0000:00:0d.0: AHCI 0001.0100 32 slots 3 ports 3 Gbps 0x7 impl SATA mode Jul 27 05:26:51 hanvolumio kernel: ahci 0000:00:0d.0: flags: 64bit ncq stag only ccc Jul 27 05:26:51 hanvolumio kernel: scsi host0: ahci Jul 27 05:26:51 hanvolumio kernel: scsi host1: ahci Jul 27 05:26:51 hanvolumio kernel: scsi host2: ahci Jul 27 05:26:51 hanvolumio kernel: ata1: SATA max UDMA/133 abar m8192@0xf0820000 port 0xf0820100 irq 21 Jul 27 05:26:51 hanvolumio kernel: ata2: SATA max UDMA/133 abar m8192@0xf0820000 port 0xf0820180 irq 21 Jul 27 05:26:51 hanvolumio kernel: ata3: SATA max UDMA/133 abar m8192@0xf0820000 port 0xf0820200 irq 21 Jul 27 05:26:51 hanvolumio kernel: hid: raw HID events driver (C) Jiri Kosina Jul 27 05:26:51 hanvolumio kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 Jul 27 05:26:51 hanvolumio kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Jul 27 05:26:51 hanvolumio kernel: ata1.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133 Jul 27 05:26:51 hanvolumio kernel: ata1.00: 16777216 sectors, multi 128: LBA48 NCQ (depth 32) Jul 27 05:26:51 hanvolumio kernel: ata1.00: configured for UDMA/133 Jul 27 05:26:51 hanvolumio kernel: scsi 0:0:0:0: Direct-Access ATA VBOX HARDDISK 1.0 PQ: 0 ANSI: 5 Jul 27 05:26:51 hanvolumio kernel: sd 0:0:0:0: [sda] 16777216 512-byte logical blocks: (8.59 GB/8.00 GiB) Jul 27 05:26:51 hanvolumio kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 27 05:26:51 hanvolumio kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 Jul 27 05:26:51 hanvolumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jul 27 05:26:51 hanvolumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Jul 27 05:26:51 hanvolumio kernel: sda: sda1 sda2 sda3 Jul 27 05:26:51 hanvolumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 27 05:26:51 hanvolumio kernel: ata2: SATA link down (SStatus 0 SControl 300) Jul 27 05:26:51 hanvolumio kernel: e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 08:00:27:f1:fa:a3 Jul 27 05:26:51 hanvolumio kernel: e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection Jul 27 05:26:51 hanvolumio kernel: ata3: SATA link down (SStatus 0 SControl 300) Jul 27 05:26:51 hanvolumio kernel: usbcore: registered new interface driver usbhid Jul 27 05:26:51 hanvolumio kernel: usbhid: USB HID core driver Jul 27 05:26:51 hanvolumio kernel: input: VirtualBox USB Tablet as /devices/pci0000:00/0000:00:0c.0/usb1/1-1/1-1:1.0/0003:80EE:0021.0001/input/input6 Jul 27 05:26:51 hanvolumio kernel: hid-generic 0003:80EE:0021.0001: input,hidraw0: USB HID v1.10 Mouse [VirtualBox USB Tablet] on usb-0000:00:0c.0-1/input0 Jul 27 05:26:51 hanvolumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 27 05:26:51 hanvolumio kernel: sdhci: Secure Digital Host Controller Interface driver Jul 27 05:26:51 hanvolumio kernel: sdhci: Copyright(c) Pierre Ossman Jul 27 05:26:51 hanvolumio kernel: fuse: init (API version 7.39) Jul 27 05:26:51 hanvolumio kernel: usbcore: registered new interface driver usb-storage Jul 27 05:26:51 hanvolumio kernel: usbcore: registered new interface driver uas Jul 27 05:26:51 hanvolumio kernel: ACPI: bus type drm_connector registered Jul 27 05:26:51 hanvolumio kernel: [drm] radeon kernel modesetting enabled. Jul 27 05:26:51 hanvolumio kernel: scsi host3: pata_legacy Jul 27 05:26:51 hanvolumio kernel: ata4: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 Jul 27 05:26:51 hanvolumio kernel: scsi host3: pata_legacy Jul 27 05:26:51 hanvolumio kernel: ata5: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 Jul 27 05:26:51 hanvolumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 27 05:26:51 hanvolumio kernel: EXT4-fs (sda2): recovery complete Jul 27 05:26:51 hanvolumio kernel: EXT4-fs (sda2): mounted filesystem 668afdde-eb8b-48b3-b8ac-1f0a643965c0 r/w with ordered data mode. Quota mode: none. Jul 27 05:26:51 hanvolumio kernel: loop: module loaded Jul 27 05:26:51 hanvolumio kernel: block device autoloading is deprecated and will be removed. Jul 27 05:26:51 hanvolumio kernel: loop0: detected capacity change from 0 to 1721400 Jul 27 05:26:51 hanvolumio kernel: EXT4-fs (sda3): recovery complete Jul 27 05:26:51 hanvolumio kernel: EXT4-fs (sda3): mounted filesystem 0f08d23f-00cb-42b9-914c-9463e5fce58b r/w with ordered data mode. Quota mode: none. Jul 27 05:26:51 hanvolumio systemd[1]: Inserted module 'autofs4' Jul 27 05:26:51 hanvolumio 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) Jul 27 05:26:51 hanvolumio systemd[1]: Detected virtualization oracle. Jul 27 05:26:51 hanvolumio systemd[1]: Detected architecture x86-64. Jul 27 05:26:51 hanvolumio systemd[1]: Set hostname to . Jul 27 05:26:51 hanvolumio 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. Jul 27 05:26:51 hanvolumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Jul 27 05:26:51 hanvolumio 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. Jul 27 05:26:51 hanvolumio systemd[1]: Created slice system-getty.slice. Jul 27 05:26:51 hanvolumio systemd[1]: Listening on Journal Audit Socket. Jul 27 05:26:51 hanvolumio systemd[1]: Listening on udev Kernel Socket. Jul 27 05:26:51 hanvolumio systemd-journald[420]: Journal started Jul 27 05:26:51 hanvolumio systemd-journald[420]: Runtime journal (/run/log/journal/0b2930d493c36928490994506425b530) is 3.7M, max 30.0M, 26.2M free. Jul 27 05:26:51 hanvolumio fake-hwclock[408]: Current system time: 2024-07-27 05:26:51 Jul 27 05:26:51 hanvolumio fake-hwclock[408]: fake-hwclock saved clock information is in the past: 2024-07-26 21:18:24 Jul 27 05:26:51 hanvolumio fake-hwclock[408]: To set system time to this saved clock anyway, use "force" Jul 27 05:26:51 hanvolumio systemd[1]: Started udev Coldplug all Devices. Jul 27 05:26:51 hanvolumio systemd[1]: Started Apply Kernel Variables. Jul 27 05:26:51 hanvolumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 27 05:26:51 hanvolumio systemd[1]: Started Load/Save Random Seed. Jul 27 05:26:51 hanvolumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 27 05:26:51 hanvolumio kernel: RPC: Registered named UNIX socket transport module. Jul 27 05:26:51 hanvolumio kernel: RPC: Registered udp transport module. Jul 27 05:26:51 hanvolumio kernel: RPC: Registered tcp transport module. Jul 27 05:26:51 hanvolumio kernel: RPC: Registered tcp-with-tls transport module. Jul 27 05:26:51 hanvolumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 27 05:26:51 hanvolumio systemd[1]: Mounted RPC Pipe File System. Jul 27 05:26:51 hanvolumio systemd[1]: dynamicswap.service: Succeeded. Jul 27 05:26:51 hanvolumio systemd[1]: Started Create System Users. Jul 27 05:26:51 hanvolumio systemd[1]: Starting Create Static Device Nodes in /dev... Jul 27 05:26:51 hanvolumio systemd[1]: Started Create Static Device Nodes in /dev. Jul 27 05:26:51 hanvolumio systemd[1]: Starting udev Kernel Device Manager... Jul 27 05:26:51 hanvolumio systemd[1]: Reached target Local File Systems (Pre). Jul 27 05:26:51 hanvolumio systemd[1]: Mounting /var/spool/cups... Jul 27 05:26:51 hanvolumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 27 05:26:51 hanvolumio systemd[1]: Mounting /tmp... Jul 27 05:26:51 hanvolumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 27 05:26:51 hanvolumio systemd-udevd[441]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 27 05:26:51 hanvolumio systemd[1]: Mounting /var/log... Jul 27 05:26:51 hanvolumio systemd[1]: Mounted /var/spool/cups. Jul 27 05:26:51 hanvolumio systemd[1]: Mounted /tmp. Jul 27 05:26:51 hanvolumio systemd[1]: Mounting /var/spool/cups/tmp... Jul 27 05:26:51 hanvolumio systemd[1]: Started udev Kernel Device Manager. Jul 27 05:26:51 hanvolumio systemd[1]: Mounted /var/spool/cups/tmp. Jul 27 05:26:51 hanvolumio systemd[1]: Starting Show Plymouth Boot Screen... Jul 27 05:26:51 hanvolumio systemd[1]: Mounted /var/log. Jul 27 05:26:51 hanvolumio systemd[1]: Starting Flush Journal to Persistent Storage... Jul 27 05:26:51 hanvolumio systemd-journald[420]: Runtime journal (/run/log/journal/0b2930d493c36928490994506425b530) is 3.7M, max 30.0M, 26.2M free. Jul 27 05:26:51 hanvolumio systemd[1]: plymouth-start.service: Succeeded. Jul 27 05:26:51 hanvolumio systemd[1]: Started Show Plymouth Boot Screen. Jul 27 05:26:51 hanvolumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 27 05:26:51 hanvolumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 27 05:26:51 hanvolumio systemd[1]: Reached target Paths. Jul 27 05:26:51 hanvolumio systemd[1]: Reached target Local Encrypted Volumes. Jul 27 05:26:51 hanvolumio systemd[1]: Started Flush Journal to Persistent Storage. Jul 27 05:26:51 hanvolumio systemd-udevd[449]: Using default interface naming scheme 'v240'. Jul 27 05:26:51 hanvolumio systemd-udevd[449]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 27 05:26:51 hanvolumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jul 27 05:26:51 hanvolumio systemd[1]: Found device VBOX_HARDDISK boot. Jul 27 05:26:51 hanvolumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/9561-A5F0... Jul 27 05:26:51 hanvolumio systemd-udevd[459]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio systemd-udevd[455]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio systemd-udevd[456]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio systemd-udevd[446]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio systemd[1]: Started File System Check Daemon to report status. Jul 27 05:26:51 hanvolumio systemd-udevd[451]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio systemd-fsck[492]: fsck.fat 4.1 (2017-01-24) Jul 27 05:26:51 hanvolumio systemd-fsck[492]: There are differences between boot sector and its backup. Jul 27 05:26:51 hanvolumio systemd-fsck[492]: This is mostly harmless. Differences: (offset:original/backup) Jul 27 05:26:51 hanvolumio systemd-fsck[492]: 3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 282:2d/00, 283:15/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: , 508:18/00, 509:37/00 Jul 27 05:26:51 hanvolumio systemd-fsck[492]: Not automatically fixing this. Jul 27 05:26:51 hanvolumio systemd-fsck[492]: /dev/sda1: 26 files, 161675/344788 clusters Jul 27 05:26:51 hanvolumio systemd[1]: Started File System Check on /dev/disk/by-uuid/9561-A5F0. Jul 27 05:26:51 hanvolumio systemd-udevd[459]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio systemd[1]: Mounting /boot... Jul 27 05:26:51 hanvolumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 27 05:26:51 hanvolumio systemd[1]: Mounted /boot. Jul 27 05:26:51 hanvolumio systemd[1]: Reached target Local File Systems. Jul 27 05:26:51 hanvolumio systemd[1]: Starting Preprocess NFS configuration... Jul 27 05:26:51 hanvolumio systemd[1]: Starting Create Volatile Files and Directories... Jul 27 05:26:51 hanvolumio systemd[1]: Starting Raise network interfaces... Jul 27 05:26:51 hanvolumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 27 05:26:51 hanvolumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 27 05:26:51 hanvolumio systemd-udevd[457]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio systemd-udevd[450]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:51 hanvolumio kernel: cryptd: max_cpu_qlen set to 1000 Jul 27 05:26:51 hanvolumio kernel: SSE version of gcm_enc/dec engaged. Jul 27 05:26:51 hanvolumio systemd[1]: nfs-config.service: Succeeded. Jul 27 05:26:51 hanvolumio systemd[1]: Started Preprocess NFS configuration. Jul 27 05:26:51 hanvolumio systemd[1]: Started Create Volatile Files and Directories. Jul 27 05:26:51 hanvolumio systemd[1]: plymouth-read-write.service: Succeeded. Jul 27 05:26:51 hanvolumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 27 05:26:51 hanvolumio systemd[1]: Received SIGRTMIN+20 from PID 303 (plymouthd). Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: autoconfig for STAC9221 A1: line_outs=3 (0xc/0xf/0xb/0x0/0x0) type:speaker Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: hp_outs=1 (0xa/0x0/0x0/0x0/0x0) Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: mono: mono_out=0x0 Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: dig-out=0x10/0x0 Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: inputs: Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: Mic=0xd Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: Line=0xe Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: CD=0x15 Jul 27 05:26:51 hanvolumio kernel: snd_hda_codec_idt hdaudioC0D0: dig-in=0x11 Jul 27 05:26:51 hanvolumio systemd[1]: Found device 82540EM Gigabit Ethernet Controller (PRO/1000 MT Desktop Adapter). Jul 27 05:26:51 hanvolumio systemd-udevd[451]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 27 05:26:52 hanvolumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 27 05:26:52 hanvolumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 27 05:26:52 hanvolumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 27 05:26:52 hanvolumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jul 27 05:26:52 hanvolumio systemd[1]: Started ifup for eth0. Jul 27 05:26:52 hanvolumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 27 05:26:52 hanvolumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 27 05:26:52 hanvolumio systemd[1]: Starting RPC bind portmap service... Jul 27 05:26:52 hanvolumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 27 05:26:52 hanvolumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 27 05:26:52 hanvolumio systemd[1]: Reached target NFS client services. Jul 27 05:26:52 hanvolumio ifup[507]: command failed: No such device (-19) Jul 27 05:26:52 hanvolumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 27 05:26:52 hanvolumio systemd[1]: Reached target System Initialization. Jul 27 05:26:52 hanvolumio systemd[1]: Started Daily apt download activities. Jul 27 05:26:52 hanvolumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 27 05:26:52 hanvolumio systemd[1]: Listening on triggerhappy.socket. Jul 27 05:26:52 hanvolumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 27 05:26:52 hanvolumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 27 05:26:52 hanvolumio systemd[1]: Reached target Sockets. Jul 27 05:26:52 hanvolumio systemd[1]: Started Daily apt upgrade and clean activities. Jul 27 05:26:52 hanvolumio systemd[1]: Started Daily man-db regeneration. Jul 27 05:26:52 hanvolumio systemd[1]: Reached target Timers. Jul 27 05:26:52 hanvolumio systemd[1]: Reached target Basic System. Jul 27 05:26:52 hanvolumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 27 05:26:52 hanvolumio systemd[1]: Starting Login Service... Jul 27 05:26:52 hanvolumio systemd[1]: Started Volumio Log Rotation Service. Jul 27 05:26:52 hanvolumio sh[544]: command failed: No such device (-19) Jul 27 05:26:52 hanvolumio systemd[1]: Started Volumio Iptables Module. Jul 27 05:26:52 hanvolumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 27 05:26:52 hanvolumio systemd[1]: Started D-Bus System Message Bus. Jul 27 05:26:52 hanvolumio systemd[1]: Starting WPA supplicant... Jul 27 05:26:52 hanvolumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 27 05:26:52 hanvolumio systemd[1]: Starting triggerhappy global hotkey daemon... Jul 27 05:26:52 hanvolumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 27 05:26:52 hanvolumio systemd[1]: Starting DHCP Client Daemon... Jul 27 05:26:52 hanvolumio systemd[1]: Started volumio-remote-updater.service. Jul 27 05:26:52 hanvolumio systemd[1]: Starting Wireless Services... Jul 27 05:26:52 hanvolumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 27 05:26:52 hanvolumio thd[590]: Unable to parse trigger line: Jul 27 05:26:52 hanvolumio thd[590]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 27 05:26:52 hanvolumio thd[590]: Unable to parse trigger line: Jul 27 05:26:52 hanvolumio thd[590]: Found socket passed from systemd Jul 27 05:26:52 hanvolumio systemd-udevd[459]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:52 hanvolumio systemd-logind[566]: Watching system buttons on /dev/input/event0 (Power Button) Jul 27 05:26:52 hanvolumio systemd-logind[566]: Watching system buttons on /dev/input/event1 (Sleep Button) Jul 27 05:26:52 hanvolumio systemd-logind[566]: Watching system buttons on /dev/input/event2 (AT Translated Set 2 keyboard) Jul 27 05:26:52 hanvolumio systemd-logind[566]: New seat seat0. Jul 27 05:26:52 hanvolumio kernel: input: HDA Intel Mic as /devices/pci0000:00/0000:00:05.0/sound/card0/input7 Jul 27 05:26:52 hanvolumio kernel: input: HDA Intel Line as /devices/pci0000:00/0000:00:05.0/sound/card0/input8 Jul 27 05:26:52 hanvolumio kernel: input: HDA Intel Speaker Front as /devices/pci0000:00/0000:00:05.0/sound/card0/input9 Jul 27 05:26:52 hanvolumio kernel: input: HDA Intel Speaker CLFE as /devices/pci0000:00/0000:00:05.0/sound/card0/input10 Jul 27 05:26:52 hanvolumio kernel: input: HDA Intel Front Headphone as /devices/pci0000:00/0000:00:05.0/sound/card0/input11 Jul 27 05:26:52 hanvolumio kernel: input: HDA Intel SPDIF In as /devices/pci0000:00/0000:00:05.0/sound/card0/input12 Jul 27 05:26:52 hanvolumio ifup[507]: command failed: No such device (-19) Jul 27 05:26:52 hanvolumio avahi-daemon[601]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Jul 27 05:26:52 hanvolumio avahi-daemon[601]: Successfully dropped root privileges. Jul 27 05:26:52 hanvolumio avahi-daemon[601]: avahi-daemon 0.7 starting up. Jul 27 05:26:52 hanvolumio kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 27 05:26:52 hanvolumio systemd-udevd[455]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:52 hanvolumio dhcpcd[593]: no interfaces have a carrier Jul 27 05:26:52 hanvolumio dhcpcd[593]: no interfaces have a carrier Jul 27 05:26:52 hanvolumio dhcpcd[593]: forked to background, child pid 679 Jul 27 05:26:52 hanvolumio dhcpcd[593]: forked to background, child pid 679 Jul 27 05:26:52 hanvolumio dbus-daemon[576]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 27 05:26:52 hanvolumio systemd[1]: Started Login Service. Jul 27 05:26:52 hanvolumio systemd[1]: Started triggerhappy global hotkey daemon. Jul 27 05:26:52 hanvolumio wpa_supplicant[577]: Successfully initialized wpa_supplicant Jul 27 05:26:52 hanvolumio avahi-daemon[601]: Successfully called chroot(). Jul 27 05:26:52 hanvolumio avahi-daemon[601]: Successfully dropped remaining capabilities. Jul 27 05:26:52 hanvolumio avahi-daemon[601]: Loading service file /services/volumio.service. Jul 27 05:26:52 hanvolumio systemd[1]: Started RPC bind portmap service. Jul 27 05:26:52 hanvolumio systemd[1]: Started DHCP Client Daemon. Jul 27 05:26:52 hanvolumio kernel: e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Jul 27 05:26:52 hanvolumio systemd-udevd[446]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:53 hanvolumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Jul 27 05:26:53 hanvolumio haveged[552]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Jul 27 05:26:53 hanvolumio systemd-udevd[455]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:53 hanvolumio systemd-udevd[456]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:53 hanvolumio systemd[1]: Started WPA supplicant. Jul 27 05:26:53 hanvolumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 27 05:26:53 hanvolumio systemd[1]: Started Manage Sound Card State (restore and store). Jul 27 05:26:53 hanvolumio systemd[1]: Starting Save/Restore Sound Card State... Jul 27 05:26:53 hanvolumio systemd[1]: Reached target RPC Port Mapper. Jul 27 05:26:53 hanvolumio systemd[1]: Reached target Remote File Systems (Pre). Jul 27 05:26:53 hanvolumio systemd[1]: Reached target Remote File Systems. Jul 27 05:26:53 hanvolumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 27 05:26:53 hanvolumio systemd-udevd[449]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:53 hanvolumio systemd-udevd[459]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 27 05:26:53 hanvolumio alsactl[689]: alsactl 1.1.8 daemon started Jul 27 05:26:53 hanvolumio avahi-daemon[601]: Network interface enumeration completed. Jul 27 05:26:53 hanvolumio avahi-daemon[601]: Server startup complete. Host name is hanvolumio.local. Local service cookie is 3837176366. Jul 27 05:26:53 hanvolumio avahi-daemon[601]: Service "han_Volumio" (/services/volumio.service) successfully established. Jul 27 05:26:53 hanvolumio haveged[552]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 64K (L4 V); idx: 39/40; sz: 52825/52825 Jul 27 05:26:53 hanvolumio haveged[552]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9971 Jul 27 05:26:53 hanvolumio haveged[552]: haveged: fills: 0, generated: 0 Jul 27 05:26:53 hanvolumio sh[544]: eth0: waiting for carrier Jul 27 05:26:53 hanvolumio sh[544]: eth0: carrier acquired Jul 27 05:26:53 hanvolumio dhcpcd[600]: eth0: waiting for carrier Jul 27 05:26:53 hanvolumio dhcpcd[600]: eth0: carrier acquired Jul 27 05:26:53 hanvolumio systemd[1]: iptables.service: Succeeded. Jul 27 05:26:53 hanvolumio sh[544]: DUID 00:04:c1:e7:37:75:b1:27:7c:47:9e:15:98:af:e7:2b:97:61 Jul 27 05:26:53 hanvolumio sh[544]: eth0: IAID 27:f1:fa:a3 Jul 27 05:26:53 hanvolumio dhcpcd[600]: DUID 00:04:c1:e7:37:75:b1:27:7c:47:9e:15:98:af:e7:2b:97:61 Jul 27 05:26:53 hanvolumio dhcpcd[600]: eth0: IAID 27:f1:fa:a3 Jul 27 05:26:53 hanvolumio sh[544]: eth0: adding address fe80::b1a6:16f7:4ac4:8bc6 Jul 27 05:26:53 hanvolumio sh[544]: ipv6_addaddr1: Permission denied Jul 27 05:26:53 hanvolumio dhcpcd[600]: eth0: adding address fe80::b1a6:16f7:4ac4:8bc6 Jul 27 05:26:53 hanvolumio dhcpcd[600]: ipv6_addaddr1: Permission denied Jul 27 05:26:53 hanvolumio dhcpcd[679]: eth0: waiting for carrier Jul 27 05:26:53 hanvolumio dhcpcd[679]: eth0: carrier acquired Jul 27 05:26:53 hanvolumio dhcpcd[679]: DUID 00:04:c1:e7:37:75:b1:27:7c:47:9e:15:98:af:e7:2b:97:61 Jul 27 05:26:53 hanvolumio dhcpcd[679]: eth0: IAID 27:f1:fa:a3 Jul 27 05:26:53 hanvolumio dhcpcd[679]: eth0: adding address fe80::b1a6:16f7:4ac4:8bc6 Jul 27 05:26:53 hanvolumio dhcpcd[679]: ipv6_addaddr1: Permission denied Jul 27 05:26:53 hanvolumio systemd[1]: Started Raise network interfaces. Jul 27 05:26:53 hanvolumio systemd[1]: Reached target Network. Jul 27 05:26:53 hanvolumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 27 05:26:53 hanvolumio systemd[1]: Starting Network Time Service... Jul 27 05:26:53 hanvolumio systemd[1]: Starting Permit User Sessions... Jul 27 05:26:53 hanvolumio systemd[1]: Reached target Network is Online. Jul 27 05:26:53 hanvolumio systemd[1]: Starting /etc/rc.local Compatibility... Jul 27 05:26:53 hanvolumio systemd[1]: Starting Samba NMB Daemon... Jul 27 05:26:53 hanvolumio systemd[1]: Starting LSB: Brings up/down network automatically... Jul 27 05:26:53 hanvolumio systemd[1]: Started /etc/rc.local Compatibility. Jul 27 05:26:53 hanvolumio volumio-remote-updater[598]: Error: No active session Jul 27 05:26:53 hanvolumio volumio-remote-updater[598]: [2024-07-27 05:26:53] [info] asio async_connect error: system:111 (Connection refused) Jul 27 05:26:53 hanvolumio volumio-remote-updater[598]: [2024-07-27 05:26:53] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 27 05:26:53 hanvolumio volumio-remote-updater[598]: [2024-07-27 05:26:53] [error] handle_connect error: Underlying Transport Error Jul 27 05:26:53 hanvolumio systemd[1]: Started Permit User Sessions. Jul 27 05:26:53 hanvolumio systemd[1]: Starting Hold until boot process finishes up... Jul 27 05:26:53 hanvolumio dhcpcd[600]: eth0: soliciting an IPv6 router Jul 27 05:26:53 hanvolumio sh[544]: eth0: soliciting an IPv6 router Jul 27 05:26:53 hanvolumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 27 05:26:53 hanvolumio systemd[1]: Received SIGRTMIN+21 from PID 303 (n/a). Jul 27 05:26:53 hanvolumio systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 27 05:26:53 hanvolumio systemd[1]: Started Hold until boot process finishes up. Jul 27 05:26:53 hanvolumio systemd[1]: Started Getty on tty1. Jul 27 05:26:53 hanvolumio systemd[1]: Reached target Login Prompts. Jul 27 05:26:53 hanvolumio systemd[1]: plymouth-quit.service: Succeeded. Jul 27 05:26:53 hanvolumio systemd[1]: Started Terminate Plymouth Boot Screen. Jul 27 05:26:53 hanvolumio volumio[584]: Could not open config: /tmp/upmpdcli.conf Jul 27 05:26:53 hanvolumio ifplugd(eth0)[769]: ifplugd 0.28 initializing. Jul 27 05:26:53 hanvolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 27 05:26:53 hanvolumio ifplugd(eth0)[769]: Using interface eth0/08:00:27:F1:FA:A3 with driver (version: 6.6.31-volumio) Jul 27 05:26:53 hanvolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 27 05:26:53 hanvolumio ifplugd(eth0)[769]: Using detection mode: SIOCETHTOOL Jul 27 05:26:53 hanvolumio ifplugd(eth0)[769]: Initialization complete, link beat detected. Jul 27 05:26:53 hanvolumio loadcpufreq[694]: Loading cpufreq kernel modules...failed. Jul 27 05:26:53 hanvolumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 27 05:26:53 hanvolumio ntpd[722]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 27 05:26:53 hanvolumio ntpd[722]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 27 05:26:53 hanvolumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 27 05:26:53 hanvolumio systemd[1]: Started Network Time Service. Jul 27 05:26:53 hanvolumio ntpd[777]: proto: precision = 0.037 usec (-25) Jul 27 05:26:53 hanvolumio ntpd[777]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 27 05:26:53 hanvolumio ntpd[777]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 27 05:26:53 hanvolumio ntpd[777]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 578 days ago Jul 27 05:26:53 hanvolumio ifplugd(eth0)[769]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 27 05:26:53 hanvolumio cpufrequtils[776]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Jul 27 05:26:53 hanvolumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 27 05:26:53 hanvolumio ntpd[777]: Listen and drop on 0 v6wildcard [::]:123 Jul 27 05:26:53 hanvolumio ntpd[777]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 27 05:26:53 hanvolumio ntpd[777]: Listen normally on 2 lo 127.0.0.1:123 Jul 27 05:26:53 hanvolumio ntpd[777]: Listening on routing socket on fd #19 for interface updates Jul 27 05:26:53 hanvolumio ntpd[777]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 27 05:26:53 hanvolumio ntpd[777]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 27 05:26:53 hanvolumio ifplugd(eth0)[769]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jul 27 05:26:53 hanvolumio kernel: alsactl[675]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jul 27 05:26:53 hanvolumio nmbd[730]: [2024/07/27 05:26:53.816296, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 27 05:26:53 hanvolumio nmbd[730]: started asyncdns process 796 Jul 27 05:26:53 hanvolumio nmbd[730]: [2024/07/27 05:26:53.818224, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 27 05:26:53 hanvolumio nmbd[730]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 27 05:26:53 hanvolumio nmbd[730]: [2024/07/27 05:26:53.818306, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 27 05:26:53 hanvolumio nmbd[730]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 27 05:26:53 hanvolumio sh[544]: eth0: rebinding lease of 192.168.99.174 Jul 27 05:26:53 hanvolumio dhcpcd[600]: eth0: rebinding lease of 192.168.99.174 Jul 27 05:26:53 hanvolumio sh[544]: eth0: probing address 192.168.99.174/24 Jul 27 05:26:53 hanvolumio dhcpcd[600]: eth0: probing address 192.168.99.174/24 Jul 27 05:26:53 hanvolumio dhcpcd[679]: eth0: soliciting an IPv6 router Jul 27 05:26:53 hanvolumio wireless.js[599]: WIRELESS: No wireless interface, exiting Jul 27 05:26:53 hanvolumio dhcpcd[679]: eth0: rebinding lease of 192.168.99.174 Jul 27 05:26:53 hanvolumio iw[801]: command failed: No such device (-19) Jul 27 05:26:53 hanvolumio iwconfig[804]: Error for wireless request "Set Power Management" (8B2C) : Jul 27 05:26:53 hanvolumio iwconfig[804]: SET failed on device wlan0 ; No such device. Jul 27 05:26:53 hanvolumio systemd[1]: wireless.service: Succeeded. Jul 27 05:26:53 hanvolumio systemd[1]: Started Wireless Services. Jul 27 05:26:53 hanvolumio systemd[1]: Started Volumio Backend Module. Jul 27 05:26:53 hanvolumio systemd[1]: Started Intel SST and HDA soundcard init service. Jul 27 05:26:53 hanvolumio systemd[1]: Started Volumio Cpu Tweaker. Jul 27 05:26:53 hanvolumio systemd[1]: Started Start Volumio Kiosk. Jul 27 05:26:54 hanvolumio dhcpcd[679]: eth0: probing address 192.168.99.174/24 Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: Setting RT Priority for mpd Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: pid 35's current scheduling policy: SCHED_OTHER Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: pid 35's current scheduling priority: 0 Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: Not enough cores to set MPD affinity Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: Error setting new values. Common errors: Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: - Do you have proper administration rights? (super-user?) Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: - Is the governor you requested available and modprobed? Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: - Trying to set an invalid policy? Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: - Trying to set a specific frequency, but userspace governor is not available, Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: for example because of hardware which cannot be set to a specific frequency Jul 27 05:26:54 hanvolumio volumio-cpu-tweak[809]: or because the userspace governor isn't loaded? Jul 27 05:26:54 hanvolumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 27 05:26:54 hanvolumio startx[810]: hostname: System error Jul 27 05:26:54 hanvolumio systemd[1]: Started Save/Restore Sound Card State. Jul 27 05:26:54 hanvolumio systemd[1]: Reached target Sound Card. Jul 27 05:26:54 hanvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 27 05:26:54 hanvolumio startx[810]: xauth: (stdin):1: bad display name "hanvolumio:0" in "add" command Jul 27 05:26:54 hanvolumio ntpd[777]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 27 05:26:54 hanvolumio startx[810]: X.Org X Server 1.20.4 Jul 27 05:26:54 hanvolumio startx[810]: X Protocol Version 11, Revision 0 Jul 27 05:26:54 hanvolumio startx[810]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jul 27 05:26:54 hanvolumio startx[810]: Current Operating System: Linux hanvolumio 6.6.31-volumio #2 SMP PREEMPT_DYNAMIC Fri May 17 18:26:28 CEST 2024 x86_64 Jul 27 05:26:54 hanvolumio startx[810]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=668afdde-eb8b-48b3-b8ac-1f0a643965c0 bootpart=UUID=9561-A5F0 datapart=UUID=0f08d23f-00cb-42b9-914c-9463e5fce58b 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 Jul 27 05:26:54 hanvolumio startx[810]: Build Date: 18 December 2021 09:05:36AM Jul 27 05:26:54 hanvolumio startx[810]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jul 27 05:26:54 hanvolumio startx[810]: Current version of pixman: 0.36.0 Jul 27 05:26:54 hanvolumio startx[810]: Before reporting problems, check http://wiki.x.org Jul 27 05:26:54 hanvolumio startx[810]: to make sure that you have the latest version. Jul 27 05:26:54 hanvolumio startx[810]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 27 05:26:54 hanvolumio startx[810]: (++) from command line, (!!) notice, (II) informational, Jul 27 05:26:54 hanvolumio startx[810]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 27 05:26:54 hanvolumio startx[810]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jul 27 05:26:54 2024 Jul 27 05:26:54 hanvolumio startx[810]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 27 05:26:55 hanvolumio systemd[1]: soundcard-init.service: Succeeded. Jul 27 05:26:55 hanvolumio ntpd[777]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 27 05:26:55 hanvolumio volumio[807]: info: ------------------------------------------- Jul 27 05:26:55 hanvolumio volumio[807]: info: ----- Volumio3 ---- Jul 27 05:26:55 hanvolumio volumio[807]: info: ------------------------------------------- Jul 27 05:26:55 hanvolumio volumio[807]: info: ----- System startup ---- Jul 27 05:26:55 hanvolumio volumio[807]: info: ------------------------------------------- Jul 27 05:26:56 hanvolumio volumio[807]: info: MYVOLUMIO Environment detected Jul 27 05:26:56 hanvolumio volumio[807]: info: Plugin folders cleanup Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning into folder /volumio/app/plugins/ Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category audio_interface Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category miscellanea Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category music_service Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category plugins.json Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category system_controller Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category user_interface Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning into folder /data/plugins/ Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category music_service Jul 27 05:26:56 hanvolumio volumio[807]: info: Scanning category user_interface Jul 27 05:26:56 hanvolumio volumio[807]: info: Plugin folders cleanup completed Jul 27 05:26:56 hanvolumio volumio[807]: info: ------------------------------------------- Jul 27 05:26:56 hanvolumio volumio[807]: info: ----- Core plugins startup ---- Jul 27 05:26:56 hanvolumio volumio[807]: info: ------------------------------------------- Jul 27 05:26:56 hanvolumio volumio[807]: info: Loading plugins from folder /volumio/app/plugins/ Jul 27 05:26:56 hanvolumio volumio[807]: info: Adding plugin upnp to MyMusic Plugins Jul 27 05:26:56 hanvolumio volumio[807]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 27 05:26:56 hanvolumio volumio[807]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 27 05:26:56 hanvolumio volumio[807]: info: Loading plugins from folder /data/plugins/ Jul 27 05:26:56 hanvolumio volumio[807]: info: Loading plugin "system"... Jul 27 05:26:56 hanvolumio volumio[807]: info: Loading plugin "appearance"... Jul 27 05:26:56 hanvolumio ntpd[777]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 27 05:26:56 hanvolumio volumio[807]: info: Loading plugin "network"... Jul 27 05:26:56 hanvolumio volumio[807]: info: Refreshing Cached IP Addresses Jul 27 05:26:56 hanvolumio volumio[807]: info: Loading plugin "services"... Jul 27 05:26:56 hanvolumio volumio[807]: info: Loading plugin "alsa_controller"... Jul 27 05:26:57 hanvolumio sudo[955]: volumio : unable to resolve host hanvolumio Jul 27 05:26:57 hanvolumio sudo[955]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 27 05:26:57 hanvolumio sudo[957]: volumio : unable to resolve host hanvolumio Jul 27 05:26:57 hanvolumio sudo[957]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 27 05:26:57 hanvolumio sudo[953]: volumio : unable to resolve host hanvolumio Jul 27 05:26:57 hanvolumio sudo[953]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 27 05:26:57 hanvolumio sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 27 05:26:57 hanvolumio sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:26:57 hanvolumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 27 05:26:57 hanvolumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:26:57 hanvolumio sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 27 05:26:57 hanvolumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:26:57 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "wizard"... Jul 27 05:26:57 hanvolumio sudo[957]: pam_unix(sudo:session): session closed for user root Jul 27 05:26:57 hanvolumio sudo[953]: pam_unix(sudo:session): session closed for user root Jul 27 05:26:57 hanvolumio sudo[955]: pam_unix(sudo:session): session closed for user root Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "networkfs"... Jul 27 05:26:57 hanvolumio volumio[807]: info: Starting Udev Watcher for removable devices Jul 27 05:26:57 hanvolumio volumio[807]: info: Ignoring mount for partition: boot Jul 27 05:26:57 hanvolumio volumio[807]: info: Ignoring mount for partition: volumio Jul 27 05:26:57 hanvolumio volumio[807]: info: Ignoring mount for partition: volumio_data Jul 27 05:26:57 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "volumio_command_line_client"... Jul 27 05:26:57 hanvolumio sudo[970]: volumio : unable to resolve host hanvolumio Jul 27 05:26:57 hanvolumio sudo[970]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 27 05:26:57 hanvolumio sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hanson,password=han30959769,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.99.193/music /mnt/NAS/music Jul 27 05:26:57 hanvolumio sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "upnp"... Jul 27 05:26:57 hanvolumio volumio[807]: info: [1722058017165] Starting Upmpd Daemon Jul 27 05:26:57 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "my_music"... Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "mpd"... Jul 27 05:26:57 hanvolumio kernel: FS-Cache: Loaded Jul 27 05:26:57 hanvolumio kernel: Key type dns_resolver registered Jul 27 05:26:57 hanvolumio kernel: Key type cifs.spnego registered Jul 27 05:26:57 hanvolumio kernel: Key type cifs.idmap registered Jul 27 05:26:57 hanvolumio sudo[970]: pam_unix(sudo:session): session closed for user root Jul 27 05:26:57 hanvolumio 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. Jul 27 05:26:57 hanvolumio kernel: CIFS: Attempting to mount //192.168.99.193/music Jul 27 05:26:57 hanvolumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 27 05:26:57 hanvolumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jul 27 05:26:57 hanvolumio ntpd[777]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "upnp_browser"... Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "alarm-clock"... Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "airplay_emulation"... Jul 27 05:26:57 hanvolumio volumio[807]: info: Starting Shairport Sync Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "last_100"... Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "webradio"... Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "i2s_dacs"... Jul 27 05:26:57 hanvolumio volumio[807]: info: I2S DAC not set, start Auto-detection Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "volumiodiscovery"... Jul 27 05:26:57 hanvolumio volumio[807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 27 05:26:57 hanvolumio node[807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 27 05:26:57 hanvolumio volumio[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 27 05:26:57 hanvolumio node[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 27 05:26:57 hanvolumio volumio[807]: *** WARNING *** For more information see Jul 27 05:26:57 hanvolumio node[807]: *** WARNING *** For more information see Jul 27 05:26:57 hanvolumio volumio[807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 27 05:26:57 hanvolumio node[807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 27 05:26:57 hanvolumio volumio[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 27 05:26:57 hanvolumio node[807]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 27 05:26:57 hanvolumio volumio[807]: *** WARNING *** For more information see Jul 27 05:26:57 hanvolumio node[807]: *** WARNING *** For more information see Jul 27 05:26:57 hanvolumio volumio[807]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 27 05:26:57 hanvolumio volumio[807]: info: Discovery: Started advertising with name: han_Volumio Jul 27 05:26:57 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 27 05:26:57 hanvolumio volumio[807]: info: Loading plugin "spop"... Jul 27 05:26:58 hanvolumio volumio-remote-updater[598]: [2024-07-27 05:26:58] [connect] Successful connection Jul 27 05:26:58 hanvolumio sh[544]: eth0: leased 192.168.99.174 for 86400 seconds Jul 27 05:26:58 hanvolumio sh[544]: eth0: adding route to 192.168.99.0/24 Jul 27 05:26:58 hanvolumio sh[544]: eth0: adding default route via 192.168.99.1 Jul 27 05:26:58 hanvolumio dhcpcd[600]: eth0: leased 192.168.99.174 for 86400 seconds Jul 27 05:26:58 hanvolumio dhcpcd[600]: eth0: adding route to 192.168.99.0/24 Jul 27 05:26:58 hanvolumio dhcpcd[600]: eth0: adding default route via 192.168.99.1 Jul 27 05:26:58 hanvolumio avahi-daemon[601]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.99.174. Jul 27 05:26:58 hanvolumio avahi-daemon[601]: New relevant interface eth0.IPv4 for mDNS. Jul 27 05:26:58 hanvolumio avahi-daemon[601]: Registering new address record for 192.168.99.174 on eth0.IPv4. Jul 27 05:26:58 hanvolumio ntpd[777]: ntpd exiting on signal 15 (Terminated) Jul 27 05:26:58 hanvolumio systemd[1]: Stopping Network Time Service... Jul 27 05:26:58 hanvolumio systemd[1]: ntp.service: Succeeded. Jul 27 05:26:58 hanvolumio systemd[1]: Stopped Network Time Service. Jul 27 05:26:58 hanvolumio systemd[1]: Starting Network Time Service... Jul 27 05:26:58 hanvolumio ntpd[1019]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 27 05:26:58 hanvolumio ntpd[1019]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 27 05:26:58 hanvolumio systemd[1]: Started Network Time Service. Jul 27 05:26:58 hanvolumio sh[544]: forked to background, child pid 1026 Jul 27 05:26:58 hanvolumio dhcpcd[600]: forked to background, child pid 1026 Jul 27 05:26:58 hanvolumio nmbd[730]: [2024/07/27 05:26:58.837155, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 27 05:26:58 hanvolumio nmbd[730]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 27 05:26:58 hanvolumio systemd[1]: Started Samba NMB Daemon. Jul 27 05:26:58 hanvolumio ntpd[1025]: proto: precision = 0.037 usec (-25) Jul 27 05:26:58 hanvolumio ntpd[1025]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 27 05:26:58 hanvolumio ntpd[1025]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 27 05:26:58 hanvolumio ntpd[1025]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 578 days ago Jul 27 05:26:58 hanvolumio ntpd[1025]: Listen and drop on 0 v6wildcard [::]:123 Jul 27 05:26:58 hanvolumio ntpd[1025]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 27 05:26:58 hanvolumio ntpd[1025]: Listen normally on 2 lo 127.0.0.1:123 Jul 27 05:26:58 hanvolumio ntpd[1025]: Listen normally on 3 eth0 192.168.99.174:123 Jul 27 05:26:58 hanvolumio ntpd[1025]: Listening on routing socket on fd #20 for interface updates Jul 27 05:26:58 hanvolumio ntpd[1025]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 27 05:26:58 hanvolumio ntpd[1025]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 27 05:26:58 hanvolumio dhcpcd[679]: eth0: leased 192.168.99.174 for 86400 seconds Jul 27 05:26:58 hanvolumio dhcpcd[679]: eth0: adding route to 192.168.99.0/24 Jul 27 05:26:58 hanvolumio dhcpcd[679]: eth0: adding default route via 192.168.99.1 Jul 27 05:26:58 hanvolumio systemd[1]: Starting Samba Winbind Daemon... Jul 27 05:26:59 hanvolumio sh[544]: eth0=eth0 Jul 27 05:26:59 hanvolumio ifplugd(eth0)[769]: client: ifup: interface eth0 already configured Jul 27 05:26:59 hanvolumio ifplugd(eth0)[769]: Program executed successfully. Jul 27 05:26:59 hanvolumio ifplugd[729]: Network Interface Plugging Daemon...start eth0...done. Jul 27 05:26:59 hanvolumio systemd[1]: Started LSB: Brings up/down network automatically. Jul 27 05:26:59 hanvolumio systemd[1]: Stopping Network Time Service... Jul 27 05:26:59 hanvolumio ntpd[1025]: ntpd exiting on signal 15 (Terminated) Jul 27 05:26:59 hanvolumio systemd[1]: ntp.service: Succeeded. Jul 27 05:26:59 hanvolumio systemd[1]: Stopped Network Time Service. Jul 27 05:26:59 hanvolumio systemd[1]: Starting Network Time Service... Jul 27 05:26:59 hanvolumio ntpd[1088]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 27 05:26:59 hanvolumio ntpd[1088]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 27 05:26:59 hanvolumio systemd[1]: Started Network Time Service. Jul 27 05:26:59 hanvolumio winbindd[1029]: [2024/07/27 05:26:59.244340, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 27 05:26:59 hanvolumio ntpd[1095]: proto: precision = 0.038 usec (-25) Jul 27 05:26:59 hanvolumio winbindd[1029]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 27 05:26:59 hanvolumio ntpd[1095]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 27 05:26:59 hanvolumio ntpd[1095]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 27 05:26:59 hanvolumio ntpd[1095]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 578 days ago Jul 27 05:26:59 hanvolumio ntpd[1095]: Listen and drop on 0 v6wildcard [::]:123 Jul 27 05:26:59 hanvolumio ntpd[1095]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 27 05:26:59 hanvolumio ntpd[1095]: Listen normally on 2 lo 127.0.0.1:123 Jul 27 05:26:59 hanvolumio ntpd[1095]: Listen normally on 3 eth0 192.168.99.174:123 Jul 27 05:26:59 hanvolumio ntpd[1095]: Listening on routing socket on fd #20 for interface updates Jul 27 05:26:59 hanvolumio ntpd[1095]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 27 05:26:59 hanvolumio ntpd[1095]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 27 05:26:59 hanvolumio winbindd[1029]: [2024/07/27 05:26:59.260843, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 27 05:26:59 hanvolumio winbindd[1029]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 27 05:26:59 hanvolumio systemd[1]: Started Samba Winbind Daemon. Jul 27 05:26:59 hanvolumio systemd[1]: Starting Samba SMB Daemon... Jul 27 05:26:59 hanvolumio volumio[807]: info: Loading plugin "ytmusic"... Jul 27 05:26:59 hanvolumio smbd[1099]: [2024/07/27 05:26:59.856636, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 27 05:26:59 hanvolumio smbd[1099]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 27 05:26:59 hanvolumio systemd[1]: Started Samba SMB Daemon. Jul 27 05:26:59 hanvolumio systemd[1]: Reached target Multi-User System. Jul 27 05:26:59 hanvolumio systemd[1]: Reached target Graphical Interface. Jul 27 05:26:59 hanvolumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 27 05:26:59 hanvolumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 27 05:26:59 hanvolumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 27 05:26:59 hanvolumio systemd[1]: Startup finished in 7.669s (kernel) + 9.279s (userspace) = 16.949s. Jul 27 05:27:00 hanvolumio ntpd[1095]: Soliciting pool server 220.135.175.159 Jul 27 05:27:00 hanvolumio volumio[807]: info: Loading plugin "now_playing"... Jul 27 05:27:01 hanvolumio ntpd[1095]: Soliciting pool server 118.163.81.63 Jul 27 05:27:01 hanvolumio ntpd[1095]: Soliciting pool server 118.163.81.61 Jul 27 05:27:01 hanvolumio volumio[807]: info: Loading plugin "outputs"... Jul 27 05:27:01 hanvolumio volumio[807]: info: Loading plugin "albumart"... Jul 27 05:27:01 hanvolumio volumio[807]: info: Plugin example_plugin is not enabled Jul 27 05:27:02 hanvolumio volumio[807]: info: Loading plugin "inputs"... Jul 27 05:27:02 hanvolumio volumio[807]: info: Loading plugin "updater_comm"... Jul 27 05:27:02 hanvolumio ntpd[1095]: Soliciting pool server 125.229.106.76 Jul 27 05:27:02 hanvolumio ntpd[1095]: Soliciting pool server 103.159.118.4 Jul 27 05:27:02 hanvolumio ntpd[1095]: Soliciting pool server 114.33.15.129 Jul 27 05:27:02 hanvolumio volumio[807]: Forking 1 albumart workers Jul 27 05:27:02 hanvolumio volumio[807]: info: Plugin mpdemulation is not enabled Jul 27 05:27:02 hanvolumio volumio[807]: info: Loading plugin "rest_api"... Jul 27 05:27:02 hanvolumio volumio[807]: info: Loading plugin "websocket"... Jul 27 05:27:02 hanvolumio volumio[807]: info: Starting Socket.io Server version 2.3.0 Jul 27 05:27:02 hanvolumio volumio[807]: info: Loading i18n strings for locale zh_TW Jul 27 05:27:02 hanvolumio volumio[807]: Updating browse sources language Jul 27 05:27:02 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 27 05:27:02 hanvolumio volumio[807]: Starting albumart workers Jul 27 05:27:03 hanvolumio ntpd[1095]: Soliciting pool server 118.163.74.161 Jul 27 05:27:03 hanvolumio ntpd[1095]: Soliciting pool server 183.177.72.202 Jul 27 05:27:03 hanvolumio ntpd[1095]: Soliciting pool server 122.117.253.246 Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::initPlayerControls Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: Express server listening on port 3000 Jul 27 05:27:03 hanvolumio volumio[807]: [Metrics] WebUI: 8s 45.88ms Jul 27 05:27:03 hanvolumio volumio[807]: info: Setting Device type: x86 Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreStateMachine::resetVolumioState Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreStateMachine::getcurrentVolume Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 27 05:27:03 hanvolumio volumio[807]: info: Completed loading Core Plugins Jul 27 05:27:03 hanvolumio volumio[807]: info: Preparing to generate the ALSA configuration file Jul 27 05:27:03 hanvolumio ntpd[1095]: Soliciting pool server 123.204.232.128 Jul 27 05:27:03 hanvolumio volumio[807]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Jul 27 05:27:03 hanvolumio volumio[807]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 27 05:27:03 hanvolumio volumio[807]: sudo: unable to resolve host hanvolumio: System error Jul 27 05:27:03 hanvolumio volumio[807]: wlan0 Interface doesn't support scanning. Jul 27 05:27:03 hanvolumio volumio[807]: info: Cannot use regular scanning, forcing with ap-force Jul 27 05:27:03 hanvolumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 27 05:27:03 hanvolumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:03 hanvolumio sudo[1193]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:03 hanvolumio volumio[807]: command failed: No such device (-19) Jul 27 05:27:03 hanvolumio volumio[807]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jul 27 05:27:03 hanvolumio volumio[807]: command failed: No such device (-19) Jul 27 05:27:03 hanvolumio volumio[807]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 27 05:27:03 hanvolumio volumio[807]: info: Reloading queue from file Jul 27 05:27:03 hanvolumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreStateMachine::pushState Jul 27 05:27:03 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::volumioPushState Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreStateMachine::updateTrackBlock Jul 27 05:27:03 hanvolumio volumio[807]: info: CorePlayQueue::getTrackBlock Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Jul 27 05:27:03 hanvolumio volumio[807]: info: Asound.conf file unchanged, so no further update is needed Jul 27 05:27:03 hanvolumio volumio[807]: info: Output device has changed, restarting MPD Jul 27 05:27:03 hanvolumio volumio[807]: info: Output device has changed, restarting Shairport Sync Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:03 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 27 05:27:03 hanvolumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 27 05:27:04 hanvolumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 27 05:27:04 hanvolumio sudo[1205]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:04 hanvolumio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio volumio[807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 27 05:27:04 hanvolumio volumio[807]: info: ___________ START PLUGINS ___________ Jul 27 05:27:04 hanvolumio volumio[807]: info: ControllerMpd::onStart: Initializing MPD Jul 27 05:27:04 hanvolumio volumio[807]: info: Creating MPD Configuration file Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 27 05:27:04 hanvolumio volumio[807]: info: [1722058024071] CoreMusicLibrary::Adding element Media Servers Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 27 05:27:04 hanvolumio systemd[1]: Listening on mpd.socket. Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:04 hanvolumio systemd[1]: Starting Music Player Daemon... Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 27 05:27:04 hanvolumio sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 27 05:27:04 hanvolumio sudo[1215]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 27 05:27:04 hanvolumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio sudo[1215]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 27 05:27:04 hanvolumio sudo[1215]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:04 hanvolumio sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 27 05:27:04 hanvolumio sudo[1214]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:04 hanvolumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio ntpd[1095]: Soliciting pool server 210.243.152.152 Jul 27 05:27:04 hanvolumio ntpd[1095]: Soliciting pool server 114.34.171.136 Jul 27 05:27:04 hanvolumio ntpd[1095]: Soliciting pool server 183.177.72.201 Jul 27 05:27:04 hanvolumio volumio[807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 27 05:27:04 hanvolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 27 05:27:04 hanvolumio systemd[1]: mpd.service: Succeeded. Jul 27 05:27:04 hanvolumio systemd[1]: Stopped Music Player Daemon. Jul 27 05:27:04 hanvolumio systemd[1]: Starting Music Player Daemon... Jul 27 05:27:04 hanvolumio volumio[807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 27 05:27:04 hanvolumio volumio[807]: info: [1722058024345] CoreMusicLibrary::Adding element Last_100 Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 27 05:27:04 hanvolumio volumio[807]: info: [1722058024346] CoreMusicLibrary::Adding element Webradio Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 27 05:27:04 hanvolumio volumio[807]: info: Initializing BBC Radios Jul 27 05:27:04 hanvolumio sudo[1225]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 27 05:27:04 hanvolumio sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio sudo[1225]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 27 05:27:04 hanvolumio sudo[1225]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 27 05:27:04 hanvolumio volumio[807]: info: Creating Spotify config file Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 27 05:27:04 hanvolumio volumio[807]: info: [1722058024510] CoreMusicLibrary::Adding element YouTube Music Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 27 05:27:04 hanvolumio volumio[807]: Cannot find translation for source YouTube Music Jul 27 05:27:04 hanvolumio volumio[807]: info: [now-playing-config] Config is up to date. Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 27 05:27:04 hanvolumio volumio[807]: info: Discovery: Getting this device information Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:04 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 27 05:27:04 hanvolumio volumio[807]: info: Volumio Calling Home Jul 27 05:27:04 hanvolumio volumio[807]: info: [now-playing-app] App is listening on port 4004. Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreStateMachine::setRepeat false single undefined Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreStateMachine::pushState Jul 27 05:27:04 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioPushState Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreStateMachine::setRandom true Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreStateMachine::pushState Jul 27 05:27:04 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioPushState Jul 27 05:27:04 hanvolumio volumio[807]: info: MPD Permissions set Jul 27 05:27:04 hanvolumio volumio[807]: info: Spotify config file written Jul 27 05:27:04 hanvolumio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 27 05:27:04 hanvolumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio sudo[1247]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:04 hanvolumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 27 05:27:04 hanvolumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio volumio[807]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 27 05:27:04 hanvolumio volumio[807]: info: Discovery: adding a6ef1aa9-91b2-4018-b9f4-9e5d420aee05 Jul 27 05:27:04 hanvolumio volumio[807]: info: Discovery: Found device han_Volumio Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:04 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:04 hanvolumio sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:04 hanvolumio sudo[1248]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:04 hanvolumio sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:04 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:05 hanvolumio systemd[1]: Started go-librespot Daemon. Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:05 hanvolumio go-librespot[1262]: Librespot-go daemon starting... Jul 27 05:27:05 hanvolumio sudo[1251]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 27 05:27:05 hanvolumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Jul 27 05:27:05 hanvolumio ntpd[1095]: Soliciting pool server 114.35.131.27 Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreStateMachine::pushState Jul 27 05:27:05 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::volumioPushState Jul 27 05:27:05 hanvolumio ntpd[1095]: Soliciting pool server 125.229.162.223 Jul 27 05:27:05 hanvolumio volumio[807]: info: Volumio called home Jul 27 05:27:05 hanvolumio volumio[807]: info: Starting Shairport Sync Jul 27 05:27:05 hanvolumio volumio[807]: info: Starting Shairport Sync Jul 27 05:27:05 hanvolumio volumio[807]: info: Starting Shairport Sync Jul 27 05:27:05 hanvolumio volumio[807]: verbose: New Socket.io Connection to 192.168.99.174 from 192.168.99.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jul 27 05:27:05 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:05Z" level=info msg="generated new device id: 4475cc0fe5003d8593a2384f44de3056839e6a0f" Jul 27 05:27:05 hanvolumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:05 hanvolumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 27 05:27:05 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:05Z" level=debug msg="stored credentials found for 31ugyez5ziuof5vbs5ax2jjdb4ce" Jul 27 05:27:05 hanvolumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:05 hanvolumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:05 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 27 05:27:05 hanvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 27 05:27:05 hanvolumio systemd[1]: shairport-sync.service: Succeeded. Jul 27 05:27:05 hanvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 27 05:27:05 hanvolumio sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 27 05:27:05 hanvolumio sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:05 hanvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 27 05:27:05 hanvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 27 05:27:05 hanvolumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 27 05:27:05 hanvolumio systemd[1]: shairport-sync.service: Succeeded. Jul 27 05:27:05 hanvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 27 05:27:05 hanvolumio sudo[1274]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:05 hanvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 27 05:27:05 hanvolumio sudo[1277]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:05 hanvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 27 05:27:05 hanvolumio systemd[1]: shairport-sync.service: Succeeded. Jul 27 05:27:05 hanvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 27 05:27:05 hanvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 27 05:27:05 hanvolumio sudo[1278]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetVisibleSources Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:05 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreStateMachine::getQueue Jul 27 05:27:05 hanvolumio volumio[807]: info: CorePlayQueue::getQueue Jul 27 05:27:05 hanvolumio volumio[807]: info: Listing playlists Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 27 05:27:05 hanvolumio volumio[807]: info: Received Get System Info Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 27 05:27:05 hanvolumio volumio[807]: info: Discovery: Getting this device information Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:05 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:05 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 27 05:27:05 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 27 05:27:05 hanvolumio volumio[807]: info: Shairport-Sync Started Jul 27 05:27:05 hanvolumio volumio[807]: Error adding Membership: Error: addMembership EINVAL Jul 27 05:27:05 hanvolumio volumio[807]: info: Shairport-Sync Started Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="obtained new client token: AAAmLX65W6g0RxR9SQidjxGDlK9juS2fRxRYhtSDyOQTN2RdTzOG3ganU+QIlNfIHYGv+IlsbqYcAM0XFnoRIDrsFLpfq1m38CbgYsZz6gXjRliHPYi6RBg1HSQKBWf3F9IVqZ+2y6Q8S9NJMncinL2nbP2+cQJtKGV4w2tfYwni17tLVdVe9dKKEtVle1EUFtyXAmoIblp7jjoDImHKRJsvC1CK6HeWcmKuS3wEJhjJOsm1YbAaZ2RX4eNyMw==" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="completed keyexchange" Jul 27 05:27:06 hanvolumio ntpd[1095]: Soliciting pool server 112.104.189.124 Jul 27 05:27:06 hanvolumio ntpd[1095]: Soliciting pool server 2405:a640::149 Jul 27 05:27:06 hanvolumio mpd[1232]: Jul 27 05:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 27 05:27:06 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 27 05:27:06 hanvolumio volumio[807]: info: Received Get System Info Jul 27 05:27:06 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 27 05:27:06 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 27 05:27:06 hanvolumio volumio[807]: info: Discovery: Getting this device information Jul 27 05:27:06 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:06 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:06 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="completed challenge" Jul 27 05:27:06 hanvolumio systemd[1]: Started Music Player Daemon. Jul 27 05:27:06 hanvolumio sudo[1207]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:06 hanvolumio sudo[1216]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:06 hanvolumio volumio[807]: info: Completed starting Core Plugins Jul 27 05:27:06 hanvolumio volumio[807]: info: ------------------------------------------- Jul 27 05:27:06 hanvolumio volumio[807]: info: ----- MyVolumio plugins startup ---- Jul 27 05:27:06 hanvolumio volumio[807]: info: ------------------------------------------- Jul 27 05:27:06 hanvolumio volumio[807]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="authenticated as 31ugyez5ziuof5vbs5ax2jjdb4ce" Jul 27 05:27:06 hanvolumio volumio[807]: error: MPD error: The expression evaluated to a falsy value: Jul 27 05:27:06 hanvolumio volumio[807]: assert.ok(self.idling) Jul 27 05:27:06 hanvolumio volumio[807]: error: The expression evaluated to a falsy value: Jul 27 05:27:06 hanvolumio volumio[807]: assert.ok(self.idling) Jul 27 05:27:06 hanvolumio volumio[807]: info: MPD running with PID1232 Jul 27 05:27:06 hanvolumio volumio[807]: ,establishing connection Jul 27 05:27:06 hanvolumio volumio[807]: error: MPD error: The expression evaluated to a falsy value: Jul 27 05:27:06 hanvolumio volumio[807]: assert.ok(self.idling) Jul 27 05:27:06 hanvolumio volumio[807]: error: The expression evaluated to a falsy value: Jul 27 05:27:06 hanvolumio volumio[807]: assert.ok(self.idling) Jul 27 05:27:06 hanvolumio volumio[807]: error: updateQueue error: null Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="authenticated as 31ugyez5ziuof5vbs5ax2jjdb4ce" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="fetched new dealers: [gae2-dealer2.spotify.com:443 guc3-dealer2.spotify.com:443 gue1-dealer2.spotify.com:443 gew1-dealer2.spotify.com:443]" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="dealer connection opened" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="received connection id: YmY5ZjE3ZDYtMWUzYi00MTAyLWJkMGUtNWVmZWRkMWI0Yjc0K2RlYWxlcit0Y3A6Ly8wYTYwNDI4Mi5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArNzk4RkMyMTU1RUNFQkY3RUMzODM1NkNBREYxMzc0RDg2MkI4MUZGNzg4RjgyM0FENjRDNkVGNkU5MkRFRkI1Qg==" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 27 05:27:06 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:06Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 27 05:27:07 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:07Z" level=debug msg="put connect state because NEW_DEVICE" Jul 27 05:27:07 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:07Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 27 05:27:07 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:07Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 27 05:27:07 hanvolumio go-librespot[1262]: time="2024-07-27T05:27:07Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 27 05:27:07 hanvolumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 27 05:27:07 hanvolumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:07 hanvolumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 27 05:27:07 hanvolumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:07 hanvolumio sudo[1300]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:07 hanvolumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 27 05:27:07 hanvolumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 27 05:27:07 hanvolumio sudo[1299]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:07 hanvolumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 27 05:27:07 hanvolumio sudo[1302]: pam_unix(sudo:session): session closed for user root Jul 27 05:27:07 hanvolumio volumio[807]: info: Upmpdcli Daemon Started Jul 27 05:27:07 hanvolumio volumio[1308]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 27 05:27:07 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 27 05:27:07 hanvolumio volumio[807]: info: Received Get System Info Jul 27 05:27:07 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 27 05:27:07 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 27 05:27:07 hanvolumio volumio[807]: info: Discovery: Getting this device information Jul 27 05:27:07 hanvolumio volumio[807]: info: CoreCommandRouter::volumioGetState Jul 27 05:27:07 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 0 Jul 27 05:27:07 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 27 05:27:07 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 27 05:27:07 hanvolumio volumio[1308]: ..........................++++ Jul 27 05:27:08 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 27 05:27:08 hanvolumio volumio[807]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 27 05:27:08 hanvolumio volumio[807]: SPOTIFY: BQB-WpmyIF8iye63Dcavz-vxEY8rtyuqIteLWp3VRN1y7oyxmmWe-RmCx_3CD2kTzeqiqMUxGn-hGbtw9ivNqB-Qw5WbrNbnNsjDPsMIshNuastffWuDBZ9phjYBWcdtov1uNXEg0XrKTGRdmiHoTXtZKu66zEPp1Y3PLvflUcOaS1kQzLqgH49Jxrw0jHugUhqq9rhRooNrrEKb6N6Kl-fWcxx-mLyoQTHtRzvHx9KQ7ba7Xx2aEAt6En2o9v2oSF-zw7t74LHgJelc Jul 27 05:27:08 hanvolumio volumio[807]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 27 05:27:08 hanvolumio volumio[807]: info: New Spotify access token = BQB-WpmyIF8iye63Dcavz-vxEY8rtyuqIteLWp3VRN1y7oyxmmWe-RmCx_3CD2kTzeqiqMUxGn-hGbtw9ivNqB-Qw5WbrNbnNsjDPsMIshNuastffWuDBZ9phjYBWcdtov1uNXEg0XrKTGRdmiHoTXtZKu66zEPp1Y3PLvflUcOaS1kQzLqgH49Jxrw0jHugUhqq9rhRooNrrEKb6N6Kl-fWcxx-mLyoQTHtRzvHx9KQ7ba7Xx2aEAt6En2o9v2oSF-zw7t74LHgJelc Jul 27 05:27:08 hanvolumio volumio[807]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 27 05:27:08 hanvolumio volumio[1308]: ...........................................................++++ Jul 27 05:27:08 hanvolumio volumio[1308]: e is 65537 (0x010001) Jul 27 05:27:08 hanvolumio volumio[1308]: writing RSA key Jul 27 05:27:08 hanvolumio volumio[807]: SPOTIFY: User informations: {"display_name":"han30959769","external_urls":{"spotify":"https://open.spotify.com/user/31ugyez5ziuof5vbs5ax2jjdb4ce"},"href":"https://api.spotify.com/v1/users/31ugyez5ziuof5vbs5ax2jjdb4ce","id":"31ugyez5ziuof5vbs5ax2jjdb4ce","images":[],"type":"user","uri":"spotify:user:31ugyez5ziuof5vbs5ax2jjdb4ce","followers":{"href":null,"total":0},"country":"TW","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"hanson30959769@gmail.com"} Jul 27 05:27:08 hanvolumio volumio[807]: info: Spotify Successfully logged in Jul 27 05:27:08 hanvolumio volumio[807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 27 00:06:52 hanvolumio volumio[807]: info: Enabling plugin now_playing Jul 27 00:06:52 hanvolumio volumio[807]: info: Loading plugin "now_playing"... Jul 27 00:06:52 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:52 hanvolumio volumio[807]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:52 hanvolumio volumio[807]: error: Stack trace: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:52 hanvolumio volumio[807]: at tryPackage (internal/modules/cjs/loader.js:349:19) Jul 27 00:06:52 hanvolumio volumio[807]: at Function.Module._findPath (internal/modules/cjs/loader.js:562:18) Jul 27 00:06:52 hanvolumio volumio[807]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:917:27) Jul 27 00:06:52 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Jul 27 00:06:52 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:52 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:52 hanvolumio volumio[807]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Jul 27 00:06:52 hanvolumio volumio[807]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Jul 27 00:06:52 hanvolumio volumio[807]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Jul 27 00:06:52 hanvolumio volumio[807]: at Module.load (internal/modules/cjs/loader.js:979:32) Jul 27 00:06:52 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Jul 27 00:06:52 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:52 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:52 hanvolumio volumio[807]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Jul 27 00:06:52 hanvolumio volumio[807]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 27 00:06:52 hanvolumio volumio[807]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 27 00:06:52 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:52 hanvolumio volumio[807]: info: Done. Jul 27 00:06:55 hanvolumio volumio[807]: info: Enabling plugin now_playing Jul 27 00:06:55 hanvolumio volumio[807]: info: Loading plugin "now_playing"... Jul 27 00:06:55 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:55 hanvolumio volumio[807]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:55 hanvolumio volumio[807]: error: Stack trace: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:55 hanvolumio volumio[807]: at tryPackage (internal/modules/cjs/loader.js:349:19) Jul 27 00:06:55 hanvolumio volumio[807]: at Function.Module._findPath (internal/modules/cjs/loader.js:562:18) Jul 27 00:06:55 hanvolumio volumio[807]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:917:27) Jul 27 00:06:55 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Jul 27 00:06:55 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:55 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:55 hanvolumio volumio[807]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Jul 27 00:06:55 hanvolumio volumio[807]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Jul 27 00:06:55 hanvolumio volumio[807]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Jul 27 00:06:55 hanvolumio volumio[807]: at Module.load (internal/modules/cjs/loader.js:979:32) Jul 27 00:06:55 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Jul 27 00:06:55 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:55 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:55 hanvolumio volumio[807]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Jul 27 00:06:55 hanvolumio volumio[807]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 27 00:06:55 hanvolumio volumio[807]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 27 00:06:55 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:55 hanvolumio volumio[807]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Jul 27 00:06:56 hanvolumio volumio[807]: info: Enabling plugin now_playing Jul 27 00:06:56 hanvolumio volumio[807]: info: Loading plugin "now_playing"... Jul 27 00:06:56 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:56 hanvolumio volumio[807]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:56 hanvolumio volumio[807]: error: Stack trace: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:56 hanvolumio volumio[807]: at tryPackage (internal/modules/cjs/loader.js:349:19) Jul 27 00:06:56 hanvolumio volumio[807]: at Function.Module._findPath (internal/modules/cjs/loader.js:562:18) Jul 27 00:06:56 hanvolumio volumio[807]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:917:27) Jul 27 00:06:56 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Jul 27 00:06:56 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:56 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:56 hanvolumio volumio[807]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Jul 27 00:06:56 hanvolumio volumio[807]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Jul 27 00:06:56 hanvolumio volumio[807]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Jul 27 00:06:56 hanvolumio volumio[807]: at Module.load (internal/modules/cjs/loader.js:979:32) Jul 27 00:06:56 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Jul 27 00:06:56 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:56 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:56 hanvolumio volumio[807]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Jul 27 00:06:56 hanvolumio volumio[807]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 27 00:06:56 hanvolumio volumio[807]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 27 00:06:56 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:56 hanvolumio volumio[807]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Jul 27 00:06:57 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 10 Jul 27 00:06:57 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 39 Jul 27 00:06:57 hanvolumio volumio[807]: info: Prefetching next song Jul 27 00:06:59 hanvolumio volumio[807]: info: Enabling plugin now_playing Jul 27 00:06:59 hanvolumio volumio[807]: info: Loading plugin "now_playing"... Jul 27 00:06:59 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:59 hanvolumio volumio[807]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:59 hanvolumio volumio[807]: error: Stack trace: Error: Cannot find module '/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/geo-tz.js'. Please verify that the package.json has a valid "main" entry Jul 27 00:06:59 hanvolumio volumio[807]: at tryPackage (internal/modules/cjs/loader.js:349:19) Jul 27 00:06:59 hanvolumio volumio[807]: at Function.Module._findPath (internal/modules/cjs/loader.js:562:18) Jul 27 00:06:59 hanvolumio volumio[807]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:917:27) Jul 27 00:06:59 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Jul 27 00:06:59 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:59 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:59 hanvolumio volumio[807]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Jul 27 00:06:59 hanvolumio volumio[807]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Jul 27 00:06:59 hanvolumio volumio[807]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Jul 27 00:06:59 hanvolumio volumio[807]: at Module.load (internal/modules/cjs/loader.js:979:32) Jul 27 00:06:59 hanvolumio volumio[807]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Jul 27 00:06:59 hanvolumio volumio[807]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jul 27 00:06:59 hanvolumio volumio[807]: at require (internal/modules/cjs/helpers.js:107:18) Jul 27 00:06:59 hanvolumio volumio[807]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Jul 27 00:06:59 hanvolumio volumio[807]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 27 00:06:59 hanvolumio volumio[807]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 27 00:06:59 hanvolumio volumio[807]: error: !!!! WARNING !!!! Jul 27 00:06:59 hanvolumio volumio[807]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=trace msg="emitting websocket event: not_playing" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="loading track spotify:track:5iJKGpnFfvbjZJeAtwXfCj (paused: true, position: 0ms)" Jul 27 00:07:01 hanvolumio volumio[807]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:5iJKGpnFfvbjZJeAtwXfCj","play_origin":"go-librespot"}} Jul 27 00:07:01 hanvolumio volumio[807]: error: Failed to decode event: not_playing Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=trace msg="emitting websocket event: will_play" Jul 27 00:07:01 hanvolumio volumio[807]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5iJKGpnFfvbjZJeAtwXfCj","play_origin":"go-librespot"}} Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:5iJKGpnFfvbjZJeAtwXfCj" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="requested aes key for file 0af6d4f4eaba026aec923781d2ea998219aeaf78, gid: 5iJKGpnFfvbjZJeAtwXfCj" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="fetched first chunk of 17, total size is 8773374 bytes" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=info msg="loaded track \"28\" (uri: spotify:track:5iJKGpnFfvbjZJeAtwXfCj, paused: true, position: 0ms, duration: 233333ms)" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="fetched chunk 1/16, size: 524288" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="fetched chunk 2/16, size: 524288" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=trace msg="emitting websocket event: metadata" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=trace msg="emitting websocket event: stopped" Jul 27 00:07:01 hanvolumio volumio[807]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5iJKGpnFfvbjZJeAtwXfCj","name":"28","artist_names":["Zach Bryan"],"album_name":"The Great American Bar Scene","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02647ad18a07e9e939e399e5a1","position":0,"duration":233333,"release_date":"year:2024 month:7 day:4","track_number":4,"disc_number":1}} Jul 27 00:07:01 hanvolumio volumio[807]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Jul 27 00:07:01 hanvolumio volumio[807]: SPOTIFY: PUSH STATE SPOTIFY Jul 27 00:07:01 hanvolumio volumio[807]: SPOTIFY: {"status":"stop","service":"spop","title":"28","artist":"Zach Bryan","album":"The Great American Bar Scene","albumart":"https://i.scdn.co/image/ab67616d00001e02647ad18a07e9e939e399e5a1","uri":"spotify:track:5iJKGpnFfvbjZJeAtwXfCj","trackType":"spotify","seek":0,"duration":233,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 27 00:07:01 hanvolumio volumio[807]: info: CoreCommandRouter::servicePushState Jul 27 00:07:01 hanvolumio volumio[807]: info: CoreStateMachine::pushState Jul 27 00:07:01 hanvolumio volumio[807]: info: CorePlayQueue::getTrack 10 Jul 27 00:07:01 hanvolumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 27 00:07:01 hanvolumio volumio[807]: info: CoreCommandRouter::volumioPushState Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="fetched chunk 3/16, size: 524288" Jul 27 00:07:01 hanvolumio volumio[807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 27 00:07:01 hanvolumio volumio[807]: TypeError: Cannot read property 'pushState' of null Jul 27 00:07:01 hanvolumio volumio[807]: at /volumio/app/index.js:325:32 Jul 27 00:07:01 hanvolumio volumio[807]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jul 27 00:07:01 hanvolumio volumio[807]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jul 27 00:07:01 hanvolumio volumio[807]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:323:13) Jul 27 00:07:01 hanvolumio volumio[807]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:527:22) Jul 27 00:07:01 hanvolumio volumio[807]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:592:10) Jul 27 00:07:01 hanvolumio volumio[807]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:413:28) Jul 27 00:07:01 hanvolumio volumio[807]: at ControllerSpotify.pushState (/data/plugins/music_service/spop/index.js:429:31) Jul 27 00:07:01 hanvolumio volumio[807]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:319:14) Jul 27 00:07:01 hanvolumio volumio[807]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jul 27 00:07:01 hanvolumio volumio[807]: at WebSocket.emit (events.js:400:28) Jul 27 00:07:01 hanvolumio volumio[807]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1209:20) Jul 27 00:07:01 hanvolumio volumio[807]: at Receiver.emit (events.js:400:28) Jul 27 00:07:01 hanvolumio volumio[807]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:594:14) Jul 27 00:07:01 hanvolumio volumio[807]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jul 27 00:07:01 hanvolumio volumio[807]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jul 27 00:07:01 hanvolumio volumio[807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 27 00:07:01 hanvolumio go-librespot[1262]: time="2024-07-27T00:07:01Z" level=trace msg="emitting websocket event: paused" Jul 27 00:07:02 hanvolumio sudo[9832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-27 00:06 Jul 27 00:07:02 hanvolumio sudo[9832]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"