-- Logs begin at Thu 2025-05-01 21:28:37 CST, end at Thu 2025-05-01 21:29:45 CST. --
May 01 21:28:37 volumio-x86 kernel: Linux version 6.6.32-volumio (gkkpch@UbuntuVM24.04) (gcc (Ubuntu 13.2.0-23ubuntu4) 13.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024
May 01 21:28:37 volumio-x86 kernel: Command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=3cc03e2d-018e-43c1-b7e6-361cc0a7c20f bootpart=UUID=0A5D-C90D datapart=UUID=de34b9f5-9b78-4162-a289-2de9c46fbb26 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
May 01 21:28:37 volumio-x86 kernel: KERNEL supported cpus:
May 01 21:28:37 volumio-x86 kernel:   Intel GenuineIntel
May 01 21:28:37 volumio-x86 kernel:   AMD AuthenticAMD
May 01 21:28:37 volumio-x86 kernel:   Hygon HygonGenuine
May 01 21:28:37 volumio-x86 kernel:   Centaur CentaurHauls
May 01 21:28:37 volumio-x86 kernel:   zhaoxin   Shanghai  
May 01 21:28:37 volumio-x86 kernel: BIOS-provided physical RAM map:
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ebff] usable
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x000000000009ec00-0x000000000009ffff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000a7a3efff] usable
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7a3f000-0x00000000a7a86fff] ACPI NVS
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7a87000-0x00000000a7a8dfff] ACPI data
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7a8e000-0x00000000a7a90fff] ACPI NVS
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7a91000-0x00000000a7ab4fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7ab5000-0x00000000a7ab5fff] usable
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7ab6000-0x00000000a7ac5fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7ac6000-0x00000000a7acdfff] ACPI NVS
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7ace000-0x00000000a7af4fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7af5000-0x00000000a7b37fff] ACPI NVS
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7b38000-0x00000000a7db0fff] usable
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7db1000-0x00000000a7ef6fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000a7ef7000-0x00000000a7efffff] usable
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fec10000-0x00000000fec10fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fed00000-0x00000000fed00fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fed61000-0x00000000fed70fff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fed80000-0x00000000fed8ffff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x00000000fef00000-0x00000000ffffffff] reserved
May 01 21:28:37 volumio-x86 kernel: BIOS-e820: [mem 0x0000000100001000-0x000000013fffffff] usable
May 01 21:28:37 volumio-x86 kernel: NX (Execute Disable) protection: active
May 01 21:28:37 volumio-x86 kernel: APIC: Static calls initialized
May 01 21:28:37 volumio-x86 kernel: SMBIOS 2.7 present.
May 01 21:28:37 volumio-x86 kernel: DMI: To be filled by O.E.M. To be filled by O.E.M./, BIOS 4.6.4 05/28/2013
May 01 21:28:37 volumio-x86 kernel: tsc: Fast TSC calibration using PIT
May 01 21:28:37 volumio-x86 kernel: tsc: Detected 1599.876 MHz processor
May 01 21:28:37 volumio-x86 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
May 01 21:28:37 volumio-x86 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
May 01 21:28:37 volumio-x86 kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000
May 01 21:28:37 volumio-x86 kernel: MTRR map: 8 entries (5 fixed + 3 variable; max 22), built from 9 variable MTRRs
May 01 21:28:37 volumio-x86 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
May 01 21:28:37 volumio-x86 kernel: e820: update [mem 0xa7f00000-0xffffffff] usable ==> reserved
May 01 21:28:37 volumio-x86 kernel: last_pfn = 0xa7f00 max_arch_pfn = 0x400000000
May 01 21:28:37 volumio-x86 kernel: found SMP MP-table at [mem 0x000fcd90-0x000fcd9f]
May 01 21:28:37 volumio-x86 kernel: Using GB pages for direct mapping
May 01 21:28:37 volumio-x86 kernel: RAMDISK: [mem 0x7bd7c000-0x7fffffff]
May 01 21:28:37 volumio-x86 kernel: ACPI: Early table checksum verification disabled
May 01 21:28:37 volumio-x86 kernel: ACPI: RSDP 0x00000000000F0450 000024 (v02 ALASKA)
May 01 21:28:37 volumio-x86 kernel: ACPI: XSDT 0x00000000A7A87068 00004C (v01 ALASKA A M I    01072009 AMI  00010013)
May 01 21:28:37 volumio-x86 kernel: ACPI: FACP 0x00000000A7A8C310 0000F4 (v04 ALASKA A M I    01072009 AMI  00010013)
May 01 21:28:37 volumio-x86 kernel: ACPI BIOS Warning (bug): Optional FADT field Pm2ControlBlock has valid Length but zero Address: 0x0000000000000000/0x1 (20230628/tbfadt-615)
May 01 21:28:37 volumio-x86 kernel: ACPI: DSDT 0x00000000A7A87140 0051CB (v02 ALASKA A M I    00000000 INTL 20051117)
May 01 21:28:37 volumio-x86 kernel: ACPI: FACS 0x00000000A7ACDF80 000040
May 01 21:28:37 volumio-x86 kernel: ACPI: APIC 0x00000000A7A8C408 000062 (v03 ALASKA A M I    01072009 AMI  00010013)
May 01 21:28:37 volumio-x86 kernel: ACPI: MCFG 0x00000000A7A8C470 00003C (v01 ALASKA A M I    01072009 MSFT 00010013)
May 01 21:28:37 volumio-x86 kernel: ACPI: HPET 0x00000000A7A8C4B0 000038 (v01 ALASKA A M I    01072009 AMI  00000004)
May 01 21:28:37 volumio-x86 kernel: ACPI: SSDT 0x00000000A7A8C4E8 0012FA (v02 AMD    ALIB     00000001 MSFT 04000000)
May 01 21:28:37 volumio-x86 kernel: ACPI: Reserving FACP table memory at [mem 0xa7a8c310-0xa7a8c403]
May 01 21:28:37 volumio-x86 kernel: ACPI: Reserving DSDT table memory at [mem 0xa7a87140-0xa7a8c30a]
May 01 21:28:37 volumio-x86 kernel: ACPI: Reserving FACS table memory at [mem 0xa7acdf80-0xa7acdfbf]
May 01 21:28:37 volumio-x86 kernel: ACPI: Reserving APIC table memory at [mem 0xa7a8c408-0xa7a8c469]
May 01 21:28:37 volumio-x86 kernel: ACPI: Reserving MCFG table memory at [mem 0xa7a8c470-0xa7a8c4ab]
May 01 21:28:37 volumio-x86 kernel: ACPI: Reserving HPET table memory at [mem 0xa7a8c4b0-0xa7a8c4e7]
May 01 21:28:37 volumio-x86 kernel: ACPI: Reserving SSDT table memory at [mem 0xa7a8c4e8-0xa7a8d7e1]
May 01 21:28:37 volumio-x86 kernel: Zone ranges:
May 01 21:28:37 volumio-x86 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
May 01 21:28:37 volumio-x86 kernel:   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
May 01 21:28:37 volumio-x86 kernel:   Normal   [mem 0x0000000100000000-0x000000013fffffff]
May 01 21:28:37 volumio-x86 kernel: Movable zone start for each node
May 01 21:28:37 volumio-x86 kernel: Early memory node ranges
May 01 21:28:37 volumio-x86 kernel:   node   0: [mem 0x0000000000001000-0x000000000009dfff]
May 01 21:28:37 volumio-x86 kernel:   node   0: [mem 0x0000000000100000-0x00000000a7a3efff]
May 01 21:28:37 volumio-x86 kernel:   node   0: [mem 0x00000000a7ab5000-0x00000000a7ab5fff]
May 01 21:28:37 volumio-x86 kernel:   node   0: [mem 0x00000000a7b38000-0x00000000a7db0fff]
May 01 21:28:37 volumio-x86 kernel:   node   0: [mem 0x00000000a7ef7000-0x00000000a7efffff]
May 01 21:28:37 volumio-x86 kernel:   node   0: [mem 0x0000000100001000-0x000000013fffffff]
May 01 21:28:37 volumio-x86 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff]
May 01 21:28:37 volumio-x86 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
May 01 21:28:37 volumio-x86 kernel: On node 0, zone DMA: 98 pages in unavailable ranges
May 01 21:28:37 volumio-x86 kernel: On node 0, zone DMA32: 118 pages in unavailable ranges
May 01 21:28:37 volumio-x86 kernel: On node 0, zone DMA32: 130 pages in unavailable ranges
May 01 21:28:37 volumio-x86 kernel: On node 0, zone DMA32: 326 pages in unavailable ranges
May 01 21:28:37 volumio-x86 kernel: On node 0, zone Normal: 257 pages in unavailable ranges
May 01 21:28:37 volumio-x86 kernel: ACPI: PM-Timer IO Port: 0x808
May 01 21:28:37 volumio-x86 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1])
May 01 21:28:37 volumio-x86 kernel: IOAPIC[0]: apic_id 3, version 33, address 0xfec00000, GSI 0-23
May 01 21:28:37 volumio-x86 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
May 01 21:28:37 volumio-x86 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
May 01 21:28:37 volumio-x86 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
May 01 21:28:37 volumio-x86 kernel: ACPI: HPET id: 0xffffffff base: 0xfed00000
May 01 21:28:37 volumio-x86 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x0009e000-0x0009efff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000dffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x000e0000-0x000fffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7a3f000-0xa7a86fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7a87000-0xa7a8dfff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7a8e000-0xa7a90fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7a91000-0xa7ab4fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7ab6000-0xa7ac5fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7ac6000-0xa7acdfff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7ace000-0xa7af4fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7af5000-0xa7b37fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7db1000-0xa7ef6fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xa7f00000-0xdfffffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xe0000000-0xefffffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xf0000000-0xfebfffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfec00000-0xfec00fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfec01000-0xfec0ffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfec10000-0xfec10fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfec11000-0xfecfffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfed00000-0xfed00fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfed01000-0xfed60fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfed61000-0xfed70fff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfed71000-0xfed7ffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfed80000-0xfed8ffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfed90000-0xfeefffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0xfef00000-0xffffffff]
May 01 21:28:37 volumio-x86 kernel: PM: hibernation: Registered nosave memory: [mem 0x100000000-0x100000fff]
May 01 21:28:37 volumio-x86 kernel: [mem 0xa7f00000-0xdfffffff] available for PCI devices
May 01 21:28:37 volumio-x86 kernel: Booting paravirtualized kernel on bare hardware
May 01 21:28:37 volumio-x86 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
May 01 21:28:37 volumio-x86 kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
May 01 21:28:37 volumio-x86 kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u1048576
May 01 21:28:37 volumio-x86 kernel: pcpu-alloc: s191400 r8192 d29784 u1048576 alloc=1*2097152
May 01 21:28:37 volumio-x86 kernel: pcpu-alloc: [0] 0 1 
May 01 21:28:37 volumio-x86 kernel: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=3cc03e2d-018e-43c1-b7e6-361cc0a7c20f bootpart=UUID=0A5D-C90D datapart=UUID=de34b9f5-9b78-4162-a289-2de9c46fbb26 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
May 01 21:28:37 volumio-x86 kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=3cc03e2d-018e-43c1-b7e6-361cc0a7c20f bootpart=UUID=0A5D-C90D datapart=UUID=de34b9f5-9b78-4162-a289-2de9c46fbb26 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh use_kmsg=no", will be passed to user space.
May 01 21:28:37 volumio-x86 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 934349
May 01 21:28:37 volumio-x86 kernel: mem auto-init: stack:all(zero), heap alloc:off, heap free:off
May 01 21:28:37 volumio-x86 kernel: software IO TLB: area num 2.
May 01 21:28:37 volumio-x86 kernel: Memory: 3569360K/3797368K available (14336K kernel code, 1878K rwdata, 4640K rodata, 2988K init, 1148K bss, 227752K reserved, 0K cma-reserved)
May 01 21:28:37 volumio-x86 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
May 01 21:28:37 volumio-x86 kernel: ftrace: allocating 40433 entries in 158 pages
May 01 21:28:37 volumio-x86 kernel: ftrace: allocated 158 pages with 5 groups
May 01 21:28:37 volumio-x86 kernel: Dynamic Preempt: voluntary
May 01 21:28:37 volumio-x86 kernel: rcu: Preemptible hierarchical RCU implementation.
May 01 21:28:37 volumio-x86 kernel: rcu:         RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2.
May 01 21:28:37 volumio-x86 kernel:         Trampoline variant of Tasks RCU enabled.
May 01 21:28:37 volumio-x86 kernel:         Rude variant of Tasks RCU enabled.
May 01 21:28:37 volumio-x86 kernel:         Tracing variant of Tasks RCU enabled.
May 01 21:28:37 volumio-x86 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
May 01 21:28:37 volumio-x86 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
May 01 21:28:37 volumio-x86 kernel: NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16
May 01 21:28:37 volumio-x86 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
May 01 21:28:37 volumio-x86 kernel: Console: colour dummy device 80x25
May 01 21:28:37 volumio-x86 kernel: printk: console [tty0] enabled
May 01 21:28:37 volumio-x86 kernel: ACPI: Core revision 20230628
May 01 21:28:37 volumio-x86 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484873504 ns
May 01 21:28:37 volumio-x86 kernel: APIC: Switch to symmetric I/O mode setup
May 01 21:28:37 volumio-x86 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
May 01 21:28:37 volumio-x86 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x170fafb9856, max_idle_ns: 440795204679 ns
May 01 21:28:37 volumio-x86 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 3199.75 BogoMIPS (lpj=6399504)
May 01 21:28:37 volumio-x86 kernel: Last level iTLB entries: 4KB 512, 2MB 8, 4MB 4
May 01 21:28:37 volumio-x86 kernel: Last level dTLB entries: 4KB 512, 2MB 8, 4MB 4, 1GB 0
May 01 21:28:37 volumio-x86 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
May 01 21:28:37 volumio-x86 kernel: Spectre V2 : Mitigation: Retpolines
May 01 21:28:37 volumio-x86 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
May 01 21:28:37 volumio-x86 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
May 01 21:28:37 volumio-x86 kernel: Speculative Store Bypass: Vulnerable
May 01 21:28:37 volumio-x86 kernel: x86/fpu: x87 FPU will use FXSAVE
May 01 21:28:37 volumio-x86 kernel: Freeing SMP alternatives memory: 32K
May 01 21:28:37 volumio-x86 kernel: pid_max: default: 32768 minimum: 301
May 01 21:28:37 volumio-x86 kernel: LSM: initializing lsm=capability,yama,integrity
May 01 21:28:37 volumio-x86 kernel: Yama: becoming mindful.
May 01 21:28:37 volumio-x86 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: smpboot: CPU0: AMD E-350 Processor (family: 0x14, model: 0x1, stepping: 0x0)
May 01 21:28:37 volumio-x86 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
May 01 21:28:37 volumio-x86 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
May 01 21:28:37 volumio-x86 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
May 01 21:28:37 volumio-x86 kernel: Performance Events: AMD PMU driver.
May 01 21:28:37 volumio-x86 kernel: ... version:                0
May 01 21:28:37 volumio-x86 kernel: ... bit width:              48
May 01 21:28:37 volumio-x86 kernel: ... generic registers:      4
May 01 21:28:37 volumio-x86 kernel: ... value mask:             0000ffffffffffff
May 01 21:28:37 volumio-x86 kernel: ... max period:             00007fffffffffff
May 01 21:28:37 volumio-x86 kernel: ... fixed-purpose events:   0
May 01 21:28:37 volumio-x86 kernel: ... event mask:             000000000000000f
May 01 21:28:37 volumio-x86 kernel: signal: max sigframe size: 1440
May 01 21:28:37 volumio-x86 kernel: rcu: Hierarchical SRCU implementation.
May 01 21:28:37 volumio-x86 kernel: rcu:         Max phase no-delay instances is 1000.
May 01 21:28:37 volumio-x86 kernel: NMI watchdog: Enabled. Permanently consumes one hw-PMU counter.
May 01 21:28:37 volumio-x86 kernel: smp: Bringing up secondary CPUs ...
May 01 21:28:37 volumio-x86 kernel: smpboot: x86: Booting SMP configuration:
May 01 21:28:37 volumio-x86 kernel: .... node  #0, CPUs:      #1
May 01 21:28:37 volumio-x86 kernel: smp: Brought up 1 node, 2 CPUs
May 01 21:28:37 volumio-x86 kernel: smpboot: Max logical packages: 1
May 01 21:28:37 volumio-x86 kernel: smpboot: Total of 2 processors activated (6399.50 BogoMIPS)
May 01 21:28:37 volumio-x86 kernel: devtmpfs: initialized
May 01 21:28:37 volumio-x86 kernel: x86/mm: Memory block size: 128MB
May 01 21:28:37 volumio-x86 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xa7a3f000-0xa7a86fff] (294912 bytes)
May 01 21:28:37 volumio-x86 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xa7a8e000-0xa7a90fff] (12288 bytes)
May 01 21:28:37 volumio-x86 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xa7ac6000-0xa7acdfff] (32768 bytes)
May 01 21:28:37 volumio-x86 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xa7af5000-0xa7b37fff] (274432 bytes)
May 01 21:28:37 volumio-x86 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
May 01 21:28:37 volumio-x86 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: pinctrl core: initialized pinctrl subsystem
May 01 21:28:37 volumio-x86 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
May 01 21:28:37 volumio-x86 kernel: audit: initializing netlink subsys (disabled)
May 01 21:28:37 volumio-x86 kernel: audit: type=2000 audit(1746106091.172:1): state=initialized audit_enabled=0 res=1
May 01 21:28:37 volumio-x86 kernel: thermal_sys: Registered thermal governor 'fair_share'
May 01 21:28:37 volumio-x86 kernel: thermal_sys: Registered thermal governor 'bang_bang'
May 01 21:28:37 volumio-x86 kernel: thermal_sys: Registered thermal governor 'step_wise'
May 01 21:28:37 volumio-x86 kernel: thermal_sys: Registered thermal governor 'user_space'
May 01 21:28:37 volumio-x86 kernel: EISA bus registered
May 01 21:28:37 volumio-x86 kernel: cpuidle: using governor ladder
May 01 21:28:37 volumio-x86 kernel: cpuidle: using governor menu
May 01 21:28:37 volumio-x86 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
May 01 21:28:37 volumio-x86 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
May 01 21:28:37 volumio-x86 kernel: PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved as E820 entry
May 01 21:28:37 volumio-x86 kernel: PCI: Using configuration type 1 for base access
May 01 21:28:37 volumio-x86 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
May 01 21:28:37 volumio-x86 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
May 01 21:28:37 volumio-x86 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
May 01 21:28:37 volumio-x86 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
May 01 21:28:37 volumio-x86 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
May 01 21:28:37 volumio-x86 kernel: ACPI: Added _OSI(Module Device)
May 01 21:28:37 volumio-x86 kernel: ACPI: Added _OSI(Processor Device)
May 01 21:28:37 volumio-x86 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
May 01 21:28:37 volumio-x86 kernel: ACPI: Added _OSI(Processor Aggregator Device)
May 01 21:28:37 volumio-x86 kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded
May 01 21:28:37 volumio-x86 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC
May 01 21:28:37 volumio-x86 kernel: ACPI: Interpreter enabled
May 01 21:28:37 volumio-x86 kernel: ACPI: PM: (supports S0 S3 S4 S5)
May 01 21:28:37 volumio-x86 kernel: ACPI: Using IOAPIC for interrupt routing
May 01 21:28:37 volumio-x86 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
May 01 21:28:37 volumio-x86 kernel: PCI: Using E820 reservations for host bridge windows
May 01 21:28:37 volumio-x86 kernel: ACPI: Enabled 9 GPEs in block 00 to 1F
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
May 01 21:28:37 volumio-x86 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
May 01 21:28:37 volumio-x86 kernel: acpi PNP0A08:00: _OSC: OS requested [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR]
May 01 21:28:37 volumio-x86 kernel: acpi PNP0A08:00: _OSC: platform willing to grant [PCIeHotplug SHPCHotplug PME AER PCIeCapability LTR]
May 01 21:28:37 volumio-x86 kernel: acpi PNP0A08:00: _OSC: platform retains control of PCIe features (AE_NOT_FOUND)
May 01 21:28:37 volumio-x86 kernel: PCI host bridge to bus 0000:00
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x03af window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: root bus resource [io  0x03e0-0x0cf7 window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: root bus resource [io  0x03b0-0x03df window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000dffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xffffffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:00.0: [1022:1510] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: [1002:9802] type 00 class 0x030000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xcfffffff pref]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: reg 0x14: [io  0xf000-0xf0ff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfeb00000-0xfeb3ffff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: enabling Extended Tags
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.1: [1002:1314] type 00 class 0x040300
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.1: reg 0x10: [mem 0xfeb44000-0xfeb47fff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.1: enabling Extended Tags
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.1: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: [1002:4390] type 00 class 0x01018f
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: reg 0x10: [io  0xf190-0xf197]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: reg 0x14: [io  0xf180-0xf183]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: reg 0x18: [io  0xf170-0xf177]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: reg 0x1c: [io  0xf160-0xf163]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: reg 0x20: [io  0xf150-0xf15f]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: reg 0x24: [mem 0xfeb4f000-0xfeb4f3ff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:11.0: set SATA to AHCI mode
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:12.0: [1002:4397] type 00 class 0x0c0310
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:12.0: reg 0x10: [mem 0xfeb4e000-0xfeb4efff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:12.2: [1002:4396] type 00 class 0x0c0320
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:12.2: reg 0x10: [mem 0xfeb4d000-0xfeb4d0ff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:12.2: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:12.2: PME# supported from D0 D1 D2 D3hot
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:13.0: [1002:4397] type 00 class 0x0c0310
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:13.0: reg 0x10: [mem 0xfeb4c000-0xfeb4cfff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:13.2: [1002:4396] type 00 class 0x0c0320
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:13.2: reg 0x10: [mem 0xfeb4b000-0xfeb4b0ff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:13.2: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:13.2: PME# supported from D0 D1 D2 D3hot
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.0: [1002:4385] type 00 class 0x0c0500
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: [1002:439c] type 00 class 0x01018a
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: reg 0x10: [io  0xf140-0xf147]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: reg 0x14: [io  0xf130-0xf133]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: reg 0x18: [io  0xf120-0xf127]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: reg 0x1c: [io  0xf110-0xf113]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: reg 0x20: [io  0xf100-0xf10f]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.2: [1002:4383] type 00 class 0x040300
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.2: reg 0x10: [mem 0xfeb40000-0xfeb43fff 64bit]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.2: PME# supported from D0 D3hot D3cold
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.3: [1002:439d] type 00 class 0x060100
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4: [1002:4384] type 01 class 0x060401
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.5: [1002:4399] type 00 class 0x0c0310
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.5: reg 0x10: [mem 0xfeb4a000-0xfeb4afff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0: [1002:43a0] type 01 class 0x060400
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0: enabling Extended Tags
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.2: [1002:43a2] type 01 class 0x060400
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.2: enabling Extended Tags
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.2: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:16.0: [1002:4397] type 00 class 0x0c0310
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:16.0: reg 0x10: [mem 0xfeb49000-0xfeb49fff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:16.2: [1002:4396] type 00 class 0x0c0320
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:16.2: reg 0x10: [mem 0xfeb48000-0xfeb480ff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:16.2: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:16.2: PME# supported from D0 D1 D2 D3hot
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.0: [1022:1700] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.1: [1022:1701] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.2: [1022:1702] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.3: [1022:1703] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.4: [1022:1704] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.5: [1022:1718] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.6: [1022:1716] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:18.7: [1022:1719] type 00 class 0x060000
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:01: extended config space not accessible
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4: PCI bridge to [bus 01] (subtractive decode)
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4:   bridge window [io  0x0000-0x03af window] (subtractive decode)
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4:   bridge window [io  0x03e0-0x0cf7 window] (subtractive decode)
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4:   bridge window [io  0x03b0-0x03df window] (subtractive decode)
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4:   bridge window [io  0x0d00-0xffff window] (subtractive decode)
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4:   bridge window [mem 0x000a0000-0x000dffff window] (subtractive decode)
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4:   bridge window [mem 0xc0000000-0xffffffff window] (subtractive decode)
May 01 21:28:37 volumio-x86 kernel: pci 0000:02:00.0: [10ec:8168] type 00 class 0x020000
May 01 21:28:37 volumio-x86 kernel: pci 0000:02:00.0: reg 0x10: [io  0xe000-0xe0ff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:02:00.0: reg 0x18: [mem 0xd0004000-0xd0004fff 64bit pref]
May 01 21:28:37 volumio-x86 kernel: pci 0000:02:00.0: reg 0x20: [mem 0xd0000000-0xd0003fff 64bit pref]
May 01 21:28:37 volumio-x86 kernel: pci 0000:02:00.0: supports D1 D2
May 01 21:28:37 volumio-x86 kernel: pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0: PCI bridge to [bus 02]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0:   bridge window [io  0xe000-0xefff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0:   bridge window [mem 0xd0000000-0xd00fffff 64bit pref]
May 01 21:28:37 volumio-x86 kernel: pci 0000:03:00.0: [8086:24fd] type 00 class 0x028000
May 01 21:28:37 volumio-x86 kernel: pci 0000:03:00.0: reg 0x10: [mem 0xfea00000-0xfea01fff 64bit]
May 01 21:28:37 volumio-x86 kernel: pci 0000:03:00.0: PME# supported from D0 D3hot D3cold
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.2: PCI bridge to [bus 03]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.2:   bridge window [mem 0xfea00000-0xfeafffff]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: on NUMA node 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 0
May 01 21:28:37 volumio-x86 kernel: iommu: Default domain type: Translated
May 01 21:28:37 volumio-x86 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
May 01 21:28:37 volumio-x86 kernel: SCSI subsystem initialized
May 01 21:28:37 volumio-x86 kernel: libata version 3.00 loaded.
May 01 21:28:37 volumio-x86 kernel: ACPI: bus type USB registered
May 01 21:28:37 volumio-x86 kernel: usbcore: registered new interface driver usbfs
May 01 21:28:37 volumio-x86 kernel: usbcore: registered new interface driver hub
May 01 21:28:37 volumio-x86 kernel: usbcore: registered new device driver usb
May 01 21:28:37 volumio-x86 kernel: PCI: Using ACPI for IRQ routing
May 01 21:28:37 volumio-x86 kernel: PCI: pci_cache_line_size set to 64 bytes
May 01 21:28:37 volumio-x86 kernel: e820: reserve RAM buffer [mem 0x0009ec00-0x0009ffff]
May 01 21:28:37 volumio-x86 kernel: e820: reserve RAM buffer [mem 0xa7a3f000-0xa7ffffff]
May 01 21:28:37 volumio-x86 kernel: e820: reserve RAM buffer [mem 0xa7ab6000-0xa7ffffff]
May 01 21:28:37 volumio-x86 kernel: e820: reserve RAM buffer [mem 0xa7db1000-0xa7ffffff]
May 01 21:28:37 volumio-x86 kernel: e820: reserve RAM buffer [mem 0xa7f00000-0xa7ffffff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: vgaarb: bridge control possible
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
May 01 21:28:37 volumio-x86 kernel: vgaarb: loaded
May 01 21:28:37 volumio-x86 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
May 01 21:28:37 volumio-x86 kernel: hpet0: 3 comparators, 32-bit 14.318180 MHz counter
May 01 21:28:37 volumio-x86 kernel: amd_nb: x86/cpu/AMD: CPU erratum 688 worked around
May 01 21:28:37 volumio-x86 kernel: clocksource: Switched to clocksource tsc-early
May 01 21:28:37 volumio-x86 kernel: VFS: Disk quotas dquot_6.6.0
May 01 21:28:37 volumio-x86 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
May 01 21:28:37 volumio-x86 kernel: pnp: PnP ACPI init
May 01 21:28:37 volumio-x86 kernel: system 00:00: [mem 0xe0000000-0xefffffff] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x040b] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x04d6] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0c00-0x0c01] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0c14] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0c50-0x0c51] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0c52] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0c6c] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0c6f] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0cd0-0x0cd1] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0cd2-0x0cd3] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0cd4-0x0cd5] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0cd6-0x0cd7] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0cd8-0x0cdf] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0800-0x089f] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0b20-0x0b3f] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0900-0x090f] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0x0910-0x091f] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [io  0xfe00-0xfefe] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [mem 0xfec00000-0xfec00fff] could not be reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [mem 0xfee00000-0xfee00fff] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [mem 0xfed80000-0xfed8ffff] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [mem 0xfed61000-0xfed70fff] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [mem 0xfec10000-0xfec10fff] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [mem 0xfed00000-0xfed00fff] could not be reserved
May 01 21:28:37 volumio-x86 kernel: system 00:01: [mem 0xffe00000-0xffffffff] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:02: [io  0x0a00-0x0a1f] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:02: [io  0x0a30-0x0a3f] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:02: [io  0x0a20-0x0a2f] has been reserved
May 01 21:28:37 volumio-x86 kernel: pnp 00:05: [dma 0 disabled]
May 01 21:28:37 volumio-x86 kernel: system 00:07: [io  0x04d0-0x04d1] has been reserved
May 01 21:28:37 volumio-x86 kernel: system 00:09: [mem 0xa8000000-0xbfffffff] has been reserved
May 01 21:28:37 volumio-x86 kernel: pnp: PnP ACPI: found 10 devices
May 01 21:28:37 volumio-x86 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
May 01 21:28:37 volumio-x86 kernel: NET: Registered PF_INET protocol family
May 01 21:28:37 volumio-x86 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: TCP: Hash tables configured (established 32768 bind 32768)
May 01 21:28:37 volumio-x86 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
May 01 21:28:37 volumio-x86 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.4: PCI bridge to [bus 01]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0: PCI bridge to [bus 02]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0:   bridge window [io  0xe000-0xefff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.0:   bridge window [mem 0xd0000000-0xd00fffff 64bit pref]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.2: PCI bridge to [bus 03]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:15.2:   bridge window [mem 0xfea00000-0xfeafffff]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x03af window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: resource 5 [io  0x03e0-0x0cf7 window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: resource 6 [io  0x03b0-0x03df window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: resource 7 [io  0x0d00-0xffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: resource 8 [mem 0x000a0000-0x000dffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:00: resource 9 [mem 0xc0000000-0xffffffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:01: resource 4 [io  0x0000-0x03af window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:01: resource 5 [io  0x03e0-0x0cf7 window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:01: resource 6 [io  0x03b0-0x03df window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:01: resource 7 [io  0x0d00-0xffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:01: resource 8 [mem 0x000a0000-0x000dffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:01: resource 9 [mem 0xc0000000-0xffffffff window]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:02: resource 0 [io  0xe000-0xefff]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:02: resource 2 [mem 0xd0000000-0xd00fffff 64bit pref]
May 01 21:28:37 volumio-x86 kernel: pci_bus 0000:03: resource 1 [mem 0xfea00000-0xfeafffff]
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:01.1: D0 power state depends on 0000:00:01.0
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:12.0: quirk_usb_early_handoff+0x0/0x770 took 769567 usecs
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:13.0: quirk_usb_early_handoff+0x0/0x770 took 26882 usecs
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:14.5: quirk_usb_early_handoff+0x0/0x770 took 26830 usecs
May 01 21:28:37 volumio-x86 kernel: pci 0000:00:16.0: quirk_usb_early_handoff+0x0/0x770 took 27307 usecs
May 01 21:28:37 volumio-x86 kernel: PCI: CLS 64 bytes, default 64
May 01 21:28:37 volumio-x86 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
May 01 21:28:37 volumio-x86 kernel: software IO TLB: mapped [mem 0x00000000a3a3f000-0x00000000a7a3f000] (64MB)
May 01 21:28:37 volumio-x86 kernel: LVT offset 0 assigned for vector 0x400
May 01 21:28:37 volumio-x86 kernel: Trying to unpack rootfs image as initramfs...
May 01 21:28:37 volumio-x86 kernel: perf: AMD IBS detected (0x000000ff)
May 01 21:28:37 volumio-x86 kernel: Initialise system trusted keyrings
May 01 21:28:37 volumio-x86 kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0
May 01 21:28:37 volumio-x86 kernel: zbud: loaded
May 01 21:28:37 volumio-x86 kernel: Key type asymmetric registered
May 01 21:28:37 volumio-x86 kernel: Asymmetric key parser 'x509' registered
May 01 21:28:37 volumio-x86 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
May 01 21:28:37 volumio-x86 kernel: io scheduler mq-deadline registered
May 01 21:28:37 volumio-x86 kernel: io scheduler kyber registered
May 01 21:28:37 volumio-x86 kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
May 01 21:28:37 volumio-x86 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
May 01 21:28:37 volumio-x86 kernel: ACPI: button: Power Button [PWRB]
May 01 21:28:37 volumio-x86 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
May 01 21:28:37 volumio-x86 kernel: ACPI: button: Power Button [PWRF]
May 01 21:28:37 volumio-x86 kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
May 01 21:28:37 volumio-x86 kernel: 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
May 01 21:28:37 volumio-x86 kernel: Linux agpgart interface v0.103
May 01 21:28:37 volumio-x86 kernel: QUIRK: Enable AMD PLL fix
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:12.0: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:12.0: new USB bus registered, assigned bus number 1
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:12.0: irq 18, io mem 0xfeb4e000
May 01 21:28:37 volumio-x86 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06
May 01 21:28:37 volumio-x86 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 01 21:28:37 volumio-x86 kernel: usb usb1: Product: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: usb usb1: Manufacturer: Linux 6.6.32-volumio ohci_hcd
May 01 21:28:37 volumio-x86 kernel: usb usb1: SerialNumber: 0000:00:12.0
May 01 21:28:37 volumio-x86 kernel: hub 1-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 1-0:1.0: 5 ports detected
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:13.2: EHCI Host Controller
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:13.2: new USB bus registered, assigned bus number 2
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:13.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:13.2: debug port 1
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:13.2: irq 17, io mem 0xfeb4b000
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:13.2: USB 2.0 started, EHCI 1.00
May 01 21:28:37 volumio-x86 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06
May 01 21:28:37 volumio-x86 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 01 21:28:37 volumio-x86 kernel: usb usb2: Product: EHCI Host Controller
May 01 21:28:37 volumio-x86 kernel: usb usb2: Manufacturer: Linux 6.6.32-volumio ehci_hcd
May 01 21:28:37 volumio-x86 kernel: usb usb2: SerialNumber: 0000:00:13.2
May 01 21:28:37 volumio-x86 kernel: hub 2-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 2-0:1.0: 5 ports detected
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:12.2: EHCI Host Controller
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:12.2: new USB bus registered, assigned bus number 3
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:12.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:12.2: debug port 1
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:12.2: irq 17, io mem 0xfeb4d000
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:12.2: USB 2.0 started, EHCI 1.00
May 01 21:28:37 volumio-x86 kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06
May 01 21:28:37 volumio-x86 kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 01 21:28:37 volumio-x86 kernel: usb usb3: Product: EHCI Host Controller
May 01 21:28:37 volumio-x86 kernel: usb usb3: Manufacturer: Linux 6.6.32-volumio ehci_hcd
May 01 21:28:37 volumio-x86 kernel: usb usb3: SerialNumber: 0000:00:12.2
May 01 21:28:37 volumio-x86 kernel: hub 3-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 3-0:1.0: 5 ports detected
May 01 21:28:37 volumio-x86 kernel: hub 1-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 1-0:1.0: 5 ports detected
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:16.2: EHCI Host Controller
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:16.2: new USB bus registered, assigned bus number 4
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:16.2: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:16.2: debug port 1
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:16.2: irq 17, io mem 0xfeb48000
May 01 21:28:37 volumio-x86 kernel: usb 2-4: new high-speed USB device number 2 using ehci-pci
May 01 21:28:37 volumio-x86 kernel: ehci-pci 0000:00:16.2: USB 2.0 started, EHCI 1.00
May 01 21:28:37 volumio-x86 kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06
May 01 21:28:37 volumio-x86 kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 01 21:28:37 volumio-x86 kernel: usb usb4: Product: EHCI Host Controller
May 01 21:28:37 volumio-x86 kernel: usb usb4: Manufacturer: Linux 6.6.32-volumio ehci_hcd
May 01 21:28:37 volumio-x86 kernel: usb usb4: SerialNumber: 0000:00:16.2
May 01 21:28:37 volumio-x86 kernel: hub 4-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 4-0:1.0: 4 ports detected
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:13.0: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:13.0: new USB bus registered, assigned bus number 5
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:13.0: irq 18, io mem 0xfeb4c000
May 01 21:28:37 volumio-x86 kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06
May 01 21:28:37 volumio-x86 kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 01 21:28:37 volumio-x86 kernel: usb usb5: Product: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: usb usb5: Manufacturer: Linux 6.6.32-volumio ohci_hcd
May 01 21:28:37 volumio-x86 kernel: usb usb5: SerialNumber: 0000:00:13.0
May 01 21:28:37 volumio-x86 kernel: hub 5-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 5-0:1.0: 5 ports detected
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:14.5: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:14.5: new USB bus registered, assigned bus number 6
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:14.5: irq 18, io mem 0xfeb4a000
May 01 21:28:37 volumio-x86 kernel: usb usb6: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06
May 01 21:28:37 volumio-x86 kernel: usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 01 21:28:37 volumio-x86 kernel: usb usb6: Product: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: usb usb6: Manufacturer: Linux 6.6.32-volumio ohci_hcd
May 01 21:28:37 volumio-x86 kernel: usb usb6: SerialNumber: 0000:00:14.5
May 01 21:28:37 volumio-x86 kernel: hub 6-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 6-0:1.0: 2 ports detected
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:16.0: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:16.0: new USB bus registered, assigned bus number 7
May 01 21:28:37 volumio-x86 kernel: ohci-pci 0000:00:16.0: irq 18, io mem 0xfeb49000
May 01 21:28:37 volumio-x86 kernel: usb 2-4: New USB device found, idVendor=0781, idProduct=5591, bcdDevice= 1.00
May 01 21:28:37 volumio-x86 kernel: usb 2-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
May 01 21:28:37 volumio-x86 kernel: usb 2-4: Product: Ultra USB 3.0
May 01 21:28:37 volumio-x86 kernel: usb 2-4: Manufacturer: SanDisk
May 01 21:28:37 volumio-x86 kernel: usb 2-4: SerialNumber: 4C530000020402120321
May 01 21:28:37 volumio-x86 kernel: usb usb7: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06
May 01 21:28:37 volumio-x86 kernel: usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 01 21:28:37 volumio-x86 kernel: usb usb7: Product: OHCI PCI host controller
May 01 21:28:37 volumio-x86 kernel: usb usb7: Manufacturer: Linux 6.6.32-volumio ohci_hcd
May 01 21:28:37 volumio-x86 kernel: usb usb7: SerialNumber: 0000:00:16.0
May 01 21:28:37 volumio-x86 kernel: hub 7-0:1.0: USB hub found
May 01 21:28:37 volumio-x86 kernel: hub 7-0:1.0: 4 ports detected
May 01 21:28:37 volumio-x86 kernel: i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
May 01 21:28:37 volumio-x86 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
May 01 21:28:37 volumio-x86 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
May 01 21:28:37 volumio-x86 kernel: mousedev: PS/2 mouse device common for all mice
May 01 21:28:37 volumio-x86 kernel: rtc_cmos 00:06: RTC can wake from S4
May 01 21:28:37 volumio-x86 kernel: rtc_cmos 00:06: registered as rtc0
May 01 21:28:37 volumio-x86 kernel: rtc_cmos 00:06: setting system clock to 2025-05-01T13:28:13 UTC (1746106093)
May 01 21:28:37 volumio-x86 kernel: rtc_cmos 00:06: alarms up to one month, y3k, 114 bytes nvram, hpet irqs
May 01 21:28:37 volumio-x86 kernel: i2c_dev: i2c /dev entries driver
May 01 21:28:37 volumio-x86 kernel: platform eisa.0: Probing EISA bus 0
May 01 21:28:37 volumio-x86 kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard
May 01 21:28:37 volumio-x86 kernel: ledtrig-cpu: registered to indicate activity on CPUs
May 01 21:28:37 volumio-x86 kernel: NET: Registered PF_INET6 protocol family
May 01 21:28:37 volumio-x86 kernel: Segment Routing with IPv6
May 01 21:28:37 volumio-x86 kernel: In-situ OAM (IOAM) with IPv6
May 01 21:28:37 volumio-x86 kernel: mip6: Mobile IPv6
May 01 21:28:37 volumio-x86 kernel: NET: Registered PF_PACKET protocol family
May 01 21:28:37 volumio-x86 kernel: mpls_gso: MPLS GSO support
May 01 21:28:37 volumio-x86 kernel: microcode: CPU1: patch_level=0x05000028
May 01 21:28:37 volumio-x86 kernel: microcode: CPU0: patch_level=0x05000028
May 01 21:28:37 volumio-x86 kernel: microcode: Microcode Update Driver: v2.2.
May 01 21:28:37 volumio-x86 kernel: IPI shorthand broadcast: enabled
May 01 21:28:37 volumio-x86 kernel: sched_clock: Marking stable (1931191502, 823886)->(1935671496, -3656108)
May 01 21:28:37 volumio-x86 kernel: Loading compiled-in X.509 certificates
May 01 21:28:37 volumio-x86 kernel: tsc: Refined TSC clocksource calibration: 1599.998 MHz
May 01 21:28:37 volumio-x86 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x17102307eab, max_idle_ns: 440795204679 ns
May 01 21:28:37 volumio-x86 kernel: clocksource: Switched to clocksource tsc
May 01 21:28:37 volumio-x86 kernel: usb 7-1: new full-speed USB device number 2 using ohci-pci
May 01 21:28:37 volumio-x86 kernel: clk: Disabling unused clocks
May 01 21:28:37 volumio-x86 kernel: usb 5-5: new full-speed USB device number 2 using ohci-pci
May 01 21:28:37 volumio-x86 kernel: usb 7-1: New USB device found, idVendor=8087, idProduct=0a2b, bcdDevice= 0.10
May 01 21:28:37 volumio-x86 kernel: usb 7-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 01 21:28:37 volumio-x86 kernel: usb 5-5: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00
May 01 21:28:37 volumio-x86 kernel: usb 5-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
May 01 21:28:37 volumio-x86 kernel: usb 5-5: Product: USB2IIC_CTP_CONTROL
May 01 21:28:37 volumio-x86 kernel: usb 5-5: Manufacturer: wch.cn
May 01 21:28:37 volumio-x86 kernel: Freeing initrd memory: 68112K
May 01 21:28:37 volumio-x86 kernel: Freeing unused kernel image (initmem) memory: 2988K
May 01 21:28:37 volumio-x86 kernel: Write protecting the kernel read-only data: 20480k
May 01 21:28:37 volumio-x86 kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K
May 01 21:28:37 volumio-x86 kernel: Run /init as init process
May 01 21:28:37 volumio-x86 kernel:   with arguments:
May 01 21:28:37 volumio-x86 kernel:     /init
May 01 21:28:37 volumio-x86 kernel:     splash
May 01 21:28:37 volumio-x86 kernel:   with environment:
May 01 21:28:37 volumio-x86 kernel:     HOME=/
May 01 21:28:37 volumio-x86 kernel:     TERM=linux
May 01 21:28:37 volumio-x86 kernel:     BOOT_IMAGE=vmlinuz
May 01 21:28:37 volumio-x86 kernel:     biosdevname=0
May 01 21:28:37 volumio-x86 kernel:     imgpart=UUID=3cc03e2d-018e-43c1-b7e6-361cc0a7c20f
May 01 21:28:37 volumio-x86 kernel:     bootpart=UUID=0A5D-C90D
May 01 21:28:37 volumio-x86 kernel:     datapart=UUID=de34b9f5-9b78-4162-a289-2de9c46fbb26
May 01 21:28:37 volumio-x86 kernel:     hwdevice=x86
May 01 21:28:37 volumio-x86 kernel:     uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg
May 01 21:28:37 volumio-x86 kernel:     imgfile=/volumio_current.sqsh
May 01 21:28:37 volumio-x86 kernel:     use_kmsg=no
May 01 21:28:37 volumio-x86 kernel: usb-storage 2-4:1.0: USB Mass Storage device detected
May 01 21:28:37 volumio-x86 kernel: scsi host0: pata_atiixp
May 01 21:28:37 volumio-x86 kernel: scsi host1: usb-storage 2-4:1.0
May 01 21:28:37 volumio-x86 kernel: usbcore: registered new interface driver usb-storage
May 01 21:28:37 volumio-x86 kernel: scsi host2: pata_atiixp
May 01 21:28:37 volumio-x86 kernel: ahci 0000:00:11.0: version 3.0
May 01 21:28:37 volumio-x86 kernel: ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xf100 irq 14
May 01 21:28:37 volumio-x86 kernel: ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xf108 irq 15
May 01 21:28:37 volumio-x86 kernel: usbcore: registered new interface driver uas
May 01 21:28:37 volumio-x86 kernel: piix4_smbus 0000:00:14.0: SMBus Host Controller at 0xb00, revision 0
May 01 21:28:37 volumio-x86 kernel: piix4_smbus 0000:00:14.0: Using register 0x2c for SMBus port selection
May 01 21:28:37 volumio-x86 kernel: ahci 0000:00:11.0: AHCI 0001.0200 32 slots 4 ports 6 Gbps 0xf impl SATA mode
May 01 21:28:37 volumio-x86 kernel: ahci 0000:00:11.0: flags: 64bit ncq sntf ilck pm led clo pmp pio slum part 
May 01 21:28:37 volumio-x86 kernel: ACPI: bus type drm_connector registered
May 01 21:28:37 volumio-x86 kernel: piix4_smbus 0000:00:14.0: Auxiliary SMBus Host Controller at 0xb20
May 01 21:28:37 volumio-x86 kernel: scsi host3: ahci
May 01 21:28:37 volumio-x86 kernel: scsi host4: ahci
May 01 21:28:37 volumio-x86 kernel: scsi host5: ahci
May 01 21:28:37 volumio-x86 kernel: scsi host6: ahci
May 01 21:28:37 volumio-x86 kernel: ata3: SATA max UDMA/133 abar m1024@0xfeb4f000 port 0xfeb4f100 irq 19
May 01 21:28:37 volumio-x86 kernel: ata4: SATA max UDMA/133 abar m1024@0xfeb4f000 port 0xfeb4f180 irq 19
May 01 21:28:37 volumio-x86 kernel: ata5: SATA max UDMA/133 abar m1024@0xfeb4f000 port 0xfeb4f200 irq 19
May 01 21:28:37 volumio-x86 kernel: ata6: SATA max UDMA/133 abar m1024@0xfeb4f000 port 0xfeb4f280 irq 19
May 01 21:28:37 volumio-x86 kernel: hid: raw HID events driver (C) Jiri Kosina
May 01 21:28:37 volumio-x86 kernel: ata4: SATA link down (SStatus 0 SControl 300)
May 01 21:28:37 volumio-x86 kernel: ata3: SATA link down (SStatus 0 SControl 300)
May 01 21:28:37 volumio-x86 kernel: ata5: SATA link down (SStatus 0 SControl 300)
May 01 21:28:37 volumio-x86 kernel: ata6: SATA link down (SStatus 0 SControl 300)
May 01 21:28:37 volumio-x86 kernel: usbcore: registered new interface driver usbhid
May 01 21:28:37 volumio-x86 kernel: usbhid: USB HID core driver
May 01 21:28:37 volumio-x86 kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/pci0000:00/0000:00:13.0/usb5/5-5/5-5:1.0/0003:1A86:E5E3.0001/input/input5
May 01 21:28:37 volumio-x86 kernel: r8169 0000:02:00.0: can't disable ASPM; OS doesn't have ASPM control
May 01 21:28:37 volumio-x86 kernel: hid-generic 0003:1A86:E5E3.0001: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-0000:00:13.0-5/input0
May 01 21:28:37 volumio-x86 kernel: r8169 0000:02:00.0 eth0: RTL8168evl/8111evl, 00:e0:4c:5d:aa:01, XID 2c9, IRQ 26
May 01 21:28:37 volumio-x86 kernel: r8169 0000:02:00.0 eth0: jumbo features [frames: 9194 bytes, tx checksumming: ko]
May 01 21:28:37 volumio-x86 kernel: [drm] radeon kernel modesetting enabled.
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: vgaarb: deactivate vga console
May 01 21:28:37 volumio-x86 kernel: [drm] initializing kernel modesetting (PALM 0x1002:0x9802 0x1022:0x1511 0x00).
May 01 21:28:37 volumio-x86 kernel: ATOM BIOS: AMD
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: VRAM: 384M 0x0000000000000000 - 0x0000000017FFFFFF (384M used)
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: GTT: 1024M 0x0000000018000000 - 0x0000000057FFFFFF
May 01 21:28:37 volumio-x86 kernel: [drm] Detected VRAM RAM=384M, BAR=256M
May 01 21:28:37 volumio-x86 kernel: [drm] RAM width 32bits DDR
May 01 21:28:37 volumio-x86 kernel: [drm] radeon: 384M of VRAM memory ready
May 01 21:28:37 volumio-x86 kernel: [drm] radeon: 1024M of GTT memory ready.
May 01 21:28:37 volumio-x86 kernel: [drm] Loading PALM Microcode
May 01 21:28:37 volumio-x86 kernel: [drm] Internal thermal controller without fan control
May 01 21:28:37 volumio-x86 kernel: [drm] Found smc ucode version: 0x00010200
May 01 21:28:37 volumio-x86 kernel: [drm] radeon: dpm initialized
May 01 21:28:37 volumio-x86 kernel: [drm] GART: num cpu pages 262144, num gpu pages 262144
May 01 21:28:37 volumio-x86 kernel: [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000).
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: WB enabled
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: fence driver on ring 0 use gpu addr 0x0000000018000c00
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: fence driver on ring 3 use gpu addr 0x0000000018000c0c
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: fence driver on ring 5 use gpu addr 0x0000000000072118
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: radeon: MSI limited to 32-bit
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: radeon: using MSI.
May 01 21:28:37 volumio-x86 kernel: [drm] radeon: irq initialized.
May 01 21:28:37 volumio-x86 kernel: [drm] ring test on 0 succeeded in 1 usecs
May 01 21:28:37 volumio-x86 kernel: [drm] ring test on 3 succeeded in 3 usecs
May 01 21:28:37 volumio-x86 kernel: [drm] ring test on 5 succeeded in 1 usecs
May 01 21:28:37 volumio-x86 kernel: [drm] UVD initialized successfully.
May 01 21:28:37 volumio-x86 kernel: [drm] ib test on ring 0 succeeded in 0 usecs
May 01 21:28:37 volumio-x86 kernel: [drm] ib test on ring 3 succeeded in 0 usecs
May 01 21:28:37 volumio-x86 kernel: scsi 1:0:0:0: Direct-Access     SanDisk  Ultra USB 3.0    1.00 PQ: 0 ANSI: 6
May 01 21:28:37 volumio-x86 kernel: sd 1:0:0:0: [sda] 30031872 512-byte logical blocks: (15.4 GB/14.3 GiB)
May 01 21:28:37 volumio-x86 kernel: sd 1:0:0:0: [sda] Write Protect is off
May 01 21:28:37 volumio-x86 kernel: sd 1:0:0:0: [sda] Mode Sense: 43 00 00 00
May 01 21:28:37 volumio-x86 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
May 01 21:28:37 volumio-x86 kernel:  sda: sda1 sda2 sda3
May 01 21:28:37 volumio-x86 kernel: sd 1:0:0:0: [sda] Attached SCSI removable disk
May 01 21:28:37 volumio-x86 kernel: [drm] ib test on ring 5 succeeded
May 01 21:28:37 volumio-x86 kernel: [drm] Radeon Display Connectors
May 01 21:28:37 volumio-x86 kernel: [drm] Connector 0:
May 01 21:28:37 volumio-x86 kernel: [drm]   HDMI-A-1
May 01 21:28:37 volumio-x86 kernel: [drm]   HPD2
May 01 21:28:37 volumio-x86 kernel: [drm]   DDC: 0x6440 0x6440 0x6444 0x6444 0x6448 0x6448 0x644c 0x644c
May 01 21:28:37 volumio-x86 kernel: [drm]   Encoders:
May 01 21:28:37 volumio-x86 kernel: [drm]     DFP1: INTERNAL_UNIPHY
May 01 21:28:37 volumio-x86 kernel: [drm] Connector 1:
May 01 21:28:37 volumio-x86 kernel: [drm]   VGA-1
May 01 21:28:37 volumio-x86 kernel: [drm]   DDC: 0x64d8 0x64d8 0x64dc 0x64dc 0x64e0 0x64e0 0x64e4 0x64e4
May 01 21:28:37 volumio-x86 kernel: [drm]   Encoders:
May 01 21:28:37 volumio-x86 kernel: [drm]     CRT1: INTERNAL_KLDSCP_DAC1
May 01 21:28:37 volumio-x86 kernel: [drm] Initialized radeon 2.50.0 20080528 for 0000:00:01.0 on minor 0
May 01 21:28:37 volumio-x86 kernel: [drm] fb mappable at 0xC0366000
May 01 21:28:37 volumio-x86 kernel: [drm] vram apper at 0xC0000000
May 01 21:28:37 volumio-x86 kernel: [drm] size 2293760
May 01 21:28:37 volumio-x86 kernel: [drm] fb depth is 24
May 01 21:28:37 volumio-x86 kernel: [drm]    pitch is 1792
May 01 21:28:37 volumio-x86 kernel: fbcon: radeondrmfb (fb0) is primary device
May 01 21:28:37 volumio-x86 kernel: Console: switching to colour frame buffer device 50x80
May 01 21:28:37 volumio-x86 kernel: radeon 0000:00:01.0: [drm] fb0: radeondrmfb frame buffer device
May 01 21:28:37 volumio-x86 kernel: [drm] amdgpu kernel modesetting enabled.
May 01 21:28:37 volumio-x86 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
May 01 21:28:37 volumio-x86 kernel: sdhci: Secure Digital Host Controller Interface driver
May 01 21:28:37 volumio-x86 kernel: sdhci: Copyright(c) Pierre Ossman
May 01 21:28:37 volumio-x86 kernel: fuse: init (API version 7.39)
May 01 21:28:37 volumio-x86 kernel: random: crng init done
May 01 21:28:37 volumio-x86 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
May 01 21:28:37 volumio-x86 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
May 01 21:28:37 volumio-x86 kernel: EXT4-fs (sda2): mounted filesystem 3cc03e2d-018e-43c1-b7e6-361cc0a7c20f r/w with ordered data mode. Quota mode: none.
May 01 21:28:37 volumio-x86 kernel: loop: module loaded
May 01 21:28:37 volumio-x86 kernel: block device autoloading is deprecated and will be removed.
May 01 21:28:37 volumio-x86 kernel: loop0: detected capacity change from 0 to 1726400
May 01 21:28:37 volumio-x86 kernel: EXT4-fs (sda3): mounted filesystem de34b9f5-9b78-4162-a289-2de9c46fbb26 r/w with ordered data mode. Quota mode: none.
May 01 21:28:37 volumio-x86 kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff)
May 01 21:28:37 volumio-x86 systemd[1]: Inserted module 'autofs4'
May 01 21:28:37 volumio-x86 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)
May 01 21:28:37 volumio-x86 systemd[1]: Detected architecture x86-64.
May 01 21:28:37 volumio-x86 systemd[1]: Set hostname to .
May 01 21:28:37 volumio-x86 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.
May 01 21:28:37 volumio-x86 systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
May 01 21:28:37 volumio-x86 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.
May 01 21:28:37 volumio-x86 systemd[1]: Listening on fsck to fsckd communication Socket.
May 01 21:28:37 volumio-x86 systemd[1]: Listening on Journal Audit Socket.
May 01 21:28:37 volumio-x86 systemd[1]: Reached target System Time Synchronized.
May 01 21:28:37 volumio-x86 systemd[1]: Started Forward Password Requests to Wall Directory Watch.
May 01 21:28:37 volumio-x86 kernel: RPC: Registered named UNIX socket transport module.
May 01 21:28:37 volumio-x86 kernel: RPC: Registered udp transport module.
May 01 21:28:37 volumio-x86 kernel: RPC: Registered tcp transport module.
May 01 21:28:37 volumio-x86 kernel: RPC: Registered tcp-with-tls transport module.
May 01 21:28:37 volumio-x86 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
May 01 21:28:37 volumio-x86 systemd-journald[445]: Journal started
May 01 21:28:37 volumio-x86 systemd-journald[445]: Runtime journal (/run/log/journal/bd097a20dc242dbec9d88bbb67ead084) is 3.7M, max 30.0M, 26.2M free.
May 01 21:28:37 volumio-x86 fake-hwclock[446]: Current system time: 2025-05-01 13:28:37
May 01 21:28:37 volumio-x86 fake-hwclock[446]: fake-hwclock saved clock information is in the past: 2025-05-01 13:12:43
May 01 21:28:37 volumio-x86 fake-hwclock[446]: To set system time to this saved clock anyway, use "force"
May 01 21:28:37 volumio-x86 systemd[1]: Started Create Static Device Nodes in /dev.
May 01 21:28:37 volumio-x86 systemd[1]: Starting udev Kernel Device Manager...
May 01 21:28:37 volumio-x86 systemd[1]: Reached target Local File Systems (Pre).
May 01 21:28:37 volumio-x86 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
May 01 21:28:37 volumio-x86 systemd[1]: Mounting /tmp...
May 01 21:28:37 volumio-x86 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
May 01 21:28:37 volumio-x86 systemd[1]: Mounting /var/log...
May 01 21:28:37 volumio-x86 systemd[1]: Mounting /var/spool/cups...
May 01 21:28:37 volumio-x86 systemd-udevd[470]: Network interface NamePolicy= disabled on kernel command line, ignoring.
May 01 21:28:37 volumio-x86 systemd[1]: Mounted /tmp.
May 01 21:28:37 volumio-x86 systemd[1]: Mounted /var/log.
May 01 21:28:37 volumio-x86 systemd[1]: Mounted /var/spool/cups.
May 01 21:28:37 volumio-x86 systemd[1]: Mounting /var/spool/cups/tmp...
May 01 21:28:37 volumio-x86 systemd[1]: Starting Flush Journal to Persistent Storage...
May 01 21:28:37 volumio-x86 systemd[1]: Started udev Kernel Device Manager.
May 01 21:28:37 volumio-x86 systemd[1]: Mounted /var/spool/cups/tmp.
May 01 21:28:38 volumio-x86 systemd-journald[445]: Runtime journal (/run/log/journal/bd097a20dc242dbec9d88bbb67ead084) is 3.7M, max 30.0M, 26.2M free.
May 01 21:28:38 volumio-x86 systemd[1]: Started Flush Journal to Persistent Storage.
May 01 21:28:38 volumio-x86 systemd[1]: Started udev Coldplug all Devices.
May 01 21:28:38 volumio-x86 systemd[1]: Starting Helper to synchronize boot up for ifupdown...
May 01 21:28:38 volumio-x86 systemd[1]: Starting Show Plymouth Boot Screen...
May 01 21:28:38 volumio-x86 systemd[1]: Started Helper to synchronize boot up for ifupdown.
May 01 21:28:38 volumio-x86 systemd[1]: plymouth-start.service: Succeeded.
May 01 21:28:38 volumio-x86 systemd[1]: Started Show Plymouth Boot Screen.
May 01 21:28:38 volumio-x86 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
May 01 21:28:38 volumio-x86 systemd[1]: Reached target Local Encrypted Volumes.
May 01 21:28:38 volumio-x86 systemd[1]: Reached target Paths.
May 01 21:28:38 volumio-x86 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
May 01 21:28:38 volumio-x86 systemd-udevd[491]: Using default interface naming scheme 'v240'.
May 01 21:28:38 volumio-x86 systemd-udevd[491]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
May 01 21:28:38 volumio-x86 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database
May 01 21:28:39 volumio-x86 kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0
May 01 21:28:39 volumio-x86 kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/pci0000:00/0000:00:13.0/usb5/5-5/5-5:1.0/0003:1A86:E5E3.0001/input/input6
May 01 21:28:39 volumio-x86 kernel: hid-multitouch 0003:1A86:E5E3.0001: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-0000:00:13.0-5/input0
May 01 21:28:39 volumio-x86 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
May 01 21:28:39 volumio-x86 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
May 01 21:28:39 volumio-x86 systemd-udevd[496]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
May 01 21:28:39 volumio-x86 kernel: cryptd: max_cpu_qlen set to 1000
May 01 21:28:39 volumio-x86 systemd-udevd[480]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
May 01 21:28:39 volumio-x86 kernel: kvm_amd: Nested Virtualization enabled
May 01 21:28:39 volumio-x86 kernel: kvm_amd: Nested Paging enabled
May 01 21:28:39 volumio-x86 kernel: kvm_amd: LBR virtualization supported
May 01 21:28:39 volumio-x86 kernel: Intel(R) Wireless WiFi driver for Linux
May 01 21:28:39 volumio-x86 kernel: snd_hda_intel 0000:00:01.1: bound 0000:00:01.0 (ops __SCT__tp_func_radeon_semaphore_wait [radeon])
May 01 21:28:39 volumio-x86 kernel: iwlwifi 0000:03:00.0: Detected crf-id 0xbadcafe, cnv-id 0x10 wfpm id 0x80000000
May 01 21:28:39 volumio-x86 kernel: iwlwifi 0000:03:00.0: PCI dev 24fd/9010, rev=0x230, rfid=0xd55555d5
May 01 21:28:39 volumio-x86 kernel: input: HD-Audio Generic HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.1/sound/card0/input7
May 01 21:28:39 volumio-x86 systemd-udevd[503]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
May 01 21:28:39 volumio-x86 systemd-udevd[489]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
May 01 21:28:39 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D3: autoconfig for ALC892: line_outs=1 (0x1b/0x0/0x0/0x0/0x0) type:line
May 01 21:28:39 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D3:    speaker_outs=1 (0x14/0x0/0x0/0x0/0x0)
May 01 21:28:39 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D3:    hp_outs=0 (0x0/0x0/0x0/0x0/0x0)
May 01 21:28:39 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D3:    mono: mono_out=0x0
May 01 21:28:39 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D3:    dig-out=0x1e/0x0
May 01 21:28:39 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D3:    inputs:
May 01 21:28:39 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D3:      Mic=0x18
May 01 21:28:39 volumio-x86 kernel: input: HDA ATI SB Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input8
May 01 21:28:39 volumio-x86 kernel: input: HDA ATI SB Line Out as /devices/pci0000:00/0000:00:14.2/sound/card1/input9
May 01 21:28:39 volumio-x86 kernel: iwlwifi 0000:03:00.0: loaded firmware version 36.ca7b901d.0 8265-36.ucode op_mode iwlmvm
May 01 21:28:39 volumio-x86 systemd-udevd[484]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
May 01 21:28:40 volumio-x86 systemd-udevd[498]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
May 01 21:28:40 volumio-x86 systemd-udevd[489]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
May 01 21:28:40 volumio-x86 systemd-udevd[487]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
May 01 21:28:40 volumio-x86 kernel: iwlwifi 0000:03:00.0: Detected Intel(R) Dual Band Wireless AC 8265, REV=0x230
May 01 21:28:40 volumio-x86 kernel: thermal thermal_zone0: failed to read out thermal zone (-61)
May 01 21:28:40 volumio-x86 kernel: iwlwifi 0000:03:00.0: base HW address: d8:f2:ca:d7:27:bf, OTP minor version: 0x4
May 01 21:28:40 volumio-x86 kernel: Bluetooth: Core ver 2.22
May 01 21:28:40 volumio-x86 kernel: NET: Registered PF_BLUETOOTH protocol family
May 01 21:28:40 volumio-x86 kernel: Bluetooth: HCI device and connection manager initialized
May 01 21:28:40 volumio-x86 kernel: Bluetooth: HCI socket layer initialized
May 01 21:28:40 volumio-x86 kernel: Bluetooth: L2CAP socket layer initialized
May 01 21:28:40 volumio-x86 kernel: Bluetooth: SCO socket layer initialized
May 01 21:28:40 volumio-x86 kernel: ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
May 01 21:28:40 volumio-x86 systemd-udevd[480]: Using default interface naming scheme 'v240'.
May 01 21:28:40 volumio-x86 systemd-udevd[480]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
May 01 21:28:40 volumio-x86 kernel: usbcore: registered new interface driver btusb
May 01 21:28:40 volumio-x86 kernel: Bluetooth: hci0: Firmware revision 0.1 build 19 week 44 2021
May 01 21:28:40 volumio-x86 kernel: Bluetooth: hci0: Reading supported features failed (-16)
May 01 21:28:40 volumio-x86 kernel: Bluetooth: hci0: Error reading debug features
May 01 21:28:40 volumio-x86 kernel: Bluetooth: hci0: HCI LE Coded PHY feature bit is set, but its usage is not supported.
May 01 21:28:40 volumio-x86 kernel: alsactl[578]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set
May 01 21:28:41 volumio-x86 systemd[1]: Found device Ultra_USB_3.0 boot.
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in Wireless 8265 / 8275 being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Starting Show Plymouth Boot Screen...
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Starting File System Check on /dev/disk/by-uuid/0A5D-C90D...
May 01 21:28:41 volumio-x86 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
May 01 21:28:41 volumio-x86 systemd[1]: Starting Load/Save RF Kill Switch Status...
May 01 21:28:41 volumio-x86 systemd[1]: Started File System Check Daemon to report status.
May 01 21:28:41 volumio-x86 systemd[1]: plymouth-start.service: Succeeded.
May 01 21:28:41 volumio-x86 systemd[1]: Started Show Plymouth Boot Screen.
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Started Load/Save RF Kill Switch Status.
May 01 21:28:41 volumio-x86 systemd-fsck[610]: fsck.fat 4.1 (2017-01-24)
May 01 21:28:41 volumio-x86 systemd-fsck[610]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
May 01 21:28:41 volumio-x86 systemd-fsck[610]:  Automatically removing dirty bit.
May 01 21:28:41 volumio-x86 systemd-fsck[610]: There are differences between boot sector and its backup.
May 01 21:28:41 volumio-x86 systemd-fsck[610]: This is mostly harmless. Differences: (offset:original/backup)
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 282:c6/00, 283:9b/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   , 508:18/00, 509:37/00
May 01 21:28:41 volumio-x86 systemd-fsck[610]:   Not automatically fixing this.
May 01 21:28:41 volumio-x86 systemd-fsck[610]: Performing changes.
May 01 21:28:41 volumio-x86 systemd-fsck[610]: /dev/sda1: 18 files, 159614/344788 clusters
May 01 21:28:41 volumio-x86 systemd[1]: Started File System Check on /dev/disk/by-uuid/0A5D-C90D.
May 01 21:28:41 volumio-x86 systemd[1]: Mounting /boot...
May 01 21:28:41 volumio-x86 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
May 01 21:28:41 volumio-x86 systemd[1]: Mounted /boot.
May 01 21:28:41 volumio-x86 systemd[1]: Reached target Local File Systems.
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Started ifup for wlan0.
May 01 21:28:41 volumio-x86 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
May 01 21:28:41 volumio-x86 systemd[1]: Starting Preprocess NFS configuration...
May 01 21:28:41 volumio-x86 systemd[1]: Starting Raise network interfaces...
May 01 21:28:41 volumio-x86 systemd[1]: Starting Create Volatile Files and Directories...
May 01 21:28:41 volumio-x86 systemd[1]: Started ifup for eth0.
May 01 21:28:41 volumio-x86 systemd[1]: plymouth-read-write.service: Succeeded.
May 01 21:28:41 volumio-x86 systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
May 01 21:28:41 volumio-x86 systemd[1]: Received SIGRTMIN+20 from PID 333 (plymouthd).
May 01 21:28:41 volumio-x86 systemd[1]: nfs-config.service: Succeeded.
May 01 21:28:41 volumio-x86 systemd[1]: Started Preprocess NFS configuration.
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
May 01 21:28:41 volumio-x86 systemd[1]: Reached target NFS client services.
May 01 21:28:41 volumio-x86 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211
May 01 21:28:41 volumio-x86 systemd[1]: Started Create Volatile Files and Directories.
May 01 21:28:41 volumio-x86 systemd[1]: Starting Update UTMP about System Boot/Shutdown...
May 01 21:28:42 volumio-x86 systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
May 01 21:28:42 volumio-x86 systemd[1]: Starting RPC bind portmap service...
May 01 21:28:42 volumio-x86 systemd[1]: Condition check resulted in Network Time Synchronization being skipped.
May 01 21:28:42 volumio-x86 systemd[1]: Started Update UTMP about System Boot/Shutdown.
May 01 21:28:42 volumio-x86 systemd[1]: Reached target System Initialization.
May 01 21:28:42 volumio-x86 systemd[1]: Listening on triggerhappy.socket.
May 01 21:28:42 volumio-x86 systemd[1]: Started Daily man-db regeneration.
May 01 21:28:42 volumio-x86 kernel: 8021q: 802.1Q VLAN Support v1.8
May 01 21:28:42 volumio-x86 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
May 01 21:28:42 volumio-x86 systemd[1]: Started Daily Cleanup of Temporary Directories.
May 01 21:28:42 volumio-x86 systemd[1]: Listening on D-Bus System Message Bus Socket.
May 01 21:28:42 volumio-x86 systemd[1]: Reached target Sockets.
May 01 21:28:42 volumio-x86 systemd[1]: Reached target Basic System.
May 01 21:28:42 volumio-x86 systemd[1]: Starting Bluetooth service...
May 01 21:28:42 volumio-x86 systemd[1]: Started D-Bus System Message Bus.
May 01 21:28:42 volumio-x86 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
May 01 21:28:42 volumio-x86 systemd[1]: Started Volumio Log Rotation Service.
May 01 21:28:42 volumio-x86 systemd[1]: Starting Volumio Time Update Utility...
May 01 21:28:42 volumio-x86 systemd[1]: Starting Wireless Services...
May 01 21:28:42 volumio-x86 systemd[1]: Started Manage Sound Card State (restore and store).
May 01 21:28:42 volumio-x86 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
May 01 21:28:42 volumio-x86 alsactl[694]: alsactl 1.1.8 daemon started
May 01 21:28:42 volumio-x86 systemd[1]: Started My Shell Script.
May 01 21:28:42 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Fetching time from Volumio...
May 01 21:28:42 volumio-x86 systemd[1]: Started volumio-remote-updater.service.
May 01 21:28:42 volumio-x86 systemd[1]: Started Volumio Iptables Module.
May 01 21:28:42 volumio-x86 systemd[1]: Started MPD Monitor Service.
May 01 21:28:42 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:42 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:42 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:42 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:42 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:42 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:42 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:42 volumio-x86 systemd[1]: Starting DHCP Client Daemon...
May 01 21:28:42 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD.
May 01 21:28:42 volumio-x86 systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
May 01 21:28:42 volumio-x86 mpd_monitor.sh[703]: MPD Monitor Service: Starting MPD Monitor Service
May 01 21:28:42 volumio-x86 systemd[1]: Starting Save/Restore Sound Card State...
May 01 21:28:42 volumio-x86 systemd[1]: Starting triggerhappy global hotkey daemon...
May 01 21:28:42 volumio-x86 systemd[1]: Starting Login Service...
May 01 21:28:42 volumio-x86 systemd[1]: Starting WPA supplicant...
May 01 21:28:42 volumio-x86 systemd[1]: Started Daily apt download activities.
May 01 21:28:42 volumio-x86 systemd[1]: Started Daily apt upgrade and clean activities.
May 01 21:28:42 volumio-x86 systemd[1]: Reached target Timers.
May 01 21:28:42 volumio-x86 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped.
May 01 21:28:42 volumio-x86 thd[715]: Unable to parse trigger line:
May 01 21:28:42 volumio-x86 thd[715]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle
May 01 21:28:42 volumio-x86 thd[715]: Unable to parse trigger line:
May 01 21:28:42 volumio-x86 thd[715]: Found socket passed from systemd
May 01 21:28:43 volumio-x86 mpd_monitor.sh[703]: MPD error: Connection refused
May 01 21:28:43 volumio-x86 bluetoothd[689]: Bluetooth daemon 5.50
May 01 21:28:43 volumio-x86 systemd-logind[716]: New seat seat0.
May 01 21:28:43 volumio-x86 mpd_monitor.sh[703]: MPD Monitor Service: MPD Appears to be inactive, restarting
May 01 21:28:43 volumio-x86 bluetoothd[689]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
May 01 21:28:43 volumio-x86 systemd-logind[716]: Watching system buttons on /dev/input/event1 (Power Button)
May 01 21:28:43 volumio-x86 systemd-logind[716]: Watching system buttons on /dev/input/event0 (Power Button)
May 01 21:28:43 volumio-x86 avahi-daemon[712]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113).
May 01 21:28:43 volumio-x86 avahi-daemon[712]: Successfully dropped root privileges.
May 01 21:28:43 volumio-x86 avahi-daemon[712]: avahi-daemon 0.7 starting up.
May 01 21:28:43 volumio-x86 mpd_monitor.sh[703]: mpd: no process found
May 01 21:28:43 volumio-x86 haveged[683]: haveged: ver: 1.9.1; arch: x86; vend: ; build: (gcc 8.3.0 ITV); collect: 128K
May 01 21:28:43 volumio-x86 haveged[683]: haveged: cpu: (VC); data: 32K (V); inst: 32K (V); idx: 24/40; sz: 31410/52825
May 01 21:28:43 volumio-x86 haveged[683]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B):  last entropy estimate 8.00369
May 01 21:28:43 volumio-x86 haveged[683]: haveged: fills: 0, generated: 0
May 01 21:28:43 volumio-x86 kernel: RTL8211E Gigabit Ethernet r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC)
May 01 21:28:43 volumio-x86 dhcpcd[708]: no interfaces have a carrier
May 01 21:28:43 volumio-x86 dhcpcd[708]: no interfaces have a carrier
May 01 21:28:43 volumio-x86 dhcpcd[708]: forked to background, child pid 788
May 01 21:28:43 volumio-x86 dhcpcd[708]: forked to background, child pid 788
May 01 21:28:43 volumio-x86 systemd[1]: Started Login Service.
May 01 21:28:43 volumio-x86 systemd[1]: Started RPC bind portmap service.
May 01 21:28:43 volumio-x86 systemd[1]: Started triggerhappy global hotkey daemon.
May 01 21:28:43 volumio-x86 systemd[1]: Started DHCP Client Daemon.
May 01 21:28:43 volumio-x86 systemd[1]: Started Save/Restore Sound Card State.
May 01 21:28:43 volumio-x86 wpa_supplicant[724]: Successfully initialized wpa_supplicant
May 01 21:28:43 volumio-x86 avahi-daemon[712]: Successfully called chroot().
May 01 21:28:43 volumio-x86 avahi-daemon[712]: Successfully dropped remaining capabilities.
May 01 21:28:43 volumio-x86 bluetoothd[689]: Starting SDP server
May 01 21:28:43 volumio-x86 bluetoothd[689]: Excluding (cli) sap
May 01 21:28:43 volumio-x86 avahi-daemon[712]: Loading service file /services/volumio.service.
May 01 21:28:43 volumio-x86 systemd[1]: Started Bluetooth service.
May 01 21:28:43 volumio-x86 systemd[1]: Started WPA supplicant.
May 01 21:28:43 volumio-x86 systemd[1]: Started Avahi mDNS/DNS-SD Stack.
May 01 21:28:43 volumio-x86 systemd[1]: Reached target Bluetooth.
May 01 21:28:43 volumio-x86 sh[618]: wlan0=wlan0
May 01 21:28:43 volumio-x86 systemd[1]: Listening on mpd.socket.
May 01 21:28:43 volumio-x86 systemd[1]: Reached target Sound Card.
May 01 21:28:43 volumio-x86 systemd[1]: Reached target RPC Port Mapper.
May 01 21:28:43 volumio-x86 systemd[1]: Reached target Remote File Systems (Pre).
May 01 21:28:43 volumio-x86 systemd[1]: Reached target Remote File Systems.
May 01 21:28:43 volumio-x86 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
May 01 21:28:43 volumio-x86 kernel: Bluetooth: BNEP filters: protocol multicast
May 01 21:28:43 volumio-x86 kernel: Bluetooth: BNEP socket layer initialized
May 01 21:28:43 volumio-x86 bluetoothd[689]: Bluetooth management interface 1.22 initialized
May 01 21:28:43 volumio-x86 dbus-daemon[690]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=0 pid=689 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
May 01 21:28:43 volumio-x86 kernel: Bluetooth: MGMT ver 1.22
May 01 21:28:43 volumio-x86 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
May 01 21:28:44 volumio-x86 sh[629]: eth0: waiting for carrier
May 01 21:28:44 volumio-x86 sh[629]: eth0: carrier acquired
May 01 21:28:44 volumio-x86 dhcpcd[669]: eth0: waiting for carrier
May 01 21:28:44 volumio-x86 dhcpcd[669]: eth0: carrier acquired
May 01 21:28:44 volumio-x86 dhcpcd[788]: eth0: waiting for carrier
May 01 21:28:44 volumio-x86 dhcpcd[788]: wlan0: waiting for carrier
May 01 21:28:44 volumio-x86 kernel: r8169 0000:02:00.0 eth0: Link is Down
May 01 21:28:44 volumio-x86 dhcpcd[788]: eth0: carrier acquired
May 01 21:28:44 volumio-x86 avahi-daemon[712]: Network interface enumeration completed.
May 01 21:28:44 volumio-x86 avahi-daemon[712]: Server startup complete. Host name is volumio-x86.local. Local service cookie is 3922922724.
May 01 21:28:44 volumio-x86 avahi-daemon[712]: Service "Volumio-x86" (/services/volumio.service) successfully established.
May 01 21:28:44 volumio-x86 dhcpcd[788]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09
May 01 21:28:44 volumio-x86 sh[629]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09
May 01 21:28:44 volumio-x86 sh[629]: eth0: IAID 4c:5d:aa:01
May 01 21:28:44 volumio-x86 dhcpcd[788]: eth0: IAID 4c:5d:aa:01
May 01 21:28:44 volumio-x86 dhcpcd[669]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09
May 01 21:28:44 volumio-x86 dhcpcd[669]: eth0: IAID 4c:5d:aa:01
May 01 21:28:44 volumio-x86 sh[629]: eth0: adding address fe80::413:9dda:85b1:6377
May 01 21:28:44 volumio-x86 sh[629]: ipv6_addaddr1: Permission denied
May 01 21:28:44 volumio-x86 sh[629]: eth0: carrier lost
May 01 21:28:44 volumio-x86 dhcpcd[669]: eth0: adding address fe80::413:9dda:85b1:6377
May 01 21:28:44 volumio-x86 dhcpcd[669]: ipv6_addaddr1: Permission denied
May 01 21:28:44 volumio-x86 dhcpcd[669]: eth0: carrier lost
May 01 21:28:44 volumio-x86 dhcpcd[788]: eth0: adding address fe80::413:9dda:85b1:6377
May 01 21:28:44 volumio-x86 dhcpcd[788]: ipv6_addaddr1: Permission denied
May 01 21:28:44 volumio-x86 dhcpcd[788]: eth0: carrier lost
May 01 21:28:44 volumio-x86 volumio-remote-updater[696]: Error: No active session
May 01 21:28:44 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:28:44] [info] asio async_connect error: system:111 (Connection refused)
May 01 21:28:44 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:28:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
May 01 21:28:44 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:28:44] [error] handle_connect error: Underlying Transport Error
May 01 21:28:44 volumio-x86 volumio[711]: Could not open config: /tmp/upmpdcli.conf
May 01 21:28:44 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Date not found in response
May 01 21:28:44 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Retrying in 5 seconds...
May 01 21:28:44 volumio-x86 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
May 01 21:28:44 volumio-x86 systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
May 01 21:28:44 volumio-x86 systemd[1]: Starting Hostname Service...
May 01 21:28:44 volumio-x86 systemd[1]: Started Raise network interfaces.
May 01 21:28:44 volumio-x86 systemd[1]: Reached target Network.
May 01 21:28:44 volumio-x86 systemd[1]: Reached target Network is Online.
May 01 21:28:44 volumio-x86 systemd[1]: Starting LSB: Brings up/down network automatically...
May 01 21:28:44 volumio-x86 systemd[1]: Starting OpenBSD Secure Shell server...
May 01 21:28:44 volumio-x86 systemd[1]: Starting /etc/rc.local Compatibility...
May 01 21:28:44 volumio-x86 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
May 01 21:28:44 volumio-x86 systemd[1]: Starting Music Player Daemon...
May 01 21:28:44 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 01 21:28:44 volumio-x86 systemd[1]: Starting Permit User Sessions...
May 01 21:28:44 volumio-x86 systemd[1]: Starting Samba NMB Daemon...
May 01 21:28:44 volumio-x86 systemd[1]: Starting Network Time Service...
May 01 21:28:44 volumio-x86 systemd[1]: Started /etc/rc.local Compatibility.
May 01 21:28:44 volumio-x86 systemd[1]: Started Permit User Sessions.
May 01 21:28:44 volumio-x86 systemd[1]: iptables.service: Succeeded.
May 01 21:28:44 volumio-x86 dbus-daemon[690]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 01 21:28:44 volumio-x86 systemd[1]: Starting Hold until boot process finishes up...
May 01 21:28:44 volumio-x86 systemd[1]: Starting Terminate Plymouth Boot Screen...
May 01 21:28:44 volumio-x86 systemd[1]: Started Hostname Service.
May 01 21:28:44 volumio-x86 systemd[1]: Received SIGRTMIN+21 from PID 333 (plymouthd).
May 01 21:28:44 volumio-x86 systemd[1]: Received SIGRTMIN+21 from PID 333 (plymouthd).
May 01 21:28:44 volumio-x86 ifplugd[851]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon
May 01 21:28:44 volumio-x86 systemd[1]: plymouth-quit-wait.service: Succeeded.
May 01 21:28:44 volumio-x86 systemd[1]: Started Hold until boot process finishes up.
May 01 21:28:44 volumio-x86 systemd[1]: plymouth-quit.service: Succeeded.
May 01 21:28:45 volumio-x86 systemd[1]: Started Terminate Plymouth Boot Screen.
May 01 21:28:45 volumio-x86 systemd[1]: Started Getty on tty1.
May 01 21:28:45 volumio-x86 systemd[1]: Reached target Login Prompts.
May 01 21:28:45 volumio-x86 ifplugd(eth0)[908]: ifplugd 0.28 initializing.
May 01 21:28:45 volumio-x86 ifplugd(eth0)[908]: Using interface eth0/00:E0:4C:5D:AA:01 with driver  (version: 6.6.32-volumio)
May 01 21:28:45 volumio-x86 ifplugd(eth0)[908]: Using detection mode: SIOCETHTOOL
May 01 21:28:45 volumio-x86 ifplugd(eth0)[908]: Initialization complete, link beat not detected.
May 01 21:28:45 volumio-x86 ifplugd[851]: start eth0...done.
May 01 21:28:45 volumio-x86 systemd[1]: Started LSB: Brings up/down network automatically.
May 01 21:28:45 volumio-x86 loadcpufreq[813]: Loading cpufreq kernel modules...failed.
May 01 21:28:45 volumio-x86 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
May 01 21:28:45 volumio-x86 systemd[1]: Starting LSB: set CPUFreq kernel parameters...
May 01 21:28:45 volumio-x86 sshd[918]: Server listening on 0.0.0.0 port 22.
May 01 21:28:45 volumio-x86 sshd[918]: Server listening on :: port 22.
May 01 21:28:45 volumio-x86 ntpd[866]: ntpd 4.2.8p12@1.3728-o (1): Starting
May 01 21:28:45 volumio-x86 ntpd[866]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
May 01 21:28:45 volumio-x86 ntpd[924]: proto: precision = 0.252 usec (-22)
May 01 21:28:45 volumio-x86 systemd[1]: Started OpenBSD Secure Shell server.
May 01 21:28:45 volumio-x86 systemd[1]: Started Network Time Service.
May 01 21:28:45 volumio-x86 ntpd[924]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
May 01 21:28:45 volumio-x86 ntpd[924]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
May 01 21:28:45 volumio-x86 ntpd[924]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 856 days ago
May 01 21:28:45 volumio-x86 ntpd[924]: Listen and drop on 0 v6wildcard [::]:123
May 01 21:28:45 volumio-x86 ntpd[924]: Listen and drop on 1 v4wildcard 0.0.0.0:123
May 01 21:28:45 volumio-x86 ntpd[924]: Listen normally on 2 lo 127.0.0.1:123
May 01 21:28:45 volumio-x86 ntpd[924]: Listening on routing socket on fd #19 for interface updates
May 01 21:28:45 volumio-x86 ntpd[924]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 01 21:28:45 volumio-x86 ntpd[924]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 01 21:28:45 volumio-x86 cpufrequtils[921]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done.
May 01 21:28:45 volumio-x86 systemd[1]: Started LSB: set CPUFreq kernel parameters.
May 01 21:28:45 volumio-x86 sudo[861]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 01 21:28:45 volumio-x86 sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:45 volumio-x86 sudo[861]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
May 01 21:28:45 volumio-x86 sudo[861]: pam_unix(sudo:session): session closed for user root
May 01 21:28:46 volumio-x86 ntpd[924]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
May 01 21:28:46 volumio-x86 systemd[1]: systemd-rfkill.service: Succeeded.
May 01 21:28:46 volumio-x86 nmbd[869]: [2025/05/01 21:28:46.530694,  0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
May 01 21:28:46 volumio-x86 nmbd[869]:   started asyncdns process 933
May 01 21:28:46 volumio-x86 nmbd[869]: [2025/05/01 21:28:46.547348,  0] ../lib/util/become_daemon.c:149(daemon_status)
May 01 21:28:46 volumio-x86 nmbd[869]:   daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
May 01 21:28:46 volumio-x86 nmbd[869]: [2025/05/01 21:28:46.547609,  0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
May 01 21:28:46 volumio-x86 nmbd[869]:   NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
May 01 21:28:46 volumio-x86 dhcpcd[788]: eth0: carrier acquired
May 01 21:28:46 volumio-x86 kernel: r8169 0000:02:00.0 eth0: Link is Up - 1Gbps/Full - flow control off
May 01 21:28:46 volumio-x86 sh[629]: eth0: carrier acquired
May 01 21:28:46 volumio-x86 dhcpcd[669]: eth0: carrier acquired
May 01 21:28:46 volumio-x86 dhcpcd[788]: eth0: IAID 4c:5d:aa:01
May 01 21:28:46 volumio-x86 sh[629]: eth0: IAID 4c:5d:aa:01
May 01 21:28:46 volumio-x86 dhcpcd[669]: eth0: IAID 4c:5d:aa:01
May 01 21:28:46 volumio-x86 dhcpcd[788]: eth0: rebinding lease of 192.168.5.23
May 01 21:28:46 volumio-x86 wireless.js[693]: Cleaning previous...
May 01 21:28:46 volumio-x86 dhcpcd[788]: eth0: soliciting an IPv6 router
May 01 21:28:47 volumio-x86 sudo[943]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
May 01 21:28:47 volumio-x86 sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:47 volumio-x86 sudo[943]: pam_unix(sudo:session): session closed for user root
May 01 21:28:47 volumio-x86 sudo[945]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
May 01 21:28:47 volumio-x86 sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:47 volumio-x86 sudo[945]: pam_unix(sudo:session): session closed for user root
May 01 21:28:47 volumio-x86 ifplugd(eth0)[908]: Link beat detected.
May 01 21:28:47 volumio-x86 wireless.js[693]: Stopped aP
May 01 21:28:47 volumio-x86 ifplugd(eth0)[908]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
May 01 21:28:47 volumio-x86 ntpd[924]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
May 01 21:28:47 volumio-x86 sh[629]: eth0: rebinding lease of 192.168.5.23
May 01 21:28:47 volumio-x86 dhcpcd[669]: eth0: rebinding lease of 192.168.5.23
May 01 21:28:47 volumio-x86 sudo[952]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
May 01 21:28:47 volumio-x86 sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:47 volumio-x86 sh[629]: eth0: soliciting an IPv6 router
May 01 21:28:47 volumio-x86 dhcpcd[669]: eth0: soliciting an IPv6 router
May 01 21:28:47 volumio-x86 sh[629]: eth0: probing address 192.168.5.23/24
May 01 21:28:47 volumio-x86 dhcpcd[669]: eth0: probing address 192.168.5.23/24
May 01 21:28:47 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:47 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:47 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:47 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:47 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:47 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:47 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:47 volumio-x86 sudo[952]: pam_unix(sudo:session): session closed for user root
May 01 21:28:47 volumio-x86 ifplugd(eth0)[908]: client: ifup: waiting for lock on /run/network/ifstate.eth0
May 01 21:28:47 volumio-x86 sudo[959]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
May 01 21:28:47 volumio-x86 sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:47 volumio-x86 sudo[959]: pam_unix(sudo:session): session closed for user root
May 01 21:28:47 volumio-x86 sudo[966]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
May 01 21:28:47 volumio-x86 sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:47 volumio-x86 sudo[966]: pam_unix(sudo:session): session closed for user root
May 01 21:28:47 volumio-x86 sudo[968]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
May 01 21:28:47 volumio-x86 sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:48 volumio-x86 ntpd[924]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
May 01 21:28:49 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:28:49] [info] asio async_connect error: system:111 (Connection refused)
May 01 21:28:49 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:28:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
May 01 21:28:49 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:28:49] [error] handle_connect error: Underlying Transport Error
May 01 21:28:49 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Fetching time from Volumio...
May 01 21:28:49 volumio-x86 ntpd[924]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
May 01 21:28:49 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Date not found in response
May 01 21:28:49 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Retrying in 5 seconds...
May 01 21:28:49 volumio-x86 mpd[931]: May 01 21:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 01 21:28:49 volumio-x86 systemd[1]: Started Music Player Daemon.
May 01 21:28:49 volumio-x86 mpd_monitor.sh[703]: MPD Monitor Service: MPD restarted due to no mpc output.
May 01 21:28:50 volumio-x86 sudo[968]: pam_unix(sudo:session): session closed for user root
May 01 21:28:50 volumio-x86 wireless.js[693]: SETTING APPROPRIATE REG DOMAIN: CN
May 01 21:28:50 volumio-x86 sudo[981]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
May 01 21:28:50 volumio-x86 sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:51 volumio-x86 sudo[981]: pam_unix(sudo:session): session closed for user root
May 01 21:28:51 volumio-x86 sudo[983]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN
May 01 21:28:51 volumio-x86 sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:51 volumio-x86 sudo[983]: pam_unix(sudo:session): session closed for user root
May 01 21:28:51 volumio-x86 wireless.js[693]: SUCCESSFULLY SET NEW REGDOMAIN: CN
May 01 21:28:51 volumio-x86 sudo[990]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
May 01 21:28:51 volumio-x86 sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:51 volumio-x86 sudo[990]: pam_unix(sudo:session): session closed for user root
May 01 21:28:51 volumio-x86 sudo[992]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
May 01 21:28:51 volumio-x86 sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:51 volumio-x86 sudo[992]: pam_unix(sudo:session): session closed for user root
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 avahi-daemon[712]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
May 01 21:28:51 volumio-x86 avahi-daemon[712]: New relevant interface wlan0.IPv4 for mDNS.
May 01 21:28:51 volumio-x86 avahi-daemon[712]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
May 01 21:28:51 volumio-x86 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
May 01 21:28:51 volumio-x86 hostapd[998]: Configuration file: /etc/hostapd/hostapd.conf
May 01 21:28:51 volumio-x86 systemd[1]: Started Samba NMB Daemon.
May 01 21:28:51 volumio-x86 nmbd[869]: [2025/05/01 21:28:51.569076,  0] ../lib/util/become_daemon.c:138(daemon_ready)
May 01 21:28:51 volumio-x86 nmbd[869]:   daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
May 01 21:28:51 volumio-x86 systemd[1]: Starting Samba Winbind Daemon...
May 01 21:28:51 volumio-x86 avahi-daemon[712]: Interface wlan0.IPv4 no longer relevant for mDNS.
May 01 21:28:51 volumio-x86 avahi-daemon[712]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
May 01 21:28:51 volumio-x86 dhcpcd[788]: eth0: probing address 192.168.5.23/24
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
May 01 21:28:51 volumio-x86 avahi-daemon[712]: Withdrawing address record for 192.168.211.1 on wlan0.
May 01 21:28:51 volumio-x86 avahi-daemon[712]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
May 01 21:28:51 volumio-x86 avahi-daemon[712]: New relevant interface wlan0.IPv4 for mDNS.
May 01 21:28:51 volumio-x86 avahi-daemon[712]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
May 01 21:28:51 volumio-x86 hostapd[998]: Using interface wlan0 with hwaddr d8:f2:ca:d7:27:bf and ssid "Volumio-07690"
May 01 21:28:51 volumio-x86 dhcpcd[788]: wlan0: carrier acquired
May 01 21:28:51 volumio-x86 hostapd[998]: wlan0: interface state UNINITIALIZED->ENABLED
May 01 21:28:51 volumio-x86 hostapd[998]: wlan0: AP-ENABLED
May 01 21:28:51 volumio-x86 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
May 01 21:28:51 volumio-x86 dhcpcd[788]: wlan0: IAID ca:d7:27:bf
May 01 21:28:51 volumio-x86 dhcpcd[788]: wlan0: adding address fe80::6cc0:93bd:3eb5:36fe
May 01 21:28:51 volumio-x86 dhcpcd[788]: ipv6_addaddr1: Permission denied
May 01 21:28:51 volumio-x86 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
May 01 21:28:51 volumio-x86 dnsmasq[1003]: dnsmasq: syntax check OK.
May 01 21:28:51 volumio-x86 iwconfig[1005]: Error for wireless request "Set Power Management" (8B2C) :
May 01 21:28:51 volumio-x86 iwconfig[1005]:     SET failed on device wlan0 ; Invalid argument.
May 01 21:28:51 volumio-x86 systemd[1]: wireless.service: Succeeded.
May 01 21:28:51 volumio-x86 systemd[1]: Started Wireless Services.
May 01 21:28:51 volumio-x86 systemd[1]: Started Volumio Backend Module.
May 01 21:28:51 volumio-x86 winbindd[999]: [2025/05/01 21:28:51.959186,  0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
May 01 21:28:51 volumio-x86 systemd[1]: Started Intel SST and HDA soundcard init service.
May 01 21:28:51 volumio-x86 winbindd[999]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
May 01 21:28:51 volumio-x86 systemd[1]: Started Volumio Cpu Tweaker.
May 01 21:28:51 volumio-x86 systemd[1]: Started Start Volumio Kiosk.
May 01 21:28:52 volumio-x86 sh[629]: eth0: leased 192.168.5.23 for 86400 seconds
May 01 21:28:52 volumio-x86 dhcpcd[669]: eth0: leased 192.168.5.23 for 86400 seconds
May 01 21:28:52 volumio-x86 avahi-daemon[712]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.5.23.
May 01 21:28:52 volumio-x86 avahi-daemon[712]: New relevant interface eth0.IPv4 for mDNS.
May 01 21:28:52 volumio-x86 avahi-daemon[712]: Registering new address record for 192.168.5.23 on eth0.IPv4.
May 01 21:28:52 volumio-x86 sh[629]: eth0: adding route to 192.168.5.0/24
May 01 21:28:52 volumio-x86 sh[629]: eth0: adding default route via 192.168.5.1
May 01 21:28:52 volumio-x86 dhcpcd[669]: eth0: adding route to 192.168.5.0/24
May 01 21:28:52 volumio-x86 dhcpcd[669]: eth0: adding default route via 192.168.5.1
May 01 21:28:52 volumio-x86 winbindd[999]: [2025/05/01 21:28:52.033741,  0] ../lib/util/become_daemon.c:138(daemon_ready)
May 01 21:28:52 volumio-x86 winbindd[999]:   daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
May 01 21:28:52 volumio-x86 systemd[1]: Started Samba Winbind Daemon.
May 01 21:28:52 volumio-x86 systemd[1]: Starting Samba SMB Daemon...
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: Setting RT Priority for mpd
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: Not enough cores to set MPD affinity
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: Error setting new values. Common errors:
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: - Do you have proper administration rights? (super-user?)
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: - Is the governor you requested available and modprobed?
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: - Trying to set an invalid policy?
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]: - Trying to set a specific frequency, but userspace governor is not available,
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]:    for example because of hardware which cannot be set to a specific frequency
May 01 21:28:52 volumio-x86 volumio-cpu-tweak[1012]:    or because the userspace governor isn't loaded?
May 01 21:28:52 volumio-x86 dnsmasq[1075]: started, version 2.80 cachesize 150
May 01 21:28:52 volumio-x86 dnsmasq[1075]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile
May 01 21:28:52 volumio-x86 dnsmasq-dhcp[1075]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d
May 01 21:28:52 volumio-x86 dnsmasq[1075]: reading /etc/resolv.conf
May 01 21:28:52 volumio-x86 dnsmasq[1075]: using nameserver 192.168.5.1#53
May 01 21:28:52 volumio-x86 dnsmasq[1075]: using nameserver 208.67.222.222#53
May 01 21:28:52 volumio-x86 dnsmasq[1075]: using nameserver 208.67.220.220#53
May 01 21:28:52 volumio-x86 dnsmasq[1075]: read /etc/hosts - 1 addresses
May 01 21:28:52 volumio-x86 systemd[1]: volumio_cpu_tweak.service: Succeeded.
May 01 21:28:52 volumio-x86 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server.
May 01 21:28:52 volumio-x86 systemd[1]: Reached target Host and Network Name Lookups.
May 01 21:28:52 volumio-x86 ntpd[924]: ntpd exiting on signal 15 (Terminated)
May 01 21:28:52 volumio-x86 systemd[1]: Stopping Network Time Service...
May 01 21:28:52 volumio-x86 dhcpcd[788]: wlan0: soliciting a DHCP lease
May 01 21:28:52 volumio-x86 systemd[1]: ntp.service: Succeeded.
May 01 21:28:52 volumio-x86 systemd[1]: Stopped Network Time Service.
May 01 21:28:52 volumio-x86 systemd[1]: Starting Network Time Service...
May 01 21:28:52 volumio-x86 dhcpcd[788]: wlan0: soliciting an IPv6 router
May 01 21:28:52 volumio-x86 screensaver.sh[695]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused
May 01 21:28:52 volumio-x86 ntpd[1114]: ntpd 4.2.8p12@1.3728-o (1): Starting
May 01 21:28:52 volumio-x86 ntpd[1114]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
May 01 21:28:52 volumio-x86 systemd[1]: Started Network Time Service.
May 01 21:28:52 volumio-x86 startx[1014]: X.Org X Server 1.20.4
May 01 21:28:52 volumio-x86 startx[1014]: X Protocol Version 11, Revision 0
May 01 21:28:52 volumio-x86 startx[1014]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian
May 01 21:28:52 volumio-x86 startx[1014]: Current Operating System: Linux volumio-x86 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64
May 01 21:28:52 volumio-x86 startx[1014]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=3cc03e2d-018e-43c1-b7e6-361cc0a7c20f bootpart=UUID=0A5D-C90D datapart=UUID=de34b9f5-9b78-4162-a289-2de9c46fbb26 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
May 01 21:28:52 volumio-x86 startx[1014]: Build Date: 18 December 2021  09:05:36AM
May 01 21:28:52 volumio-x86 startx[1014]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support)
May 01 21:28:52 volumio-x86 startx[1014]: Current version of pixman: 0.36.0
May 01 21:28:52 volumio-x86 startx[1014]:         Before reporting problems, check http://wiki.x.org
May 01 21:28:52 volumio-x86 startx[1014]:         to make sure that you have the latest version.
May 01 21:28:52 volumio-x86 startx[1014]: Markers: (--) probed, (**) from config file, (==) default setting,
May 01 21:28:52 volumio-x86 startx[1014]:         (++) from command line, (!!) notice, (II) informational,
May 01 21:28:52 volumio-x86 startx[1014]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
May 01 21:28:52 volumio-x86 startx[1014]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu May  1 21:28:52 2025
May 01 21:28:52 volumio-x86 ntpd[1123]: proto: precision = 0.256 usec (-22)
May 01 21:28:52 volumio-x86 sh[629]: forked to background, child pid 1124
May 01 21:28:52 volumio-x86 dhcpcd[669]: forked to background, child pid 1124
May 01 21:28:52 volumio-x86 ntpd[1123]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
May 01 21:28:52 volumio-x86 ntpd[1123]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
May 01 21:28:52 volumio-x86 ntpd[1123]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 856 days ago
May 01 21:28:52 volumio-x86 ntpd[1123]: Listen and drop on 0 v6wildcard [::]:123
May 01 21:28:52 volumio-x86 ntpd[1123]: Listen and drop on 1 v4wildcard 0.0.0.0:123
May 01 21:28:52 volumio-x86 ntpd[1123]: Listen normally on 2 lo 127.0.0.1:123
May 01 21:28:52 volumio-x86 ntpd[1123]: Listen normally on 3 eth0 192.168.5.23:123
May 01 21:28:52 volumio-x86 ntpd[1123]: Listen normally on 4 wlan0 192.168.211.1:123
May 01 21:28:52 volumio-x86 ntpd[1123]: Listening on routing socket on fd #21 for interface updates
May 01 21:28:52 volumio-x86 ntpd[1123]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 01 21:28:52 volumio-x86 ntpd[1123]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 01 21:28:53 volumio-x86 startx[1014]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
May 01 21:28:53 volumio-x86 ifplugd(eth0)[908]: client: ifup: interface eth0 already configured
May 01 21:28:53 volumio-x86 sh[629]: eth0=eth0
May 01 21:28:53 volumio-x86 ifplugd(eth0)[908]: Program executed successfully.
May 01 21:28:53 volumio-x86 startx[1014]: (II) [KMS] Kernel modesetting enabled.
May 01 21:28:53 volumio-x86 winbindd[999]: [2025/05/01 21:28:53.575333,  0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv)
May 01 21:28:53 volumio-x86 winbindd[999]:   res_names->count = 3, expected 4
May 01 21:28:53 volumio-x86 smbd[1053]: [2025/05/01 21:28:53.650478,  0] ../lib/util/become_daemon.c:138(daemon_ready)
May 01 21:28:53 volumio-x86 systemd[1]: Started Samba SMB Daemon.
May 01 21:28:53 volumio-x86 smbd[1053]:   daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
May 01 21:28:53 volumio-x86 ntpd[1123]: Soliciting pool server 84.16.73.33
May 01 21:28:54 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Fetching time from Volumio...
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]: amixer: Unable to find simple control 'Surround',0
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]: amixer: Unable to find simple control 'Center',0
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]: amixer: Unable to find simple control 'LFE',0
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]: amixer: Unable to find simple control 'IEC958',16
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]: amixer: Unable to find simple control 'Front',0
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]: amixer: Unable to find simple control 'Headphone',0
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]: Simple mixer control 'Master',0
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]:   Capabilities: pvolume pvolume-joined pswitch pswitch-joined
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]:   Playback channels: Mono
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]:   Limits: Playback 0 - 64
May 01 21:28:54 volumio-x86 soundcard-init.sh[1011]:   Mono: Playback 48 [75%] [-16.00dB] [on]
May 01 21:28:54 volumio-x86 ntpd[1123]: Soliciting pool server 45.76.221.157
May 01 21:28:54 volumio-x86 systemd[1]: soundcard-init.service: Succeeded.
May 01 21:28:55 volumio-x86 volumio-time-update[692]: volumio-time-update-util: Setting system time to: 2025-05-01 21:28:55
May 01 21:28:55 volumio-x86 sudo[1253]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-01 21:28:55
May 01 21:28:55 volumio-x86 sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:28:55 volumio-x86 dbus-daemon[690]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1254 comm="timedatectl set-time 2025-05-01 21:28:55 ")
May 01 21:28:55 volumio-x86 systemd[1]: Starting Time & Date Service...
May 01 21:28:55 volumio-x86 dbus-daemon[690]: [system] Successfully activated service 'org.freedesktop.timedate1'
May 01 21:28:55 volumio-x86 systemd[1]: Started Time & Date Service.
May 01 21:28:55 volumio-x86 systemd-timedated[1255]: Changed local time to Thu May  1 21:28:55 2025
May 01 21:28:55 volumio-x86 sudo[1253]: pam_unix(sudo:session): session closed for user root
May 01 21:28:55 volumio-x86 volumio-time-update[692]: volumio-time-update-util: System time updated successfully.
May 01 21:28:55 volumio-x86 systemd[1]: Started Volumio Time Update Utility.
May 01 21:28:55 volumio-x86 systemd[1]: Reached target Multi-User System.
May 01 21:28:55 volumio-x86 systemd[1]: Reached target Graphical Interface.
May 01 21:28:55 volumio-x86 systemd[1]: Starting Update UTMP about System Runlevel Changes...
May 01 21:28:55 volumio-x86 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
May 01 21:28:55 volumio-x86 systemd[1]: Started Update UTMP about System Runlevel Changes.
May 01 21:28:55 volumio-x86 systemd[1]: Startup finished in 24.528s (kernel) + 19.601s (userspace) = 44.129s.
May 01 21:28:55 volumio-x86 ntpd[1123]: Soliciting pool server 162.159.200.123
May 01 21:28:55 volumio-x86 ntpd[1123]: Soliciting pool server 84.16.73.33
May 01 21:28:56 volumio-x86 dhcpcd[788]: eth0: leased 192.168.5.23 for 86400 seconds
May 01 21:28:56 volumio-x86 dhcpcd[788]: eth0: adding route to 192.168.5.0/24
May 01 21:28:56 volumio-x86 dhcpcd[788]: eth0: adding default route via 192.168.5.1
May 01 21:28:56 volumio-x86 ntpd[1123]: Soliciting pool server 162.159.200.1
May 01 21:28:56 volumio-x86 ntpd[1123]: Soliciting pool server 193.182.111.143
May 01 21:28:56 volumio-x86 volumio[1007]: info: -------------------------------------------
May 01 21:28:56 volumio-x86 volumio[1007]: info: -----            Volumio3              ----
May 01 21:28:56 volumio-x86 volumio[1007]: info: -------------------------------------------
May 01 21:28:56 volumio-x86 volumio[1007]: info: -----          System startup          ----
May 01 21:28:56 volumio-x86 volumio[1007]: info: -------------------------------------------
May 01 21:28:56 volumio-x86 systemd[1]: Stopping Network Time Service...
May 01 21:28:56 volumio-x86 ntpd[1123]: ntpd exiting on signal 15 (Terminated)
May 01 21:28:56 volumio-x86 ntpd[1123]: 45.76.221.157 local addr 192.168.5.23 -> 
May 01 21:28:56 volumio-x86 ntpd[1123]: 162.159.200.123 local addr 192.168.5.23 -> 
May 01 21:28:56 volumio-x86 ntpd[1123]: 84.16.73.33 local addr 192.168.5.23 -> 
May 01 21:28:56 volumio-x86 systemd[1]: ntp.service: Succeeded.
May 01 21:28:56 volumio-x86 systemd[1]: Stopped Network Time Service.
May 01 21:28:56 volumio-x86 systemd[1]: Starting Network Time Service...
May 01 21:28:56 volumio-x86 ntpd[1319]: ntpd 4.2.8p12@1.3728-o (1): Starting
May 01 21:28:56 volumio-x86 ntpd[1319]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
May 01 21:28:56 volumio-x86 systemd[1]: Started Network Time Service.
May 01 21:28:56 volumio-x86 ntpd[1325]: proto: precision = 0.255 usec (-22)
May 01 21:28:56 volumio-x86 ntpd[1325]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
May 01 21:28:56 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:28:56] [connect] Successful connection
May 01 21:28:56 volumio-x86 ntpd[1325]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
May 01 21:28:56 volumio-x86 ntpd[1325]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 856 days ago
May 01 21:28:56 volumio-x86 ntpd[1325]: Listen and drop on 0 v6wildcard [::]:123
May 01 21:28:56 volumio-x86 ntpd[1325]: Listen and drop on 1 v4wildcard 0.0.0.0:123
May 01 21:28:56 volumio-x86 ntpd[1325]: Listen normally on 2 lo 127.0.0.1:123
May 01 21:28:56 volumio-x86 ntpd[1325]: Listen normally on 3 eth0 192.168.5.23:123
May 01 21:28:56 volumio-x86 ntpd[1325]: Listen normally on 4 wlan0 192.168.211.1:123
May 01 21:28:56 volumio-x86 ntpd[1325]: Listening on routing socket on fd #21 for interface updates
May 01 21:28:56 volumio-x86 ntpd[1325]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 01 21:28:56 volumio-x86 ntpd[1325]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 01 21:28:57 volumio-x86 dhcpcd[788]: wlan0: probing for an IPv4LL address
May 01 21:28:57 volumio-x86 ntpd[1325]: Soliciting pool server 84.16.73.33
May 01 21:28:58 volumio-x86 ntpd[1325]: Soliciting pool server 45.76.221.157
May 01 21:28:58 volumio-x86 volumio[1007]: info: MYVOLUMIO Environment detected
May 01 21:28:59 volumio-x86 ntpd[1325]: Soliciting pool server 162.159.200.123
May 01 21:28:59 volumio-x86 ntpd[1325]: Soliciting pool server 162.159.200.123
May 01 21:28:59 volumio-x86 volumio[1007]: info: Plugin folders cleanup
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning into folder /volumio/app/plugins/
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category audio_interface
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category miscellanea
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category music_service
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category plugins.json
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category system_controller
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category user_interface
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning into folder /data/plugins/
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category music_service
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category system_controller
May 01 21:28:59 volumio-x86 volumio[1007]: info: Scanning category user_interface
May 01 21:28:59 volumio-x86 volumio[1007]: info: Plugin folders cleanup completed
May 01 21:28:59 volumio-x86 volumio[1007]: info: -------------------------------------------
May 01 21:28:59 volumio-x86 volumio[1007]: info: -----      Core plugins startup        ----
May 01 21:28:59 volumio-x86 volumio[1007]: info: -------------------------------------------
May 01 21:28:59 volumio-x86 volumio[1007]: info: Loading plugins from folder /volumio/app/plugins/
May 01 21:28:59 volumio-x86 volumio[1007]: info: Adding plugin upnp to MyMusic Plugins
May 01 21:28:59 volumio-x86 volumio[1007]: info: Adding plugin airplay_emulation to MyMusic Plugins
May 01 21:28:59 volumio-x86 volumio[1007]: info: Adding plugin upnp_browser to MyMusic Plugins
May 01 21:28:59 volumio-x86 volumio[1007]: info: Loading plugins from folder /data/plugins/
May 01 21:28:59 volumio-x86 volumio[1007]: info: Loading plugin "system"...
May 01 21:29:00 volumio-x86 volumio[1007]: info: Loading plugin "appearance"...
May 01 21:29:00 volumio-x86 ntpd[1325]: Soliciting pool server 162.159.200.1
May 01 21:29:01 volumio-x86 ntpd[1325]: Soliciting pool server 202.118.1.130
May 01 21:29:02 volumio-x86 dhcpcd[788]: wlan0: using IPv4LL address 169.254.204.56
May 01 21:29:02 volumio-x86 dhcpcd[788]: wlan0: adding route to 169.254.0.0/16
May 01 21:29:02 volumio-x86 avahi-daemon[712]: Registering new address record for 169.254.204.56 on wlan0.IPv4.
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "network"...
May 01 21:29:03 volumio-x86 volumio[1007]: info: Refreshing Cached IP Addresses
May 01 21:29:03 volumio-x86 sudo[1393]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 21:29:03 volumio-x86 sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:03 volumio-x86 sudo[1393]: pam_unix(sudo:session): session closed for user root
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "services"...
May 01 21:29:03 volumio-x86 sudo[1395]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 21:29:03 volumio-x86 sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "alsa_controller"...
May 01 21:29:03 volumio-x86 sudo[1395]: pam_unix(sudo:session): session closed for user root
May 01 21:29:03 volumio-x86 sudo[1413]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
May 01 21:29:03 volumio-x86 sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:03 volumio-x86 sudo[1413]: pam_unix(sudo:session): session closed for user root
May 01 21:29:03 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "wizard"...
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "networkfs"...
May 01 21:29:03 volumio-x86 volumio[1007]: info: Starting Udev Watcher for removable devices
May 01 21:29:03 volumio-x86 sudo[1434]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local
May 01 21:29:03 volumio-x86 sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:03 volumio-x86 ntpd[1325]: Listen normally on 5 wlan0 169.254.204.56:123
May 01 21:29:03 volumio-x86 ntpd[1325]: new interface(s) found: waking up resolver
May 01 21:29:03 volumio-x86 volumio[1007]: info: Ignoring mount for partition: boot
May 01 21:29:03 volumio-x86 volumio[1007]: info: Ignoring mount for partition: volumio
May 01 21:29:03 volumio-x86 volumio[1007]: info: Ignoring mount for partition: volumio_data
May 01 21:29:03 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "volumio_command_line_client"...
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "upnp"...
May 01 21:29:03 volumio-x86 volumio[1007]: info: [1746106143705] Starting Upmpd Daemon
May 01 21:29:03 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "my_music"...
May 01 21:29:03 volumio-x86 kernel: FS-Cache: Loaded
May 01 21:29:03 volumio-x86 kernel: Key type dns_resolver registered
May 01 21:29:03 volumio-x86 volumio[1007]: info: Loading plugin "mpd"...
May 01 21:29:03 volumio-x86 kernel: Key type cifs.spnego registered
May 01 21:29:03 volumio-x86 kernel: Key type cifs.idmap registered
May 01 21:29:03 volumio-x86 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.
May 01 21:29:03 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
May 01 21:29:04 volumio-x86 sudo[1434]: pam_unix(sudo:session): session closed for user root
May 01 21:29:04 volumio-x86 volumio[1007]: info: Loading plugin "upnp_browser"...
May 01 21:29:05 volumio-x86 volumio[1007]: info: Loading plugin "alarm-clock"...
May 01 21:29:05 volumio-x86 volumio[1007]: info: Loading plugin "airplay_emulation"...
May 01 21:29:05 volumio-x86 volumio[1007]: info: Starting Shairport Sync
May 01 21:29:05 volumio-x86 volumio[1007]: info: Loading plugin "last_100"...
May 01 21:29:05 volumio-x86 volumio[1007]: info: Loading plugin "webradio"...
May 01 21:29:05 volumio-x86 volumio[1007]: info: Loading plugin "i2s_dacs"...
May 01 21:29:05 volumio-x86 volumio[1007]: info: I2S DAC not set, start Auto-detection
May 01 21:29:05 volumio-x86 volumio[1007]: info: Loading plugin "volumiodiscovery"...
May 01 21:29:05 volumio-x86 volumio[1007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 01 21:29:05 volumio-x86 volumio[1007]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 21:29:05 volumio-x86 node[1007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 01 21:29:05 volumio-x86 volumio[1007]: *** WARNING *** For more information see 
May 01 21:29:05 volumio-x86 volumio[1007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 01 21:29:05 volumio-x86 volumio[1007]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 21:29:05 volumio-x86 volumio[1007]: *** WARNING *** For more information see 
May 01 21:29:05 volumio-x86 node[1007]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 21:29:05 volumio-x86 node[1007]: *** WARNING *** For more information see 
May 01 21:29:05 volumio-x86 node[1007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 01 21:29:05 volumio-x86 node[1007]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 01 21:29:05 volumio-x86 node[1007]: *** WARNING *** For more information see 
May 01 21:29:05 volumio-x86 volumio[1007]: info: Applying required configuration parameters for plugin volumiodiscovery
May 01 21:29:05 volumio-x86 volumio[1007]: info: Discovery: Started advertising with name: Volumio-x86
May 01 21:29:06 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 01 21:29:06 volumio-x86 volumio[1007]: info: Plugin calmradio is not enabled
May 01 21:29:06 volumio-x86 volumio[1007]: info: Plugin jellyfin is not enabled
May 01 21:29:06 volumio-x86 volumio[1007]: info: Plugin spop is not enabled
May 01 21:29:06 volumio-x86 volumio[1007]: info: Plugin youtube2 is not enabled
May 01 21:29:06 volumio-x86 volumio[1007]: info: Plugin ytcr is not enabled
May 01 21:29:06 volumio-x86 volumio[1007]: info: Plugin ytmusic is not enabled
May 01 21:29:06 volumio-x86 volumio[1007]: info: Loading plugin "autostart"...
May 01 21:29:08 volumio-x86 volumio[1007]: info: Applying required configuration parameters for plugin autostart
May 01 21:29:08 volumio-x86 volumio[1007]: info: AutoStart - onVolumioStart -  read config.json
May 01 21:29:08 volumio-x86 volumio[1007]: info: Plugin now_playing is not enabled
May 01 21:29:08 volumio-x86 volumio[1007]: info: Loading plugin "peppy_screensaver"...
May 01 21:29:09 volumio-x86 volumio[1007]: info: Applying required configuration parameters for plugin peppy_screensaver
May 01 21:29:09 volumio-x86 volumio[1007]: info: Loading plugin "outputs"...
May 01 21:29:09 volumio-x86 volumio[1007]: info: Loading plugin "albumart"...
May 01 21:29:09 volumio-x86 volumio[1007]: info: Plugin example_plugin is not enabled
May 01 21:29:09 volumio-x86 volumio[1007]: info: Loading plugin "inputs"...
May 01 21:29:09 volumio-x86 volumio[1007]: info: Loading plugin "updater_comm"...
May 01 21:29:10 volumio-x86 volumio[1007]: Forking 1 albumart workers
May 01 21:29:10 volumio-x86 volumio[1007]: info: Plugin mpdemulation is not enabled
May 01 21:29:10 volumio-x86 volumio[1007]: info: Loading plugin "rest_api"...
May 01 21:29:10 volumio-x86 volumio[1007]: info: Loading plugin "websocket"...
May 01 21:29:10 volumio-x86 volumio[1007]: info: Starting Socket.io Server version 2.3.0
May 01 21:29:10 volumio-x86 volumio[1007]: info: Plugin 80s80s is not enabled
May 01 21:29:10 volumio-x86 volumio[1007]: info: Plugin RoonBridge is not enabled
May 01 21:29:10 volumio-x86 volumio[1007]: info: Loading plugin "minidlna"...
May 01 21:29:10 volumio-x86 volumio[1007]: info: Applying required configuration parameters for plugin minidlna
May 01 21:29:10 volumio-x86 volumio[1007]: info: Plugin podcast is not enabled
May 01 21:29:10 volumio-x86 volumio[1007]: info: Plugin Systeminfo is not enabled
May 01 21:29:10 volumio-x86 volumio[1007]: info: Loading i18n strings for locale zh
May 01 21:29:10 volumio-x86 volumio[1007]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 01 21:29:10 volumio-x86 volumio[1007]: Updating browse sources language
May 01 21:29:10 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 21:29:10 volumio-x86 volumio[1007]: Starting albumart workers
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::initPlayerControls
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: Express server listening on port 3000
May 01 21:29:11 volumio-x86 volumio[1007]:  [Metrics]  WebUI:  14s 726.88ms 
May 01 21:29:11 volumio-x86 volumio[1007]: info: Setting Device type: x86
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreStateMachine::resetVolumioState
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreStateMachine::getcurrentVolume
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioRetrievevolume
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:11 volumio-x86 volumio[1007]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
May 01 21:29:11 volumio-x86 volumio[1007]: info: Completed loading Core Plugins
May 01 21:29:11 volumio-x86 volumio[1007]: info: Preparing to generate the ALSA configuration file
May 01 21:29:11 volumio-x86 volumio[1007]: info: Volumio Network Manager: Network status updated: 1
May 01 21:29:11 volumio-x86 volumio[1007]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
May 01 21:29:11 volumio-x86 volumio[1007]: wlan0     Interface doesn't support scanning : Operation not supported
May 01 21:29:11 volumio-x86 volumio[1007]: info: Cannot use regular scanning, forcing with ap-force
May 01 21:29:11 volumio-x86 sudo[1546]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
May 01 21:29:11 volumio-x86 sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:11 volumio-x86 sudo[1546]: pam_unix(sudo:session): session closed for user root
May 01 21:29:11 volumio-x86 volumio[1007]: command failed: Operation not supported (-95)
May 01 21:29:11 volumio-x86 volumio[1007]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
May 01 21:29:11 volumio-x86 volumio[1007]: command failed: Operation not supported (-95)
May 01 21:29:11 volumio-x86 volumio[1007]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
May 01 21:29:11 volumio-x86 volumio[1007]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
May 01 21:29:11 volumio-x86 volumio[1007]: info: Reading ALSA contributions from plugins.
May 01 21:29:11 volumio-x86 volumio[1007]: Unable to parse:
May 01 21:29:11 volumio-x86 volumio[1007]:  Simple mixer control 'IEC958',0
May 01 21:29:11 volumio-x86 volumio[1007]:   Capabilities: pswitch pswitch-joined
May 01 21:29:11 volumio-x86 volumio[1007]:   Playback channels: Mono
May 01 21:29:11 volumio-x86 volumio[1007]:   Mono: Playback [on]
May 01 21:29:11 volumio-x86 volumio[1007]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:11 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:11 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
May 01 21:29:11 volumio-x86 volumio[1007]: info: Reloading queue from file
May 01 21:29:11 volumio-x86 volumio[1007]: Unable to parse:
May 01 21:29:11 volumio-x86 volumio[1007]:  Simple mixer control 'IEC958',0
May 01 21:29:11 volumio-x86 volumio[1007]:   Capabilities: pswitch pswitch-joined
May 01 21:29:11 volumio-x86 volumio[1007]:   Playback channels: Mono
May 01 21:29:11 volumio-x86 volumio[1007]:   Mono: Playback [on]
May 01 21:29:11 volumio-x86 volumio[1007]: info: VolumeController:: Volume=undefined Mute =false
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreStateMachine::pushState
May 01 21:29:11 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPushState
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreStateMachine::updateTrackBlock
May 01 21:29:11 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrackBlock
May 01 21:29:11 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioRetrievevolume
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreStateMachine::setRepeat null single undefined
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreStateMachine::pushState
May 01 21:29:12 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPushState
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreStateMachine::setRandom null
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreStateMachine::pushState
May 01 21:29:12 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPushState
May 01 21:29:12 volumio-x86 volumio[1007]: Unable to parse:
May 01 21:29:12 volumio-x86 volumio[1007]:  Simple mixer control 'IEC958',0
May 01 21:29:12 volumio-x86 volumio[1007]:   Capabilities: pswitch pswitch-joined
May 01 21:29:12 volumio-x86 volumio[1007]:   Playback channels: Mono
May 01 21:29:12 volumio-x86 volumio[1007]:   Mono: Playback [on]
May 01 21:29:12 volumio-x86 volumio[1007]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
May 01 21:29:12 volumio-x86 volumio[1007]: info: Discovery: adding 842803b6-f2c1-4d6c-b97d-d9db7c8587d2
May 01 21:29:12 volumio-x86 volumio[1007]: info: Discovery: Found device Volumio-x86
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:12 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:12 volumio-x86 volumio[1007]: info: Discovery: this is already registered,  842803b6-f2c1-4d6c-b97d-d9db7c8587d2
May 01 21:29:12 volumio-x86 volumio[1007]: info: Discovery: Found device Volumio-x86
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:12 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:12 volumio-x86 volumio[1007]: Unable to parse:
May 01 21:29:12 volumio-x86 volumio[1007]:  Simple mixer control 'IEC958',0
May 01 21:29:12 volumio-x86 volumio[1007]:   Capabilities: pswitch pswitch-joined
May 01 21:29:12 volumio-x86 volumio[1007]:   Playback channels: Mono
May 01 21:29:12 volumio-x86 volumio[1007]:   Mono: Playback [on]
May 01 21:29:12 volumio-x86 volumio[1007]: info: VolumeController:: Volume=undefined Mute =false
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreStateMachine::pushState
May 01 21:29:12 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPushState
May 01 21:29:12 volumio-x86 volumio[1007]: info: Asound.conf file unchanged, so no further update is needed
May 01 21:29:12 volumio-x86 volumio[1007]: info: Output device has changed, restarting MPD
May 01 21:29:12 volumio-x86 volumio[1007]: info: Output device has changed, restarting Shairport Sync
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:12 volumio-x86 sudo[1569]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 01 21:29:12 volumio-x86 sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:12 volumio-x86 sudo[1569]: pam_unix(sudo:session): session closed for user root
May 01 21:29:12 volumio-x86 sudo[1571]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 01 21:29:12 volumio-x86 sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:12 volumio-x86 volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 01 21:29:12 volumio-x86 volumio[1007]: info: ___________ START PLUGINS ___________
May 01 21:29:12 volumio-x86 volumio[1007]: info: ControllerMpd::onStart: Initializing MPD
May 01 21:29:12 volumio-x86 volumio[1007]: info: Creating MPD Configuration file
May 01 21:29:12 volumio-x86 systemd[1]: Stopping Music Player Daemon...
May 01 21:29:12 volumio-x86 systemd[1]: systemd-fsckd.service: Succeeded.
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 01 21:29:12 volumio-x86 volumio[1007]: info: [1746106152589] CoreMusicLibrary::Adding element 媒体服务器
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 21:29:12 volumio-x86 sudo[1578]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 01 21:29:12 volumio-x86 systemd[1]: mpd.service: Succeeded.
May 01 21:29:12 volumio-x86 systemd[1]: Stopped Music Player Daemon.
May 01 21:29:12 volumio-x86 sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:12 volumio-x86 sudo[1580]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 01 21:29:12 volumio-x86 sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:12 volumio-x86 systemd[1]: Starting Music Player Daemon...
May 01 21:29:12 volumio-x86 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
May 01 21:29:12 volumio-x86 systemd[1]: mpd.service: Succeeded.
May 01 21:29:12 volumio-x86 systemd[1]: Stopped Music Player Daemon.
May 01 21:29:12 volumio-x86 sudo[1578]: pam_unix(sudo:session): session closed for user root
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:12 volumio-x86 systemd[1]: Starting Music Player Daemon...
May 01 21:29:12 volumio-x86 volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:12 volumio-x86 sudo[1584]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 01 21:29:12 volumio-x86 sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:12 volumio-x86 sudo[1584]: pam_unix(sudo:session): session closed for user root
May 01 21:29:12 volumio-x86 volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 01 21:29:12 volumio-x86 volumio[1007]: info: [1746106152803] CoreMusicLibrary::Adding element Last_100
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 01 21:29:12 volumio-x86 volumio[1007]: info: [1746106152806] CoreMusicLibrary::Adding element Webradio
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 01 21:29:12 volumio-x86 volumio[1007]: info: Initializing BBC Radios
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 01 21:29:12 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:12 volumio-x86 volumio[1007]: info: AutoStart - onStart
May 01 21:29:12 volumio-x86 volumio[1007]: info: Loading i18n strings for locale zh
May 01 21:29:12 volumio-x86 volumio[1007]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 01 21:29:12 volumio-x86 volumio[1007]: info: /tmp/myfifo created
May 01 21:29:12 volumio-x86 volumio[1007]: info: /tmp/myfifosa created
May 01 21:29:13 volumio-x86 sudo[1608]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
May 01 21:29:13 volumio-x86 sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:13 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:29:13] [connect] Successful connection
May 01 21:29:13 volumio-x86 sudo[1608]: pam_unix(sudo:session): session closed for user root
May 01 21:29:13 volumio-x86 volumio[1007]: info: snd-dummy loaded
May 01 21:29:13 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:13 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:13 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:13 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync
May 01 21:29:13 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:13 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:13 volumio-x86 volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 01 21:29:13 volumio-x86 volumio[1007]: info: Loading i18n strings for locale zh
May 01 21:29:13 volumio-x86 volumio[1007]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 01 21:29:13 volumio-x86 volumio[1007]: info: Volumio Calling Home
May 01 21:29:13 volumio-x86 volumio[1007]: info: MPD Permissions set
May 01 21:29:13 volumio-x86 volumio[1007]: info: MPD Permissions set
May 01 21:29:14 volumio-x86 volumio-remote-updater[696]: [2025-05-01 21:29:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746106153 101
May 01 21:29:14 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:14 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:14 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 sudo[1636]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl
May 01 21:29:14 volumio-x86 sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 01 21:29:14 volumio-x86 sudo[1636]: pam_unix(sudo:session): session closed for user root
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 mpd[1592]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 01 21:29:14 volumio-x86 mpd[1592]: output: No 'audio_output' defined in config file
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 01 21:29:14 volumio-x86 mpd[1592]: couldn't open play stream: No such file or directory
May 01 21:29:14 volumio-x86 mpd[1592]: sndio_output: Error opening default sndio device
May 01 21:29:14 volumio-x86 mpd[1592]: output: Successfully detected a alsa audio device
May 01 21:29:14 volumio-x86 mpd[1592]: zeroconf: No global port, disabling zeroconf
May 01 21:29:14 volumio-x86 systemd[1]: Started Music Player Daemon.
May 01 21:29:14 volumio-x86 sudo[1580]: pam_unix(sudo:session): session closed for user root
May 01 21:29:14 volumio-x86 sudo[1571]: pam_unix(sudo:session): session closed for user root
May 01 21:29:14 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:14 volumio-x86 volumio[1007]: info: minidlna: Found miniDLNA version 1.2.1
May 01 21:29:14 volumio-x86 volumio[1007]: info: minidlna: Starting minidlna.service
May 01 21:29:14 volumio-x86 sudo[1667]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service
May 01 21:29:14 volumio-x86 sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server.
May 01 21:29:15 volumio-x86 sudo[1667]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 sudo[1670]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 21:29:15 volumio-x86 sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 sudo[1673]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 21:29:15 volumio-x86 sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 sudo[1670]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 sudo[1673]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile
May 01 21:29:15 volumio-x86 volumio[1007]: info: Volumio called home
May 01 21:29:15 volumio-x86 sudo[1679]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 01 21:29:15 volumio-x86 sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 sudo[1679]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 sudo[1687]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 21:29:15 volumio-x86 sudo[1683]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 21:29:15 volumio-x86 sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 sudo[1683]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 sudo[1687]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 volumio[1007]: info: MPD Permissions set
May 01 21:29:15 volumio-x86 volumio[1007]: info: MPD running with PID1592
May 01 21:29:15 volumio-x86 volumio[1007]:  ,establishing connection
May 01 21:29:15 volumio-x86 volumio[1007]: info: minidlna: systemctl start minidlna.service succeeded.
May 01 21:29:15 volumio-x86 volumio[1007]: info: Completed starting Core Plugins
May 01 21:29:15 volumio-x86 volumio[1007]: info: -------------------------------------------
May 01 21:29:15 volumio-x86 volumio[1007]: info: -----    MyVolumio plugins startup     ----
May 01 21:29:15 volumio-x86 volumio[1007]: info: -------------------------------------------
May 01 21:29:15 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Fetching plans data....
May 01 21:29:15 volumio-x86 volumio[1007]: info: Starting Shairport Sync
May 01 21:29:15 volumio-x86 volumio[1007]: info: Starting Shairport Sync
May 01 21:29:15 volumio-x86 sudo[1693]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 01 21:29:15 volumio-x86 sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 volumio[1007]: info: Starting Shairport Sync
May 01 21:29:15 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 01 21:29:15 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
May 01 21:29:15 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 volumio[1007]: info: Starting Shairport Sync
May 01 21:29:15 volumio-x86 sudo[1693]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 sudo[1696]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 01 21:29:15 volumio-x86 sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 sudo[1699]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 01 21:29:15 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 01 21:29:15 volumio-x86 sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
May 01 21:29:15 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 sudo[1696]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 sudo[1704]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 01 21:29:15 volumio-x86 sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:15 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 01 21:29:15 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
May 01 21:29:15 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 systemd[1]: systemd-hostnamed.service: Succeeded.
May 01 21:29:15 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 sudo[1699]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 01 21:29:15 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
May 01 21:29:15 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
May 01 21:29:15 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 01 21:29:15 volumio-x86 sudo[1704]: pam_unix(sudo:session): session closed for user root
May 01 21:29:15 volumio-x86 volumio[1007]: error: MPD error: The expression evaluated to a falsy value:
May 01 21:29:15 volumio-x86 volumio[1007]:   assert.ok(self.idling)
May 01 21:29:15 volumio-x86 volumio[1007]: error: The expression evaluated to a falsy value:
May 01 21:29:15 volumio-x86 volumio[1007]:   assert.ok(self.idling)
May 01 21:29:15 volumio-x86 volumio[1007]: error: MPD error: The expression evaluated to a falsy value:
May 01 21:29:15 volumio-x86 volumio[1007]:   assert.ok(self.idling)
May 01 21:29:15 volumio-x86 volumio[1007]: error: The expression evaluated to a falsy value:
May 01 21:29:15 volumio-x86 volumio[1007]:   assert.ok(self.idling)
May 01 21:29:15 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:15 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:15 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
May 01 21:29:15 volumio-x86 sudo[1716]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 01 21:29:15 volumio-x86 sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:16 volumio-x86 systemd[1]: Stopping Music Player Daemon...
May 01 21:29:16 volumio-x86 systemd[1]: mpd.service: Succeeded.
May 01 21:29:16 volumio-x86 systemd[1]: Stopped Music Player Daemon.
May 01 21:29:16 volumio-x86 volumio[1007]: info: Shairport-Sync Started
May 01 21:29:16 volumio-x86 volumio[1007]: Error adding Membership: Error: addMembership EINVAL
May 01 21:29:16 volumio-x86 volumio[1007]: info: Shairport-Sync Started
May 01 21:29:16 volumio-x86 systemd[1]: Starting Music Player Daemon...
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 01 21:29:16 volumio-x86 sudo[1719]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 01 21:29:16 volumio-x86 volumio[1007]: error: updateQueue error: null
May 01 21:29:16 volumio-x86 sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:16 volumio-x86 sudo[1719]: pam_unix(sudo:session): session closed for user root
May 01 21:29:16 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to 192.168.5.23 from 192.168.5.75 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
May 01 21:29:16 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:16 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:16 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:16 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:16 volumio-x86 sudo[1729]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 01 21:29:16 volumio-x86 sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetVisibleSources
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:16 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 01 21:29:16 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:16 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:16 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:16 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:16 volumio-x86 volumio[1007]: info: Listing playlists
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 01 21:29:16 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 21:29:16 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD.
May 01 21:29:16 volumio-x86 sudo[1729]: pam_unix(sudo:session): session closed for user root
May 01 21:29:16 volumio-x86 volumio[1007]: info: Upmpdcli Daemon Started
May 01 21:29:17 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 21:29:17 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:17 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:17 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:17 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:17 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:17 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:17 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:17 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:17 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:18 volumio-x86 mpd[1727]: May 01 21:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 01 21:29:18 volumio-x86 systemd[1]: Started Music Player Daemon.
May 01 21:29:18 volumio-x86 sudo[1716]: pam_unix(sudo:session): session closed for user root
May 01 21:29:18 volumio-x86 volumio[1007]: error: updateQueue error: null
May 01 21:29:18 volumio-x86 volumio[1731]: Generating RSA private key, 4096 bit long modulus (2 primes)
May 01 21:29:19 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 01 21:29:20 volumio-x86 volumio[1007]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
May 01 21:29:20 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
May 01 21:29:21 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 21:29:21 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:21 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:21 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:21 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:21 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:21 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:21 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:21 volumio-x86 volumio[1731]: .......................................................................................................................++++
May 01 21:29:21 volumio-x86 volumio[1731]: ...................++++
May 01 21:29:21 volumio-x86 volumio[1731]: e is 65537 (0x010001)
May 01 21:29:21 volumio-x86 volumio[1731]: writing RSA key
May 01 21:29:22 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 01 21:29:22 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:22 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 01 21:29:24 volumio-x86 volumio[1007]: info: Adding plugin bluetooth to MyMusic Plugins
May 01 21:29:24 volumio-x86 volumio[1007]: info: Adding plugin multiroom to MyMusic Plugins
May 01 21:29:24 volumio-x86 volumio[1007]: info: Adding plugin metavolumio to MyMusic Plugins
May 01 21:29:24 volumio-x86 volumio[1007]: info: Adding plugin cd_controller to MyMusic Plugins
May 01 21:29:24 volumio-x86 volumio[1007]: info: Adding plugin smart_inputs to MyMusic Plugins
May 01 21:29:24 volumio-x86 volumio[1007]: info: Adding plugin tidalconnect to MyMusic Plugins
May 01 21:29:24 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 01 21:29:25 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 01 21:29:25 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 01 21:29:25 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:25 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:25 volumio-x86 volumio[1007]: info: Starting MyVolumio Remote Streaming Endpoints
May 01 21:29:25 volumio-x86 volumio[1007]: info: MyVolumio login type: Token
May 01 21:29:25 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 01 21:29:25 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 01 21:29:26 volumio-x86 systemd[1]: systemd-timedated.service: Succeeded.
May 01 21:29:27 volumio-x86 volumio[1007]: info: Starting Streaming Service Transparent Proxy
May 01 21:29:27 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 01 21:29:27 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 01 21:29:27 volumio-x86 volumio[1007]: info: Streaming services startup
May 01 21:29:27 volumio-x86 volumio[1007]: info: Starting Streaming Daemon
May 01 21:29:27 volumio-x86 sudo[1789]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 01 21:29:27 volumio-x86 sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:27 volumio-x86 sudo[1789]: pam_unix(sudo:session): session closed for user root
May 01 21:29:27 volumio-x86 volumio[1007]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 01 21:29:27 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 01 21:29:27 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 01 21:29:27 volumio-x86 volumio[1007]: error: Cannot start Volumio Streaming Daemon
May 01 21:29:27 volumio-x86 volumio[1007]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 01 21:29:27 volumio-x86 volumio[1007]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 01 21:29:27 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
May 01 21:29:28 volumio-x86 volumio[1007]: STREAMING PROXY: Starting server on port 3245
May 01 21:29:28 volumio-x86 volumio[1007]: Node JS runtime: 14
May 01 21:29:28 volumio-x86 volumio[1007]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 01 21:29:29 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 01 21:29:29 volumio-x86 sudo[1872]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 21:29:29 volumio-x86 sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:29 volumio-x86 sudo[1872]: pam_unix(sudo:session): session closed for user root
May 01 21:29:29 volumio-x86 sudo[1876]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 21:29:29 volumio-x86 sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:29 volumio-x86 sudo[1876]: pam_unix(sudo:session): session closed for user root
May 01 21:29:29 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 01 21:29:29 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to 192.168.5.23 from 192.168.5.75 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetVisibleSources
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 01 21:29:30 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:30 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:30 volumio-x86 volumio[1007]: info: Listing playlists
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 21:29:30 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:30 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetVisibleSources
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 01 21:29:30 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:30 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:30 volumio-x86 volumio[1007]: info: Listing playlists
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 21:29:30 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:30 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:31 volumio-x86 volumio[1007]: info: MyVolumio token set successfully
May 01 21:29:31 volumio-x86 volumio[1007]: info: MYVOLUMIO: Adding device
May 01 21:29:31 volumio-x86 volumio[1007]: info: MYVOLUMIO: Evaluating Server
May 01 21:29:31 volumio-x86 volumio[1007]: info: MyVolumio status changed
May 01 21:29:31 volumio-x86 volumio[1007]: info: Streaming services startup
May 01 21:29:31 volumio-x86 volumio[1007]: info: Starting Streaming Daemon
May 01 21:29:31 volumio-x86 volumio[1007]: info: Removing browser output: myVolumio user plan is not superstar
May 01 21:29:31 volumio-x86 volumio[1007]: info: Removing audio output:
May 01 21:29:31 volumio-x86 volumio[1007]: info: Stoppping Tunnel 1
May 01 21:29:31 volumio-x86 sudo[1999]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 01 21:29:31 volumio-x86 sudo[1999]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:31 volumio-x86 sudo[2002]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 01 21:29:31 volumio-x86 sudo[1999]: pam_unix(sudo:session): session closed for user root
May 01 21:29:31 volumio-x86 sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:31 volumio-x86 volumio[1007]: error: Cannot start Volumio Streaming Daemon
May 01 21:29:31 volumio-x86 volumio[1007]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 01 21:29:31 volumio-x86 volumio[1007]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 01 21:29:31 volumio-x86 sudo[2002]: pam_unix(sudo:session): session closed for user root
May 01 21:29:31 volumio-x86 volumio[1007]: info: Remote SSH Stopped
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 21:29:32 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:32 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:32 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 01 21:29:32 volumio-x86 volumio[1007]: info: AutoStart - Plugin is starting
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetQueue
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::getQueue
May 01 21:29:32 volumio-x86 volumio[1007]: info: CorePlayQueue::getQueue
May 01 21:29:32 volumio-x86 volumio[1007]: info: AutoStart - start playing
May 01 21:29:32 volumio-x86 volumio[1007]: info: AutoStart - start playing with no specific position
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPlay
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::play index 0
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::stop
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::play index undefined
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined
May 01 21:29:32 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:32 volumio-x86 volumio[1007]: info: CoreStateMachine::startPlaybackTimer
May 01 21:29:32 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:32 volumio-x86 volumio[1007]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/20-BEYOND专辑 42首/Beyond - 不再犹豫_-8倍音质_-8倍音质-8倍音质.mp3
May 01 21:29:32 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop
May 01 21:29:32 volumio-x86 volumio[1007]: info: sendMpdCommand stop took 2 milliseconds
May 01 21:29:32 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand clear
May 01 21:29:32 volumio-x86 volumio[1007]: info:
May 01 21:29:32 volumio-x86 volumio[1007]: ---------------------------- MPD announces system playlist update
May 01 21:29:32 volumio-x86 volumio[1007]: info: Ignoring MPD Status Update
May 01 21:29:32 volumio-x86 volumio[1007]: info: sendMpdCommand clear took 4 milliseconds
May 01 21:29:32 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/20-BEYOND专辑 42首/Beyond - 不再犹豫_-8倍音质_-8倍音质-8倍音质.mp3"
May 01 21:29:32 volumio-x86 volumio[1007]: error: updateQueue error: null
May 01 21:29:32 volumio-x86 volumio[1007]: info:
May 01 21:29:32 volumio-x86 volumio[1007]: ---------------------------- MPD announces system playlist update
May 01 21:29:32 volumio-x86 volumio[1007]: info: Ignoring MPD Status Update
May 01 21:29:32 volumio-x86 volumio[1007]: info: ------------------------------ 7ms
May 01 21:29:32 volumio-x86 volumio[1007]: info: sendMpdCommand add "INTERNAL/musics/20-BEYOND专辑 42首/Beyond - 不再犹豫_-8倍音质_-8倍音质-8倍音质.mp3" took 4 milliseconds
May 01 21:29:32 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand play
May 01 21:29:32 volumio-x86 volumio[1007]: info: ------------------------------ 4ms
May 01 21:29:32 volumio-x86 volumio[1007]: info: sendMpdCommand play took 3 milliseconds
May 01 21:29:32 volumio-x86 volumio[1007]: info:
May 01 21:29:32 volumio-x86 volumio[1007]: ---------------------------- MPD announces state update: player
May 01 21:29:32 volumio-x86 volumio[1007]: info: ControllerMpd::getState
May 01 21:29:32 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand status
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 21:29:33 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:33 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:33 volumio-x86 volumio[1007]: info:
May 01 21:29:33 volumio-x86 volumio[1007]: ---------------------------- MPD announces state update: player
May 01 21:29:33 volumio-x86 volumio[1007]: info: sendMpdCommand status took 84 milliseconds
May 01 21:29:33 volumio-x86 volumio[1007]: info: ControllerMpd::getState
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand status
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: ControllerMpd::parseState
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 01 21:29:33 volumio-x86 volumio[1007]: info: sendMpdCommand status took 54 milliseconds
May 01 21:29:33 volumio-x86 volumio[1007]: info: sendMpdCommand playlistinfo took 44 milliseconds
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: ControllerMpd::parseState
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: ControllerMpd::parseTrackInfo
May 01 21:29:33 volumio-x86 volumio[1007]: info: ControllerMpd::pushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::servicePushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2??¨´¨®¨¬?£¤","artist":null,"album":null,"uri":"INTERNAL/musics/20-BEYOND专辑 42首/Beyond - 不再犹豫_-8倍音质_-8倍音质-8倍音质.mp3","trackType":"mp3"}
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: CURRENT POSITION 0
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreStateMachine::syncState   stateService play
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreStateMachine::syncState   currentStatus stop
May 01 21:29:33 volumio-x86 volumio[1007]: info: ------------------------------ 150ms
May 01 21:29:33 volumio-x86 volumio[1007]: info: sendMpdCommand playlistinfo took 9 milliseconds
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: ControllerMpd::parseTrackInfo
May 01 21:29:33 volumio-x86 volumio[1007]: info: ControllerMpd::pushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::servicePushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2??¨´¨®¨¬?£¤","artist":null,"album":null,"uri":"INTERNAL/musics/20-BEYOND专辑 42首/Beyond - 不再犹豫_-8倍音质_-8倍音质-8倍音质.mp3","trackType":"mp3"}
May 01 21:29:33 volumio-x86 volumio[1007]: verbose: CURRENT POSITION 0
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreStateMachine::syncState   stateService play
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreStateMachine::syncState   currentStatus play
May 01 21:29:33 volumio-x86 volumio[1007]: info: Received an update from plugin. extracting info from payload
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreStateMachine::pushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreStateMachine::pushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:33 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPushState
May 01 21:29:33 volumio-x86 volumio[1007]: info: ------------------------------ 98ms
May 01 21:29:33 volumio-x86 kernel: snd_hda_intel 0000:00:01.1: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj.
May 01 21:29:34 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:34 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:34 volumio-x86 sudo[2018]:     root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py
May 01 21:29:34 volumio-x86 sudo[2018]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 21:29:37 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 01 21:29:38 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7
May 01 21:29:38 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:38 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:39 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 01 21:29:40 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8
May 01 21:29:40 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:40 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:40 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 21:29:40 volumio-x86 volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
May 01 21:29:40 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 21:29:40 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 01 21:29:40 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:40 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 21:29:42 volumio-x86 volumio[1007]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 01 21:29:42 volumio-x86 volumio[1007]: info: Received Get System Version
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 01 21:29:42 volumio-x86 volumio[1007]: info: Received Get System Info
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 21:29:42 volumio-x86 volumio[1007]: info: Discovery: Getting this device information
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioGetState
May 01 21:29:42 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 21:29:42 volumio-x86 volumio[1007]: info: Setting Geolocation for MyVolumio to as2
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:42 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:43 volumio-x86 volumio[1007]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 01 21:29:43 volumio-x86 volumio[1007]: info: Updating MyVolumio device info
May 01 21:29:43 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:43 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 01 21:29:43 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 21:29:43 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 01 21:29:43 volumio-x86 volumio-remote-updater[696]: No test mode
May 01 21:29:43 volumio-x86 volumio-remote-updater[696]: No alpha test mode
May 01 21:29:43 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 01 21:29:44 volumio-x86 volumio[1007]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 01 21:29:44 volumio-x86 volumio[1007]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nSlow down CD read speed
\nFix NAS Mounting
\n
\nNEW ADDITIONS
\n\nRevert  TIDAL Connect to working version
\nFix for USB DAC not recognized after OTA update
\n\n","title":"Update v3.804","updateavailable":true}
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPause
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreStateMachine::pause
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreStateMachine::stPlaybackTimer
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreStateMachine::servicePause
May 01 21:29:44 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreCommandRouter::servicePause
May 01 21:29:44 volumio-x86 volumio[1007]: info: ControllerMpd::pause
May 01 21:29:44 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand pause
May 01 21:29:44 volumio-x86 volumio[1007]: info:
May 01 21:29:44 volumio-x86 volumio[1007]: ---------------------------- MPD announces state update: player
May 01 21:29:44 volumio-x86 volumio[1007]: info: sendMpdCommand pause took 12 milliseconds
May 01 21:29:44 volumio-x86 volumio[1007]: info: ControllerMpd::getState
May 01 21:29:44 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand status
May 01 21:29:44 volumio-x86 volumio[1007]: info: sendMpdCommand status took 2 milliseconds
May 01 21:29:44 volumio-x86 volumio[1007]: verbose: ControllerMpd::parseState
May 01 21:29:44 volumio-x86 volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 01 21:29:44 volumio-x86 volumio[1007]: info: sendMpdCommand playlistinfo took 6 milliseconds
May 01 21:29:44 volumio-x86 volumio[1007]: verbose: ControllerMpd::parseTrackInfo
May 01 21:29:44 volumio-x86 volumio[1007]: info: ControllerMpd::pushState
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreCommandRouter::servicePushState
May 01 21:29:44 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:44 volumio-x86 volumio[1007]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12521,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2??¨´¨®¨¬?£¤","artist":null,"album":null,"uri":"INTERNAL/musics/20-BEYOND专辑 42首/Beyond - 不再犹豫_-8倍音质_-8倍音质-8倍音质.mp3","trackType":"mp3"}
May 01 21:29:44 volumio-x86 volumio[1007]: verbose: CURRENT POSITION 0
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreStateMachine::syncState   stateService pause
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreStateMachine::syncState   currentStatus pause
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreStateMachine::pushState
May 01 21:29:44 volumio-x86 volumio[1007]: info: CorePlayQueue::getTrack 0
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 01 21:29:44 volumio-x86 volumio[1007]: info: CoreCommandRouter::volumioPushState
May 01 21:29:45 volumio-x86 volumio[1007]: info: CoreStateMachine::stPlaybackTimer
May 01 21:29:45 volumio-x86 volumio[1007]: info: ------------------------------ 73ms
May 01 21:29:45 volumio-x86 volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 01 21:29:45 volumio-x86 volumio[1007]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
May 01 21:29:45 volumio-x86 volumio[1007]:     at Object.readdirSync (fs.js:1048:3)
May 01 21:29:45 volumio-x86 volumio[1007]:     at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11)
May 01 21:29:45 volumio-x86 volumio[1007]:     at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12)
May 01 21:29:45 volumio-x86 volumio[1007]:     at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) {
May 01 21:29:45 volumio-x86 volumio[1007]:   errno: -20,
May 01 21:29:45 volumio-x86 volumio[1007]:   syscall: 'scandir',
May 01 21:29:45 volumio-x86 volumio[1007]:   code: 'ENOTDIR',
May 01 21:29:45 volumio-x86 volumio[1007]:   path: '/tmp/peppyrunning'
May 01 21:29:45 volumio-x86 volumio[1007]: }
May 01 21:29:45 volumio-x86 volumio[1007]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 01 21:29:45 volumio-x86 sudo[2177]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 21:28
May 01 21:29:45 volumio-x86 sudo[2177]: 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="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST"
VOLUMIO_VERSION="3.799"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"