-- Logs begin at Wed 2025-02-26 01:37:26 CST, end at Wed 2025-02-26 09:37:24 CST. -- Feb 26 09:36:04 volumio kernel: Linux version 6.6.7-volumio (gkkpch@TWINUNX-20) (gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #2 SMP PREEMPT_DYNAMIC Mon Dec 18 16:22:28 CET 2023 Feb 26 09:36:04 volumio kernel: Command line: BOOT_IMAGE=vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=e8396e64-ac09-46ae-b60c-b73932d465af bootpart=UUID=2138-C5D3 datapart=UUID=5c3c6231-72b1-4d40-99f1-8e542b29b6d6 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 initrd=volumio.initrd Feb 26 09:36:04 volumio kernel: KERNEL supported cpus: Feb 26 09:36:04 volumio kernel: Intel GenuineIntel Feb 26 09:36:04 volumio kernel: AMD AuthenticAMD Feb 26 09:36:04 volumio kernel: Hygon HygonGenuine Feb 26 09:36:04 volumio kernel: Centaur CentaurHauls Feb 26 09:36:04 volumio kernel: zhaoxin Shanghai Feb 26 09:36:04 volumio kernel: BIOS-provided physical RAM map: Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bffddfff] usable Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x00000000bffde000-0x00000000bfffffff] reserved Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Feb 26 09:36:04 volumio kernel: BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable Feb 26 09:36:04 volumio kernel: NX (Execute Disable) protection: active Feb 26 09:36:04 volumio kernel: APIC: Static calls initialized Feb 26 09:36:04 volumio kernel: SMBIOS 2.8 present. Feb 26 09:36:04 volumio kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Feb 26 09:36:04 volumio kernel: Hypervisor detected: KVM Feb 26 09:36:04 volumio kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Feb 26 09:36:04 volumio kernel: kvm-clock: using sched offset of 8050476263 cycles Feb 26 09:36:04 volumio kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Feb 26 09:36:04 volumio kernel: tsc: Detected 1600.000 MHz processor Feb 26 09:36:04 volumio kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Feb 26 09:36:04 volumio kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Feb 26 09:36:04 volumio kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000 Feb 26 09:36:04 volumio kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Feb 26 09:36:04 volumio kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Feb 26 09:36:04 volumio kernel: last_pfn = 0xbffde max_arch_pfn = 0x400000000 Feb 26 09:36:04 volumio kernel: found SMP MP-table at [mem 0x000f5b70-0x000f5b7f] Feb 26 09:36:04 volumio kernel: RAMDISK: [mem 0x7be22000-0x7fffffff] Feb 26 09:36:04 volumio kernel: ACPI: Early table checksum verification disabled Feb 26 09:36:04 volumio kernel: ACPI: RSDP 0x00000000000F5980 000014 (v00 BOCHS ) Feb 26 09:36:04 volumio kernel: ACPI: RSDT 0x00000000BFFE1A62 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 26 09:36:04 volumio kernel: ACPI: FACP 0x00000000BFFE1936 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 26 09:36:04 volumio kernel: ACPI: DSDT 0x00000000BFFE0040 0018F6 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 26 09:36:04 volumio kernel: ACPI: FACS 0x00000000BFFE0000 000040 Feb 26 09:36:04 volumio kernel: ACPI: APIC 0x00000000BFFE19AA 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 26 09:36:04 volumio kernel: ACPI: WAET 0x00000000BFFE1A3A 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 26 09:36:04 volumio kernel: ACPI: Reserving FACP table memory at [mem 0xbffe1936-0xbffe19a9] Feb 26 09:36:04 volumio kernel: ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1935] Feb 26 09:36:04 volumio kernel: ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] Feb 26 09:36:04 volumio kernel: ACPI: Reserving APIC table memory at [mem 0xbffe19aa-0xbffe1a39] Feb 26 09:36:04 volumio kernel: ACPI: Reserving WAET table memory at [mem 0xbffe1a3a-0xbffe1a61] Feb 26 09:36:04 volumio kernel: Zone ranges: Feb 26 09:36:04 volumio kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Feb 26 09:36:04 volumio kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Feb 26 09:36:04 volumio kernel: Normal [mem 0x0000000100000000-0x000000013fffffff] Feb 26 09:36:04 volumio kernel: Movable zone start for each node Feb 26 09:36:04 volumio kernel: Early memory node ranges Feb 26 09:36:04 volumio kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Feb 26 09:36:04 volumio kernel: node 0: [mem 0x0000000000100000-0x00000000bffddfff] Feb 26 09:36:04 volumio kernel: node 0: [mem 0x0000000100000000-0x000000013fffffff] Feb 26 09:36:04 volumio kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] Feb 26 09:36:04 volumio kernel: On node 0, zone DMA: 1 pages in unavailable ranges Feb 26 09:36:04 volumio kernel: On node 0, zone DMA: 97 pages in unavailable ranges Feb 26 09:36:04 volumio kernel: On node 0, zone Normal: 34 pages in unavailable ranges Feb 26 09:36:04 volumio kernel: ACPI: PM-Timer IO Port: 0x608 Feb 26 09:36:04 volumio kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Feb 26 09:36:04 volumio kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Feb 26 09:36:04 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Feb 26 09:36:04 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Feb 26 09:36:04 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Feb 26 09:36:04 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Feb 26 09:36:04 volumio kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Feb 26 09:36:04 volumio kernel: ACPI: Using ACPI (MADT) for SMP configuration information Feb 26 09:36:04 volumio kernel: TSC deadline timer available Feb 26 09:36:04 volumio kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Feb 26 09:36:04 volumio kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Feb 26 09:36:04 volumio kernel: kvm-guest: KVM setup pv remote TLB flush Feb 26 09:36:04 volumio kernel: kvm-guest: setup PV sched yield Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xbffde000-0xbfffffff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xff000000-0xfffbffff] Feb 26 09:36:04 volumio kernel: PM: hibernation: Registered nosave memory: [mem 0xfffc0000-0xffffffff] Feb 26 09:36:04 volumio kernel: [mem 0xc0000000-0xfeffbfff] available for PCI devices Feb 26 09:36:04 volumio kernel: Booting paravirtualized kernel on KVM Feb 26 09:36:04 volumio kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns Feb 26 09:36:04 volumio kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Feb 26 09:36:04 volumio kernel: percpu: Embedded 56 pages/cpu s191400 r8192 d29784 u524288 Feb 26 09:36:04 volumio kernel: pcpu-alloc: s191400 r8192 d29784 u524288 alloc=1*2097152 Feb 26 09:36:04 volumio kernel: pcpu-alloc: [0] 0 1 2 3 Feb 26 09:36:04 volumio kernel: Kernel command line: BOOT_IMAGE=vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=e8396e64-ac09-46ae-b60c-b73932d465af bootpart=UUID=2138-C5D3 datapart=UUID=5c3c6231-72b1-4d40-99f1-8e542b29b6d6 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 initrd=volumio.initrd Feb 26 09:36:04 volumio kernel: Unknown kernel command line parameters "splash BOOT_IMAGE=vmlinuz biosdevname=0 imgpart=UUID=e8396e64-ac09-46ae-b60c-b73932d465af bootpart=UUID=2138-C5D3 datapart=UUID=5c3c6231-72b1-4d40-99f1-8e542b29b6d6 imgfile=/volumio_current.sqsh use_kmsg=yes", will be passed to user space. Feb 26 09:36:04 volumio kernel: random: crng init done Feb 26 09:36:04 volumio kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Feb 26 09:36:04 volumio kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Feb 26 09:36:04 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031902 Feb 26 09:36:04 volumio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 26 09:36:04 volumio kernel: software IO TLB: area num 4. Feb 26 09:36:04 volumio kernel: Memory: 3959836K/4193776K available (14336K kernel code, 1874K rwdata, 4632K rodata, 2960K init, 1184K bss, 233684K reserved, 0K cma-reserved) Feb 26 09:36:04 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 26 09:36:04 volumio kernel: Kernel/User page tables isolation: enabled Feb 26 09:36:04 volumio kernel: ftrace: allocating 40414 entries in 158 pages Feb 26 09:36:04 volumio kernel: ftrace: allocated 158 pages with 5 groups Feb 26 09:36:04 volumio kernel: Dynamic Preempt: voluntary Feb 26 09:36:04 volumio kernel: rcu: Preemptible hierarchical RCU implementation. Feb 26 09:36:04 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Feb 26 09:36:04 volumio kernel: Trampoline variant of Tasks RCU enabled. Feb 26 09:36:04 volumio kernel: Rude variant of Tasks RCU enabled. Feb 26 09:36:04 volumio kernel: Tracing variant of Tasks RCU enabled. Feb 26 09:36:04 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Feb 26 09:36:04 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Feb 26 09:36:04 volumio kernel: NR_IRQS: 4352, nr_irqs: 456, preallocated irqs: 16 Feb 26 09:36:04 volumio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 26 09:36:04 volumio kernel: Console: colour VGA+ 80x25 Feb 26 09:36:04 volumio kernel: printk: console [tty0] enabled Feb 26 09:36:04 volumio kernel: ACPI: Core revision 20230628 Feb 26 09:36:04 volumio kernel: APIC: Switch to symmetric I/O mode setup Feb 26 09:36:04 volumio kernel: x2apic enabled Feb 26 09:36:04 volumio kernel: APIC: Switched APIC routing to: physical x2apic Feb 26 09:36:04 volumio kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Feb 26 09:36:04 volumio kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Feb 26 09:36:04 volumio kernel: kvm-guest: setup PV IPIs Feb 26 09:36:04 volumio kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x171024fa93b, max_idle_ns: 440795253189 ns Feb 26 09:36:04 volumio kernel: Calibrating delay loop (skipped) preset value.. 3200.00 BogoMIPS (lpj=6400000) Feb 26 09:36:04 volumio kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Feb 26 09:36:04 volumio kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Feb 26 09:36:04 volumio kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Feb 26 09:36:04 volumio kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Feb 26 09:36:04 volumio kernel: Spectre V2 : Mitigation: Retpolines Feb 26 09:36:04 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Feb 26 09:36:04 volumio kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Feb 26 09:36:04 volumio kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Feb 26 09:36:04 volumio kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Feb 26 09:36:04 volumio kernel: MDS: Mitigation: Clear CPU buffers Feb 26 09:36:04 volumio kernel: MMIO Stale Data: Unknown: No mitigations Feb 26 09:36:04 volumio kernel: x86/fpu: x87 FPU will use FXSAVE Feb 26 09:36:04 volumio kernel: Freeing SMP alternatives memory: 32K Feb 26 09:36:04 volumio kernel: pid_max: default: 32768 minimum: 301 Feb 26 09:36:04 volumio kernel: LSM: initializing lsm=capability,yama,integrity Feb 26 09:36:04 volumio kernel: Yama: becoming mindful. Feb 26 09:36:04 volumio kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 26 09:36:04 volumio kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 26 09:36:04 volumio kernel: smpboot: CPU0: Intel(R) Celeron(R) CPU J3160 @ 1.60GHz (family: 0x6, model: 0x4c, stepping: 0x4) Feb 26 09:36:04 volumio kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Feb 26 09:36:04 volumio kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Feb 26 09:36:04 volumio kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Feb 26 09:36:04 volumio kernel: Performance Events: 8-deep LBR, Silvermont events, full-width counters, Intel PMU driver. Feb 26 09:36:04 volumio kernel: ... version: 2 Feb 26 09:36:04 volumio kernel: ... bit width: 40 Feb 26 09:36:04 volumio kernel: ... generic registers: 2 Feb 26 09:36:04 volumio kernel: ... value mask: 000000ffffffffff Feb 26 09:36:04 volumio kernel: ... max period: 0000007fffffffff Feb 26 09:36:04 volumio kernel: ... fixed-purpose events: 3 Feb 26 09:36:04 volumio kernel: ... event mask: 0000000700000003 Feb 26 09:36:04 volumio kernel: signal: max sigframe size: 1440 Feb 26 09:36:04 volumio kernel: rcu: Hierarchical SRCU implementation. Feb 26 09:36:04 volumio kernel: rcu: Max phase no-delay instances is 1000. Feb 26 09:36:04 volumio kernel: smp: Bringing up secondary CPUs ... Feb 26 09:36:04 volumio kernel: smpboot: x86: Booting SMP configuration: Feb 26 09:36:04 volumio kernel: .... node #0, CPUs: #1 #2 #3 Feb 26 09:36:04 volumio kernel: smp: Brought up 1 node, 4 CPUs Feb 26 09:36:04 volumio kernel: smpboot: Max logical packages: 1 Feb 26 09:36:04 volumio kernel: smpboot: Total of 4 processors activated (12800.00 BogoMIPS) Feb 26 09:36:04 volumio kernel: devtmpfs: initialized Feb 26 09:36:04 volumio kernel: x86/mm: Memory block size: 128MB Feb 26 09:36:04 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Feb 26 09:36:04 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 26 09:36:04 volumio kernel: pinctrl core: initialized pinctrl subsystem Feb 26 09:36:04 volumio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 26 09:36:04 volumio kernel: audit: initializing netlink subsys (disabled) Feb 26 09:36:04 volumio kernel: audit: type=2000 audit(1740504945.930:1): state=initialized audit_enabled=0 res=1 Feb 26 09:36:04 volumio kernel: thermal_sys: Registered thermal governor 'fair_share' Feb 26 09:36:04 volumio kernel: thermal_sys: Registered thermal governor 'bang_bang' Feb 26 09:36:04 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 26 09:36:04 volumio kernel: thermal_sys: Registered thermal governor 'user_space' Feb 26 09:36:04 volumio kernel: EISA bus registered Feb 26 09:36:04 volumio kernel: cpuidle: using governor ladder Feb 26 09:36:04 volumio kernel: cpuidle: using governor menu Feb 26 09:36:04 volumio kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Feb 26 09:36:04 volumio kernel: PCI: Using configuration type 1 for base access Feb 26 09:36:04 volumio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 26 09:36:04 volumio kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Feb 26 09:36:04 volumio kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Feb 26 09:36:04 volumio kernel: ACPI: Added _OSI(Module Device) Feb 26 09:36:04 volumio kernel: ACPI: Added _OSI(Processor Device) Feb 26 09:36:04 volumio kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Feb 26 09:36:04 volumio kernel: ACPI: Added _OSI(Processor Aggregator Device) Feb 26 09:36:04 volumio kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Feb 26 09:36:04 volumio kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Feb 26 09:36:04 volumio kernel: ACPI: Interpreter enabled Feb 26 09:36:04 volumio kernel: ACPI: PM: (supports S0 S5) Feb 26 09:36:04 volumio kernel: ACPI: Using IOAPIC for interrupt routing Feb 26 09:36:04 volumio kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Feb 26 09:36:04 volumio kernel: PCI: Using E820 reservations for host bridge windows Feb 26 09:36:04 volumio kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Feb 26 09:36:04 volumio kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Feb 26 09:36:04 volumio kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Feb 26 09:36:04 volumio kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Feb 26 09:36:04 volumio kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Feb 26 09:36:04 volumio kernel: acpiphp: Slot [2] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [4] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [5] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [6] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [7] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [8] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [9] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [10] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [11] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [12] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [13] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [14] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [15] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [16] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [17] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [18] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [19] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [20] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [21] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [22] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [23] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [24] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [25] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [26] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [27] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [28] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [29] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [30] registered Feb 26 09:36:04 volumio kernel: acpiphp: Slot [31] registered Feb 26 09:36:04 volumio kernel: PCI host bridge to bus 0000:00 Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x140000000-0x1bfffffff window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 26 09:36:04 volumio kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Feb 26 09:36:04 volumio kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Feb 26 09:36:04 volumio kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Feb 26 09:36:04 volumio kernel: pci 0000:00:01.1: reg 0x20: [io 0xc1c0-0xc1cf] Feb 26 09:36:04 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Feb 26 09:36:04 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Feb 26 09:36:04 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Feb 26 09:36:04 volumio kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Feb 26 09:36:04 volumio kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Feb 26 09:36:04 volumio kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Feb 26 09:36:04 volumio kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Feb 26 09:36:04 volumio kernel: pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 Feb 26 09:36:04 volumio kernel: pci 0000:00:02.0: reg 0x10: [io 0xc180-0xc19f] Feb 26 09:36:04 volumio kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd6000-0xfebd6fff] Feb 26 09:36:04 volumio kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Feb 26 09:36:04 volumio kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: [1234:1111] type 00 class 0x030000 Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: reg 0x18: [mem 0xfebd7000-0xfebd7fff] Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Feb 26 09:36:04 volumio kernel: pci 0000:00:04.0: [1af4:1002] type 00 class 0x00ff00 Feb 26 09:36:04 volumio kernel: pci 0000:00:04.0: reg 0x10: [io 0xc100-0xc13f] Feb 26 09:36:04 volumio kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Feb 26 09:36:04 volumio kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 Feb 26 09:36:04 volumio kernel: pci 0000:00:05.0: reg 0x10: [io 0xc140-0xc17f] Feb 26 09:36:04 volumio kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebd8000-0xfebd8fff] Feb 26 09:36:04 volumio kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] Feb 26 09:36:04 volumio kernel: pci 0000:00:06.0: [1000:0012] type 00 class 0x010000 Feb 26 09:36:04 volumio kernel: pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc0ff] Feb 26 09:36:04 volumio kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebd9000-0xfebd93ff] Feb 26 09:36:04 volumio kernel: pci 0000:00:06.0: reg 0x18: [mem 0xfebd4000-0xfebd5fff] Feb 26 09:36:04 volumio kernel: pci 0000:00:1e.0: [1b36:000d] type 00 class 0x0c0330 Feb 26 09:36:04 volumio kernel: pci 0000:00:1e.0: reg 0x10: [mem 0xfebd0000-0xfebd3fff 64bit] Feb 26 09:36:04 volumio kernel: pci 0000:00:1f.0: [8086:2922] type 00 class 0x010601 Feb 26 09:36:04 volumio kernel: pci 0000:00:1f.0: reg 0x20: [io 0xc1a0-0xc1bf] Feb 26 09:36:04 volumio kernel: pci 0000:00:1f.0: reg 0x24: [mem 0xfebda000-0xfebdafff] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: on NUMA node 0 Feb 26 09:36:04 volumio kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Feb 26 09:36:04 volumio kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Feb 26 09:36:04 volumio kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Feb 26 09:36:04 volumio kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Feb 26 09:36:04 volumio kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Feb 26 09:36:04 volumio kernel: iommu: Default domain type: Translated Feb 26 09:36:04 volumio kernel: iommu: DMA domain TLB invalidation policy: lazy mode Feb 26 09:36:04 volumio kernel: SCSI subsystem initialized Feb 26 09:36:04 volumio kernel: libata version 3.00 loaded. Feb 26 09:36:04 volumio kernel: ACPI: bus type USB registered Feb 26 09:36:04 volumio kernel: usbcore: registered new interface driver usbfs Feb 26 09:36:04 volumio kernel: usbcore: registered new interface driver hub Feb 26 09:36:04 volumio kernel: usbcore: registered new device driver usb Feb 26 09:36:04 volumio kernel: PCI: Using ACPI for IRQ routing Feb 26 09:36:04 volumio kernel: PCI: pci_cache_line_size set to 64 bytes Feb 26 09:36:04 volumio kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Feb 26 09:36:04 volumio kernel: e820: reserve RAM buffer [mem 0xbffde000-0xbfffffff] Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: vgaarb: setting as boot VGA device Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: vgaarb: bridge control possible Feb 26 09:36:04 volumio kernel: pci 0000:00:03.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Feb 26 09:36:04 volumio kernel: vgaarb: loaded Feb 26 09:36:04 volumio kernel: clocksource: Switched to clocksource kvm-clock Feb 26 09:36:04 volumio kernel: VFS: Disk quotas dquot_6.6.0 Feb 26 09:36:04 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 26 09:36:04 volumio kernel: pnp: PnP ACPI init Feb 26 09:36:04 volumio kernel: pnp 00:02: [dma 2] Feb 26 09:36:04 volumio kernel: pnp: PnP ACPI: found 4 devices Feb 26 09:36:04 volumio kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Feb 26 09:36:04 volumio kernel: NET: Registered PF_INET protocol family Feb 26 09:36:04 volumio kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Feb 26 09:36:04 volumio kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Feb 26 09:36:04 volumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 26 09:36:04 volumio kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Feb 26 09:36:04 volumio kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Feb 26 09:36:04 volumio kernel: TCP: Hash tables configured (established 32768 bind 32768) Feb 26 09:36:04 volumio kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 26 09:36:04 volumio kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 26 09:36:04 volumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] Feb 26 09:36:04 volumio kernel: pci_bus 0000:00: resource 8 [mem 0x140000000-0x1bfffffff window] Feb 26 09:36:04 volumio kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Feb 26 09:36:04 volumio kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Feb 26 09:36:04 volumio kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Feb 26 09:36:04 volumio kernel: pci 0000:00:1e.0: quirk_usb_early_handoff+0x0/0x750 took 80721 usecs Feb 26 09:36:04 volumio kernel: PCI: CLS 0 bytes, default 64 Feb 26 09:36:04 volumio kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Feb 26 09:36:04 volumio kernel: software IO TLB: mapped [mem 0x00000000bbfde000-0x00000000bffde000] (64MB) Feb 26 09:36:04 volumio kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x171024fa93b, max_idle_ns: 440795253189 ns Feb 26 09:36:04 volumio kernel: Initialise system trusted keyrings Feb 26 09:36:04 volumio kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Feb 26 09:36:04 volumio kernel: zbud: loaded Feb 26 09:36:04 volumio kernel: Key type asymmetric registered Feb 26 09:36:04 volumio kernel: Asymmetric key parser 'x509' registered Feb 26 09:36:04 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Feb 26 09:36:04 volumio kernel: Trying to unpack rootfs image as initramfs... Feb 26 09:36:04 volumio kernel: io scheduler mq-deadline registered Feb 26 09:36:04 volumio kernel: io scheduler kyber registered Feb 26 09:36:04 volumio kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Feb 26 09:36:04 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Feb 26 09:36:04 volumio kernel: ACPI: button: Power Button [PWRF] Feb 26 09:36:04 volumio kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled Feb 26 09:36:04 volumio kernel: Linux agpgart interface v0.103 Feb 26 09:36:04 volumio kernel: ata_piix 0000:00:01.1: version 2.13 Feb 26 09:36:04 volumio kernel: scsi host0: ata_piix Feb 26 09:36:04 volumio kernel: scsi host1: ata_piix Feb 26 09:36:04 volumio kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1c0 irq 14 Feb 26 09:36:04 volumio kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1c8 irq 15 Feb 26 09:36:04 volumio kernel: xhci_hcd 0000:00:1e.0: xHCI Host Controller Feb 26 09:36:04 volumio kernel: xhci_hcd 0000:00:1e.0: new USB bus registered, assigned bus number 1 Feb 26 09:36:04 volumio kernel: xhci_hcd 0000:00:1e.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 Feb 26 09:36:04 volumio kernel: xhci_hcd 0000:00:1e.0: xHCI Host Controller Feb 26 09:36:04 volumio kernel: xhci_hcd 0000:00:1e.0: new USB bus registered, assigned bus number 2 Feb 26 09:36:04 volumio kernel: xhci_hcd 0000:00:1e.0: Host supports USB 3.0 SuperSpeed Feb 26 09:36:04 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 26 09:36:04 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 26 09:36:04 volumio kernel: usb usb1: Product: xHCI Host Controller Feb 26 09:36:04 volumio kernel: usb usb1: Manufacturer: Linux 6.6.7-volumio xhci-hcd Feb 26 09:36:04 volumio kernel: usb usb1: SerialNumber: 0000:00:1e.0 Feb 26 09:36:04 volumio kernel: hub 1-0:1.0: USB hub found Feb 26 09:36:04 volumio kernel: hub 1-0:1.0: 4 ports detected Feb 26 09:36:04 volumio kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Feb 26 09:36:04 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Feb 26 09:36:04 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 26 09:36:04 volumio kernel: usb usb2: Product: xHCI Host Controller Feb 26 09:36:04 volumio kernel: usb usb2: Manufacturer: Linux 6.6.7-volumio xhci-hcd Feb 26 09:36:04 volumio kernel: usb usb2: SerialNumber: 0000:00:1e.0 Feb 26 09:36:04 volumio kernel: hub 2-0:1.0: USB hub found Feb 26 09:36:04 volumio kernel: hub 2-0:1.0: 4 ports detected Feb 26 09:36:04 volumio kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Feb 26 09:36:04 volumio kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Feb 26 09:36:04 volumio kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Feb 26 09:36:04 volumio kernel: mousedev: PS/2 mouse device common for all mice Feb 26 09:36:04 volumio kernel: rtc_cmos 00:03: RTC can wake from S4 Feb 26 09:36:04 volumio kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 Feb 26 09:36:04 volumio kernel: rtc_cmos 00:03: registered as rtc0 Feb 26 09:36:04 volumio kernel: rtc_cmos 00:03: setting system clock to 2025-02-26T01:35:45 UTC (1740533745) Feb 26 09:36:04 volumio kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Feb 26 09:36:04 volumio kernel: i2c_dev: i2c /dev entries driver Feb 26 09:36:04 volumio kernel: platform eisa.0: Probing EISA bus 0 Feb 26 09:36:04 volumio kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard Feb 26 09:36:04 volumio kernel: intel_pstate: CPU model not supported Feb 26 09:36:04 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 26 09:36:04 volumio kernel: NET: Registered PF_INET6 protocol family Feb 26 09:36:04 volumio kernel: Segment Routing with IPv6 Feb 26 09:36:04 volumio kernel: In-situ OAM (IOAM) with IPv6 Feb 26 09:36:04 volumio kernel: mip6: Mobile IPv6 Feb 26 09:36:04 volumio kernel: NET: Registered PF_PACKET protocol family Feb 26 09:36:04 volumio kernel: mpls_gso: MPLS GSO support Feb 26 09:36:04 volumio kernel: IPI shorthand broadcast: enabled Feb 26 09:36:04 volumio kernel: sched_clock: Marking stable (752016050, 37028712)->(843741850, -54697088) Feb 26 09:36:04 volumio kernel: Loading compiled-in X.509 certificates Feb 26 09:36:04 volumio kernel: clk: Disabling unused clocks Feb 26 09:36:04 volumio kernel: usb 1-2: new high-speed USB device number 2 using xhci_hcd Feb 26 09:36:04 volumio kernel: usb 1-2: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Feb 26 09:36:04 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Feb 26 09:36:04 volumio kernel: usb 1-2: Product: xCORE USB Audio 2.0 Feb 26 09:36:04 volumio kernel: usb 1-2: Manufacturer: XMOS Feb 26 09:36:04 volumio kernel: usb 1-3: new high-speed USB device number 3 using xhci_hcd Feb 26 09:36:04 volumio kernel: usb 1-3: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 Feb 26 09:36:04 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=3, SerialNumber=10 Feb 26 09:36:04 volumio kernel: usb 1-3: Product: QEMU USB Tablet Feb 26 09:36:04 volumio kernel: usb 1-3: Manufacturer: QEMU Feb 26 09:36:04 volumio kernel: usb 1-3: SerialNumber: 28754-0000:00:1e.0-3 Feb 26 09:36:04 volumio kernel: Freeing initrd memory: 67448K Feb 26 09:36:04 volumio kernel: Freeing unused kernel image (initmem) memory: 2960K Feb 26 09:36:04 volumio kernel: Write protecting the kernel read-only data: 20480k Feb 26 09:36:04 volumio kernel: Freeing unused kernel image (rodata/data gap) memory: 1512K Feb 26 09:36:04 volumio kernel: Run /init as init process Feb 26 09:36:04 volumio kernel: with arguments: Feb 26 09:36:04 volumio kernel: /init Feb 26 09:36:04 volumio kernel: splash Feb 26 09:36:04 volumio kernel: with environment: Feb 26 09:36:04 volumio kernel: HOME=/ Feb 26 09:36:04 volumio kernel: TERM=linux Feb 26 09:36:04 volumio kernel: BOOT_IMAGE=vmlinuz Feb 26 09:36:04 volumio kernel: biosdevname=0 Feb 26 09:36:04 volumio kernel: imgpart=UUID=e8396e64-ac09-46ae-b60c-b73932d465af Feb 26 09:36:04 volumio kernel: bootpart=UUID=2138-C5D3 Feb 26 09:36:04 volumio kernel: datapart=UUID=5c3c6231-72b1-4d40-99f1-8e542b29b6d6 Feb 26 09:36:04 volumio kernel: imgfile=/volumio_current.sqsh Feb 26 09:36:04 volumio kernel: use_kmsg=yes Feb 26 09:36:04 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 26 09:36:04 volumio kernel: fuse: init (API version 7.39) Feb 26 09:36:04 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Feb 26 09:36:04 volumio kernel: sdhci: Copyright(c) Pierre Ossman Feb 26 09:36:04 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Feb 26 09:36:04 volumio kernel: usbcore: registered new interface driver usbhid Feb 26 09:36:04 volumio kernel: usbhid: USB HID core driver Feb 26 09:36:04 volumio kernel: input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1e.0/usb1/1-3/1-3:1.0/0003:0627:0001.0001/input/input3 Feb 26 09:36:04 volumio kernel: hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1e.0-3/input0 Feb 26 09:36:04 volumio kernel: loop: module loaded Feb 26 09:36:04 volumio kernel: usbcore: registered new interface driver usb-storage Feb 26 09:36:04 volumio kernel: usbcore: registered new interface driver uas Feb 26 09:36:04 volumio kernel: ahci 0000:00:1f.0: version 3.0 Feb 26 09:36:04 volumio kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Feb 26 09:36:04 volumio kernel: ahci 0000:00:1f.0: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Feb 26 09:36:04 volumio kernel: ahci 0000:00:1f.0: flags: 64bit ncq only Feb 26 09:36:04 volumio kernel: scsi host2: ahci Feb 26 09:36:04 volumio kernel: scsi host3: ahci Feb 26 09:36:04 volumio kernel: scsi host4: ahci Feb 26 09:36:04 volumio kernel: scsi host5: ahci Feb 26 09:36:04 volumio kernel: scsi host6: ahci Feb 26 09:36:04 volumio kernel: scsi host7: ahci Feb 26 09:36:04 volumio kernel: ata3: SATA max UDMA/133 abar m4096@0xfebda000 port 0xfebda100 irq 29 Feb 26 09:36:04 volumio kernel: ata4: SATA max UDMA/133 abar m4096@0xfebda000 port 0xfebda180 irq 29 Feb 26 09:36:04 volumio kernel: ata5: SATA max UDMA/133 abar m4096@0xfebda000 port 0xfebda200 irq 29 Feb 26 09:36:04 volumio kernel: ata6: SATA max UDMA/133 abar m4096@0xfebda000 port 0xfebda280 irq 29 Feb 26 09:36:04 volumio kernel: ata7: SATA max UDMA/133 abar m4096@0xfebda000 port 0xfebda300 irq 29 Feb 26 09:36:04 volumio kernel: ata8: SATA max UDMA/133 abar m4096@0xfebda000 port 0xfebda380 irq 29 Feb 26 09:36:04 volumio kernel: ata7: SATA link down (SStatus 0 SControl 300) Feb 26 09:36:04 volumio kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Feb 26 09:36:04 volumio kernel: ata6: SATA link down (SStatus 0 SControl 300) Feb 26 09:36:04 volumio kernel: ata5: SATA link down (SStatus 0 SControl 300) Feb 26 09:36:04 volumio kernel: ata4: SATA link down (SStatus 0 SControl 300) Feb 26 09:36:04 volumio kernel: ata3.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 Feb 26 09:36:04 volumio kernel: ata3.00: 20971520 sectors, multi 16: LBA48 NCQ (depth 32) Feb 26 09:36:04 volumio kernel: ata3.00: applying bridge limits Feb 26 09:36:04 volumio kernel: ata8: SATA link down (SStatus 0 SControl 300) Feb 26 09:36:04 volumio kernel: ata3.00: configured for UDMA/100 Feb 26 09:36:04 volumio kernel: scsi 2:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Feb 26 09:36:04 volumio kernel: sd 2:0:0:0: [sda] 20971520 512-byte logical blocks: (10.7 GB/10.0 GiB) Feb 26 09:36:04 volumio kernel: sd 2:0:0:0: [sda] Write Protect is off Feb 26 09:36:04 volumio kernel: sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00 Feb 26 09:36:04 volumio kernel: sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Feb 26 09:36:04 volumio kernel: sd 2:0:0:0: [sda] Preferred minimum I/O size 512 bytes Feb 26 09:36:04 volumio kernel: sda: sda1 sda2 sda3 Feb 26 09:36:04 volumio kernel: sd 2:0:0:0: [sda] Attached SCSI disk Feb 26 09:36:04 volumio unknown: [i] initramfs: IMGPART=/dev/sda2 Feb 26 09:36:04 volumio unknown: [i] initramfs: IMGFILE=/volumio_current.sqsh Feb 26 09:36:04 volumio unknown: [i] initramfs: BOOTPART=/dev/sda1 Feb 26 09:36:04 volumio unknown: [i] initramfs: DATAPART=/dev/sda3 Feb 26 09:36:04 volumio kernel: EXT4-fs (sda2): recovery complete Feb 26 09:36:04 volumio kernel: EXT4-fs (sda2): mounted filesystem e8396e64-ac09-46ae-b60c-b73932d465af r/w with ordered data mode. Quota mode: none. Feb 26 09:36:04 volumio unknown: [i] initramfs: GPTSIZEMISMATCH: Feb 26 09:36:04 volumio unknown: [i] initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 26 09:36:04 volumio unknown: [i] initramfs: Checking for a volumio rootfs update on a USB device Feb 26 09:36:04 volumio unknown: [i] initramfs: Checking for factory reset on a USB device Feb 26 09:36:04 volumio unknown: [i] initramfs: Checking for factory or user-data reset on boot device Feb 26 09:36:04 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 26 09:36:04 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 26 09:36:04 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 26 09:36:04 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 26 09:36:04 volumio kernel: loop0: detected capacity change from 0 to 1658552 Feb 26 09:36:04 volumio unknown: [i] initramfs: Volumio version 3.634 Feb 26 09:36:04 volumio kernel: EXT4-fs (sda3): 2 orphan inodes deleted Feb 26 09:36:04 volumio kernel: EXT4-fs (sda3): recovery complete Feb 26 09:36:04 volumio kernel: EXT4-fs (sda3): mounted filesystem 5c3c6231-72b1-4d40-99f1-8e542b29b6d6 r/w with ordered data mode. Quota mode: none. Feb 26 09:36:04 volumio kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Feb 26 09:36:04 volumio unknown: [i] initramfs: Checking if fstab needs to be updated.. Feb 26 09:36:04 volumio unknown: [i] initramfs: Current /dev/sda1 - UUID=2138-C5D3 Feb 26 09:36:04 volumio unknown: [i] initramfs: Current fstb \boot Feb 26 09:36:04 volumio unknown: [i] initramfs: Volumio: 3.634 Feb 26 09:36:04 volumio unknown: [i] initramfs: Finishing initramfs, switching rootfs and starting the boot process... Feb 26 09:36:04 volumio systemd[1]: Inserted module 'autofs4' Feb 26 09:36:04 volumio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Feb 26 09:36:04 volumio systemd[1]: Detected virtualization kvm. Feb 26 09:36:04 volumio systemd[1]: Detected architecture x86-64. Feb 26 09:36:04 volumio systemd[1]: Set hostname to . Feb 26 09:36:04 volumio systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Feb 26 09:36:04 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Feb 26 09:36:04 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 26 09:36:04 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Feb 26 09:36:04 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Feb 26 09:36:04 volumio systemd[1]: Listening on udev Kernel Socket. Feb 26 09:36:04 volumio systemd-journald[256]: Journal started Feb 26 09:36:04 volumio systemd-journald[256]: Runtime journal (/run/log/journal/e00814cb18d0b3744782912965e231f1) is 3.7M, max 30.0M, 26.2M free. Feb 26 09:36:04 volumio fake-hwclock[243]: Current system time: 2025-02-26 01:36:04 Feb 26 09:36:04 volumio fake-hwclock[243]: fake-hwclock saved clock information is in the past: 2024-03-01 19:51:43 Feb 26 09:36:04 volumio fake-hwclock[243]: To set system time to this saved clock anyway, use "force" Feb 26 09:36:04 volumio kernel: RPC: Registered named UNIX socket transport module. Feb 26 09:36:04 volumio kernel: RPC: Registered udp transport module. Feb 26 09:36:04 volumio kernel: RPC: Registered tcp transport module. Feb 26 09:36:04 volumio kernel: RPC: Registered tcp-with-tls transport module. Feb 26 09:36:04 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 26 09:36:04 volumio systemd[1]: Mounted RPC Pipe File System. Feb 26 09:36:04 volumio systemd[1]: Started Create System Users. Feb 26 09:36:04 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Feb 26 09:36:04 volumio systemd[1]: Started Create Static Device Nodes in /dev. Feb 26 09:36:04 volumio systemd[1]: Starting udev Kernel Device Manager... Feb 26 09:36:04 volumio systemd[1]: Reached target Local File Systems (Pre). Feb 26 09:36:04 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 26 09:36:04 volumio systemd[1]: Mounting /tmp... Feb 26 09:36:04 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 26 09:36:04 volumio systemd[1]: Mounting /var/log... Feb 26 09:36:04 volumio systemd[1]: Mounting /var/spool/cups... Feb 26 09:36:04 volumio systemd[1]: Started udev Coldplug all Devices. Feb 26 09:36:04 volumio systemd[1]: Mounted /tmp. Feb 26 09:36:04 volumio systemd[1]: Mounted /var/log. Feb 26 09:36:04 volumio systemd[1]: Mounted /var/spool/cups. Feb 26 09:36:04 volumio systemd[1]: Mounting /var/spool/cups/tmp... Feb 26 09:36:04 volumio systemd-udevd[275]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 26 09:36:04 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Feb 26 09:36:04 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 26 09:36:04 volumio systemd[1]: Mounted /var/spool/cups/tmp. Feb 26 09:36:04 volumio systemd-journald[256]: Runtime journal (/run/log/journal/e00814cb18d0b3744782912965e231f1) is 3.7M, max 30.0M, 26.2M free. Feb 26 09:36:04 volumio systemd[1]: Started Flush Journal to Persistent Storage. Feb 26 09:36:04 volumio systemd[1]: Started udev Kernel Device Manager. Feb 26 09:36:04 volumio systemd[1]: Starting Show Plymouth Boot Screen... Feb 26 09:36:05 volumio systemd[1]: Received SIGRTMIN+20 from PID 300 (plymouthd). Feb 26 09:36:05 volumio systemd[1]: Started Show Plymouth Boot Screen. Feb 26 09:36:05 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 26 09:36:05 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 26 09:36:05 volumio systemd[1]: Reached target Local Encrypted Volumes. Feb 26 09:36:05 volumio systemd[1]: Reached target Paths. Feb 26 09:36:05 volumio kernel: FDC 0 is a S82078B Feb 26 09:36:05 volumio kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Feb 26 09:36:05 volumio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 Feb 26 09:36:05 volumio kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 Feb 26 09:36:05 volumio kernel: ACPI: bus type drm_connector registered Feb 26 09:36:05 volumio kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 Feb 26 09:36:05 volumio kernel: cryptd: max_cpu_qlen set to 1000 Feb 26 09:36:05 volumio kernel: bochs-drm 0000:00:03.0: vgaarb: deactivate vga console Feb 26 09:36:05 volumio kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Feb 26 09:36:05 volumio kernel: Console: switching to colour dummy device 80x25 Feb 26 09:36:05 volumio systemd-udevd[288]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 26 09:36:05 volumio kernel: [drm] Found bochs VGA, ID 0xb0c5. Feb 26 09:36:05 volumio kernel: [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd7000. Feb 26 09:36:05 volumio kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Feb 26 09:36:05 volumio kernel: SSE version of gcm_enc/dec engaged. Feb 26 09:36:05 volumio kernel: [drm] Found EDID data blob. Feb 26 09:36:05 volumio kernel: [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:03.0 on minor 0 Feb 26 09:36:05 volumio kernel: fbcon: bochs-drmdrmfb (fb0) is primary device Feb 26 09:36:05 volumio kernel: Console: switching to colour frame buffer device 160x50 Feb 26 09:36:05 volumio kernel: bochs-drm 0000:00:03.0: [drm] fb0: bochs-drmdrmfb frame buffer device Feb 26 09:36:05 volumio systemd-udevd[298]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:05 volumio systemd-udevd[313]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:05 volumio systemd-udevd[296]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:05 volumio systemd-udevd[287]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:05 volumio systemd-udevd[292]: Using default interface naming scheme 'v240'. Feb 26 09:36:05 volumio systemd-udevd[292]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 26 09:36:05 volumio kernel: mc: Linux media interface: v0.10 Feb 26 09:36:05 volumio systemd-udevd[313]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:05 volumio systemd-udevd[315]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:06 volumio kernel: usb 1-2: 1:3 : unsupported format bits 0x100000000 Feb 26 09:36:06 volumio systemd-udevd[294]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:06 volumio systemd-udevd[304]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 26 09:36:07 volumio kernel: usbcore: registered new interface driver snd-usb-audio Feb 26 09:36:08 volumio kernel: alsactl[389]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Feb 26 09:36:08 volumio systemd-udevd[294]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Feb 26 09:36:08 volumio systemd-udevd[313]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Feb 26 09:36:08 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 26 09:36:08 volumio systemd[1]: Found device QEMU_HARDDISK boot. Feb 26 09:36:08 volumio systemd[1]: Condition check resulted in Virtio network device being skipped. Feb 26 09:36:08 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 26 09:36:08 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 26 09:36:08 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 26 09:36:08 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/2138-C5D3... Feb 26 09:36:08 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 26 09:36:08 volumio systemd[1]: Started File System Check Daemon to report status. Feb 26 09:36:08 volumio systemd-fsck[392]: fsck.fat 4.1 (2017-01-24) Feb 26 09:36:08 volumio systemd-fsck[392]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Feb 26 09:36:08 volumio systemd-fsck[392]: Automatically removing dirty bit. Feb 26 09:36:08 volumio systemd-fsck[392]: Performing changes. Feb 26 09:36:08 volumio systemd-fsck[392]: /dev/sda1: 20 files, 19787/43727 clusters Feb 26 09:36:08 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/2138-C5D3. Feb 26 09:36:08 volumio systemd[1]: Mounting /boot... Feb 26 09:36:08 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 26 09:36:08 volumio systemd[1]: Mounted /boot. Feb 26 09:36:08 volumio systemd[1]: Reached target Local File Systems. Feb 26 09:36:08 volumio systemd[1]: Started ifup for eth0. Feb 26 09:36:08 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 26 09:36:08 volumio systemd[1]: Starting Raise network interfaces... Feb 26 09:36:08 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 26 09:36:09 volumio systemd[1]: Starting Create Volatile Files and Directories... Feb 26 09:36:09 volumio systemd[1]: Starting Preprocess NFS configuration... Feb 26 09:36:09 volumio systemd[1]: Received SIGRTMIN+20 from PID 300 (plymouthd). Feb 26 09:36:09 volumio systemd[1]: plymouth-read-write.service: Succeeded. Feb 26 09:36:09 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 26 09:36:09 volumio systemd[1]: nfs-config.service: Succeeded. Feb 26 09:36:09 volumio systemd[1]: Started Preprocess NFS configuration. Feb 26 09:36:09 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 26 09:36:09 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 26 09:36:09 volumio systemd[1]: Reached target NFS client services. Feb 26 09:36:09 volumio systemd[1]: Started Create Volatile Files and Directories. Feb 26 09:36:09 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 26 09:36:09 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 26 09:36:09 volumio systemd[1]: Starting RPC bind portmap service... Feb 26 09:36:09 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 26 09:36:09 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 26 09:36:09 volumio systemd[1]: Reached target System Initialization. Feb 26 09:36:09 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 26 09:36:09 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 26 09:36:09 volumio systemd[1]: Listening on triggerhappy.socket. Feb 26 09:36:09 volumio systemd[1]: Reached target Sockets. Feb 26 09:36:09 volumio systemd[1]: Started Daily man-db regeneration. Feb 26 09:36:09 volumio systemd[1]: Started Daily apt download activities. Feb 26 09:36:09 volumio systemd[1]: Started Daily apt upgrade and clean activities. Feb 26 09:36:09 volumio systemd[1]: Started Volumio Splash. Feb 26 09:36:09 volumio systemd[1]: Reached target Basic System. Feb 26 09:36:09 volumio systemd[422]: volumiosplash.service: Failed to execute command: No such file or directory Feb 26 09:36:09 volumio systemd[422]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Feb 26 09:36:09 volumio systemd[1]: Started Volumio Iptables Module. Feb 26 09:36:09 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 26 09:36:09 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Feb 26 09:36:09 volumio systemd[1]: Started volumio-remote-updater.service. Feb 26 09:36:09 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 26 09:36:09 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 26 09:36:09 volumio systemd[1]: Started Manage Sound Card State (restore and store). Feb 26 09:36:09 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 26 09:36:09 volumio systemd[1]: Starting DHCP Client Daemon... Feb 26 09:36:09 volumio systemd[1]: Started D-Bus System Message Bus. Feb 26 09:36:09 volumio alsactl[427]: alsactl 1.1.8 daemon started Feb 26 09:36:09 volumio systemd[1]: Starting Wireless Services... Feb 26 09:36:09 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 26 09:36:09 volumio systemd[1]: Starting Save/Restore Sound Card State... Feb 26 09:36:09 volumio systemd[1]: Starting WPA supplicant... Feb 26 09:36:09 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 26 09:36:09 volumio systemd[1]: Started Volumio Log Rotation Service. Feb 26 09:36:09 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 26 09:36:09 volumio systemd[1]: Reached target Timers. Feb 26 09:36:09 volumio systemd[1]: Starting Login Service... Feb 26 09:36:09 volumio systemd[1]: Started RPC bind portmap service. Feb 26 09:36:09 volumio systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Feb 26 09:36:09 volumio systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Feb 26 09:36:09 volumio systemd[1]: Reached target RPC Port Mapper. Feb 26 09:36:09 volumio systemd[1]: Reached target Remote File Systems (Pre). Feb 26 09:36:09 volumio systemd[1]: Reached target Remote File Systems. Feb 26 09:36:09 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 26 09:36:09 volumio alsactl[427]: No state is present for card x20 Feb 26 09:36:09 volumio alsactl[427]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Feb 26 09:36:09 volumio alsactl[427]: Hardware is initialized using a generic method Feb 26 09:36:09 volumio alsactl[427]: No state is present for card x20 Feb 26 09:36:09 volumio thd[425]: Unable to parse trigger line: Feb 26 09:36:09 volumio thd[425]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 26 09:36:09 volumio thd[425]: Unable to parse trigger line: Feb 26 09:36:09 volumio thd[425]: Found socket passed from systemd Feb 26 09:36:09 volumio alsactl[432]: No state is present for card x20 Feb 26 09:36:09 volumio alsactl[432]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Feb 26 09:36:09 volumio alsactl[432]: Hardware is initialized using a generic method Feb 26 09:36:09 volumio alsactl[432]: No state is present for card x20 Feb 26 09:36:09 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 26 09:36:09 volumio ifup[399]: command failed: No such device (-19) Feb 26 09:36:09 volumio sh[397]: command failed: No such device (-19) Feb 26 09:36:09 volumio systemd-logind[437]: New seat seat0. Feb 26 09:36:09 volumio systemd-logind[437]: Watching system buttons on /dev/input/event0 (Power Button) Feb 26 09:36:09 volumio systemd-logind[437]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Feb 26 09:36:09 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 26 09:36:09 volumio avahi-daemon[434]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Feb 26 09:36:09 volumio avahi-daemon[434]: Successfully dropped root privileges. Feb 26 09:36:09 volumio avahi-daemon[434]: avahi-daemon 0.7 starting up. Feb 26 09:36:09 volumio volumio-remote-updater[426]: Error: No active session Feb 26 09:36:09 volumio volumio-remote-updater[426]: [2025-02-26 09:36:09] [info] asio async_connect error: system:111 (Connection refused) Feb 26 09:36:09 volumio volumio-remote-updater[426]: [2025-02-26 09:36:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 26 09:36:09 volumio volumio-remote-updater[426]: [2025-02-26 09:36:09] [error] handle_connect error: Underlying Transport Error Feb 26 09:36:09 volumio ifup[399]: command failed: No such device (-19) Feb 26 09:36:09 volumio haveged[419]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Feb 26 09:36:09 volumio haveged[419]: haveged: cpu: (L4 VC); data: 32K (L2 L4 V); inst: 32K (L2 L4 V); idx: 24/40; sz: 31410/52825 Feb 26 09:36:09 volumio haveged[419]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00269 Feb 26 09:36:09 volumio haveged[419]: haveged: fills: 0, generated: 0 Feb 26 09:36:09 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Feb 26 09:36:09 volumio volumio[424]: Could not open config: /tmp/upmpdcli.conf Feb 26 09:36:09 volumio dhcpcd[429]: no interfaces have a carrier Feb 26 09:36:09 volumio dhcpcd[429]: no interfaces have a carrier Feb 26 09:36:09 volumio dhcpcd[536]: eth0: waiting for carrier Feb 26 09:36:09 volumio dhcpcd[536]: eth0: carrier acquired Feb 26 09:36:09 volumio dhcpcd[429]: forked to background, child pid 536 Feb 26 09:36:09 volumio dhcpcd[429]: forked to background, child pid 536 Feb 26 09:36:09 volumio systemd[1]: Started Login Service. Feb 26 09:36:09 volumio avahi-daemon[434]: Successfully called chroot(). Feb 26 09:36:09 volumio avahi-daemon[434]: Successfully dropped remaining capabilities. Feb 26 09:36:09 volumio systemd[1]: Started triggerhappy global hotkey daemon. Feb 26 09:36:09 volumio wpa_supplicant[433]: Successfully initialized wpa_supplicant Feb 26 09:36:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 26 09:36:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 26 09:36:09 volumio systemd[1]: Started DHCP Client Daemon. Feb 26 09:36:09 volumio dhcpcd[536]: DUID 00:04:65:b0:ab:e5:45:04:48:e1:80:8b:c0:a8:c9:39:fc:e8 Feb 26 09:36:09 volumio dhcpcd[536]: eth0: IAID ee:15:d5:a3 Feb 26 09:36:09 volumio dhcpcd[536]: eth0: adding address fe80::12ff:eeff:fe15:d5a3 Feb 26 09:36:09 volumio dhcpcd[536]: ipv6_addaddr1: Permission denied Feb 26 09:36:09 volumio systemd[1]: Started Save/Restore Sound Card State. Feb 26 09:36:09 volumio dhcpcd[536]: eth0: probing address 192.168.1.9/24 Feb 26 09:36:09 volumio avahi-daemon[434]: No service file found in /etc/avahi/services. Feb 26 09:36:09 volumio avahi-daemon[434]: Network interface enumeration completed. Feb 26 09:36:09 volumio avahi-daemon[434]: Server startup complete. Host name is volumio.local. Local service cookie is 1650177304. Feb 26 09:36:09 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 26 09:36:09 volumio systemd[1]: Started WPA supplicant. Feb 26 09:36:09 volumio systemd[1]: Reached target Sound Card. Feb 26 09:36:09 volumio sh[397]: eth0=eth0 Feb 26 09:36:10 volumio dhcpcd[536]: eth0: soliciting an IPv6 router Feb 26 09:36:10 volumio ifup[399]: command failed: No such device (-19) Feb 26 09:36:10 volumio systemd[1]: iptables.service: Succeeded. Feb 26 09:36:10 volumio loadcpufreq[438]: Loading cpufreq kernel modules...failed. Feb 26 09:36:10 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 26 09:36:10 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 26 09:36:10 volumio systemd[1]: Started Raise network interfaces. Feb 26 09:36:10 volumio systemd[1]: Reached target Network. Feb 26 09:36:10 volumio systemd[1]: Reached target Network is Online. Feb 26 09:36:10 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Feb 26 09:36:10 volumio systemd[1]: Starting /etc/rc.local Compatibility... Feb 26 09:36:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 26 09:36:10 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 26 09:36:10 volumio systemd[1]: Starting Permit User Sessions... Feb 26 09:36:10 volumio systemd[1]: Starting Samba NMB Daemon... Feb 26 09:36:10 volumio cpufrequtils[633]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Feb 26 09:36:10 volumio systemd[1]: Starting Network Time Service... Feb 26 09:36:10 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 26 09:36:10 volumio systemd[1]: Started /etc/rc.local Compatibility. Feb 26 09:36:10 volumio systemd[1]: Started Permit User Sessions. Feb 26 09:36:10 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 26 09:36:10 volumio systemd[1]: Starting Hold until boot process finishes up... Feb 26 09:36:10 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (plymouthd). Feb 26 09:36:10 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (plymouthd). Feb 26 09:36:10 volumio systemd[1]: plymouth-quit.service: Succeeded. Feb 26 09:36:10 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Feb 26 09:36:10 volumio systemd[1]: plymouth-start.service: Succeeded. Feb 26 09:36:10 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 26 09:36:10 volumio systemd[1]: Started Hold until boot process finishes up. Feb 26 09:36:10 volumio ifplugd(eth0)[682]: ifplugd 0.28 initializing. Feb 26 09:36:10 volumio ifplugd(eth0)[682]: Using interface eth0/10:FF:EE:15:D5:A3 with driver (version: 1.0.0) Feb 26 09:36:10 volumio systemd[1]: Started Getty on tty1. Feb 26 09:36:10 volumio ifplugd(eth0)[682]: Using detection mode: SIOCETHTOOL Feb 26 09:36:10 volumio systemd[1]: Reached target Login Prompts. Feb 26 09:36:10 volumio ifplugd(eth0)[682]: Initialization complete, link beat detected. Feb 26 09:36:10 volumio ntpd[663]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 26 09:36:10 volumio ntpd[663]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 26 09:36:10 volumio systemd[1]: Started Network Time Service. Feb 26 09:36:10 volumio ntpd[686]: proto: precision = 0.200 usec (-22) Feb 26 09:36:10 volumio ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 26 09:36:10 volumio ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 26 09:36:10 volumio ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 792 days ago Feb 26 09:36:10 volumio ntpd[686]: Listen and drop on 0 v6wildcard [::]:123 Feb 26 09:36:10 volumio ntpd[686]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 26 09:36:10 volumio ntpd[686]: Listen normally on 2 lo 127.0.0.1:123 Feb 26 09:36:10 volumio ntpd[686]: Listening on routing socket on fd #19 for interface updates Feb 26 09:36:10 volumio ntpd[686]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 26 09:36:10 volumio ntpd[686]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 26 09:36:10 volumio ifplugd(eth0)[682]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 26 09:36:10 volumio ifplugd(eth0)[682]: client: ifup: interface eth0 already configured Feb 26 09:36:10 volumio ifplugd(eth0)[682]: Program executed successfully. Feb 26 09:36:10 volumio ifplugd[652]: Network Interface Plugging Daemon...start eth0...done. Feb 26 09:36:10 volumio systemd[1]: Started LSB: Brings up/down network automatically. Feb 26 09:36:10 volumio nmbd[665]: [2025/02/26 09:36:10.914603, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 26 09:36:10 volumio nmbd[665]: started asyncdns process 693 Feb 26 09:36:10 volumio nmbd[665]: [2025/02/26 09:36:10.916570, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 26 09:36:10 volumio nmbd[665]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 26 09:36:10 volumio nmbd[665]: [2025/02/26 09:36:10.916735, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 26 09:36:10 volumio nmbd[665]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 26 09:36:10 volumio wireless.js[431]: WIRELESS: No wireless interface, exiting Feb 26 09:36:10 volumio iw[698]: command failed: No such device (-19) Feb 26 09:36:11 volumio iwconfig[701]: Error for wireless request "Set Power Management" (8B2C) : Feb 26 09:36:11 volumio iwconfig[701]: SET failed on device wlan0 ; No such device. Feb 26 09:36:11 volumio systemd[1]: wireless.service: Succeeded. Feb 26 09:36:11 volumio systemd[1]: Started Wireless Services. Feb 26 09:36:11 volumio systemd[1]: Started Volumio Backend Module. Feb 26 09:36:11 volumio systemd[1]: Started Start Volumio Kiosk. Feb 26 09:36:11 volumio systemd[1]: Started Volumio Cpu Tweaker. Feb 26 09:36:11 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: Setting RT Priority for mpd Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: pid 35's current scheduling policy: SCHED_FIFO Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: pid 35's current scheduling priority: 99 Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: Setting MPD Affinity Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: pid 3's current affinity mask: f Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: Error setting new values. Common errors: Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: - Do you have proper administration rights? (super-user?) Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: - Is the governor you requested available and modprobed? Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: - Trying to set an invalid policy? Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: - Trying to set a specific frequency, but userspace governor is not available, Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: for example because of hardware which cannot be set to a specific frequency Feb 26 09:36:11 volumio volumio-cpu-tweak[706]: or because the userspace governor isn't loaded? Feb 26 09:36:11 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 26 09:36:11 volumio startx[705]: X.Org X Server 1.20.4 Feb 26 09:36:11 volumio startx[705]: X Protocol Version 11, Revision 0 Feb 26 09:36:11 volumio startx[705]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Feb 26 09:36:11 volumio startx[705]: Current Operating System: Linux volumio 6.6.7-volumio #2 SMP PREEMPT_DYNAMIC Mon Dec 18 16:22:28 CET 2023 x86_64 Feb 26 09:36:11 volumio startx[705]: Kernel command line: BOOT_IMAGE=vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=e8396e64-ac09-46ae-b60c-b73932d465af bootpart=UUID=2138-C5D3 datapart=UUID=5c3c6231-72b1-4d40-99f1-8e542b29b6d6 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 initrd=volumio.initrd Feb 26 09:36:11 volumio startx[705]: Build Date: 18 December 2021 09:05:36AM Feb 26 09:36:11 volumio startx[705]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Feb 26 09:36:11 volumio startx[705]: Current version of pixman: 0.36.0 Feb 26 09:36:11 volumio startx[705]: Before reporting problems, check http://wiki.x.org Feb 26 09:36:11 volumio startx[705]: to make sure that you have the latest version. Feb 26 09:36:11 volumio startx[705]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 26 09:36:11 volumio startx[705]: (++) from command line, (!!) notice, (II) informational, Feb 26 09:36:11 volumio startx[705]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 26 09:36:11 volumio startx[705]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Feb 26 09:36:11 2025 Feb 26 09:36:11 volumio startx[705]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 26 09:36:11 volumio startx[705]: pci id for fd 14: 1234:1111, driver (null) Feb 26 09:36:11 volumio ntpd[686]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 26 09:36:12 volumio systemd[1]: soundcard-init.service: Succeeded. Feb 26 09:36:12 volumio ntpd[686]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 26 09:36:12 volumio kernel: broken atomic modeset userspace detected, disabling atomic Feb 26 09:36:12 volumio startx[705]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Feb 26 09:36:13 volumio ntpd[686]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 26 09:36:13 volumio volumio[704]: info: ------------------------------------------- Feb 26 09:36:13 volumio volumio[704]: info: ----- Volumio3 ---- Feb 26 09:36:13 volumio volumio[704]: info: ------------------------------------------- Feb 26 09:36:13 volumio volumio[704]: info: ----- System startup ---- Feb 26 09:36:13 volumio volumio[704]: info: ------------------------------------------- Feb 26 09:36:14 volumio ntpd[686]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 26 09:36:14 volumio volumio-remote-updater[426]: [2025-02-26 09:36:14] [connect] Successful connection Feb 26 09:36:15 volumio dhcpcd[536]: eth0: using static address 192.168.1.9/24 Feb 26 09:36:15 volumio dhcpcd[536]: eth0: adding route to 192.168.1.0/24 Feb 26 09:36:15 volumio dhcpcd[536]: eth0: adding default route via 192.168.1.1 Feb 26 09:36:15 volumio avahi-daemon[434]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.9. Feb 26 09:36:15 volumio avahi-daemon[434]: New relevant interface eth0.IPv4 for mDNS. Feb 26 09:36:15 volumio avahi-daemon[434]: Registering new address record for 192.168.1.9 on eth0.IPv4. Feb 26 09:36:15 volumio volumio[704]: info: MYVOLUMIO Environment detected Feb 26 09:36:15 volumio volumio[704]: info: Plugin folders cleanup Feb 26 09:36:15 volumio volumio[704]: info: Scanning into folder /volumio/app/plugins/ Feb 26 09:36:15 volumio volumio[704]: info: Scanning category audio_interface Feb 26 09:36:15 volumio volumio[704]: info: Scanning category miscellanea Feb 26 09:36:15 volumio volumio[704]: info: Scanning category music_service Feb 26 09:36:15 volumio volumio[704]: info: Scanning category plugins.json Feb 26 09:36:15 volumio volumio[704]: info: Scanning category system_controller Feb 26 09:36:15 volumio volumio[704]: info: Scanning category user_interface Feb 26 09:36:15 volumio volumio[704]: info: Scanning into folder /data/plugins/ Feb 26 09:36:15 volumio volumio[704]: info: Plugin folders cleanup completed Feb 26 09:36:15 volumio volumio[704]: info: ------------------------------------------- Feb 26 09:36:15 volumio volumio[704]: info: ----- Core plugins startup ---- Feb 26 09:36:15 volumio volumio[704]: info: ------------------------------------------- Feb 26 09:36:15 volumio volumio[704]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 09:36:15 volumio volumio[704]: info: Adding plugin upnp to MyMusic Plugins Feb 26 09:36:15 volumio volumio[704]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 09:36:15 volumio volumio[704]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 09:36:15 volumio volumio[704]: info: Loading plugins from folder /data/plugins/ Feb 26 09:36:15 volumio volumio[704]: info: Loading plugin "system"... Feb 26 09:36:15 volumio volumio[704]: info: Loading plugin "appearance"... Feb 26 09:36:15 volumio systemd[1]: Started Samba NMB Daemon. Feb 26 09:36:15 volumio nmbd[665]: [2025/02/26 09:36:15.931494, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 26 09:36:15 volumio nmbd[665]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 26 09:36:15 volumio nmbd[665]: [2025/02/26 09:36:15.962344, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 26 09:36:15 volumio nmbd[665]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.9 for name WORKGROUP<1d>. Feb 26 09:36:15 volumio nmbd[665]: This response was from IP 192.168.1.8, reporting an IP address of 192.168.1.8. Feb 26 09:36:15 volumio systemd[1]: Starting Samba Winbind Daemon... Feb 26 09:36:16 volumio winbindd[933]: [2025/02/26 09:36:16.357325, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 26 09:36:16 volumio winbindd[933]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 26 09:36:16 volumio winbindd[933]: [2025/02/26 09:36:16.373069, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 26 09:36:16 volumio systemd[1]: Started Samba Winbind Daemon. Feb 26 09:36:16 volumio winbindd[933]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 26 09:36:16 volumio systemd[1]: Starting Samba SMB Daemon... Feb 26 09:36:16 volumio ntpd[686]: Listen normally on 3 eth0 192.168.1.9:123 Feb 26 09:36:16 volumio ntpd[686]: new interface(s) found: waking up resolver Feb 26 09:36:16 volumio smbd[938]: [2025/02/26 09:36:16.994304, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 26 09:36:16 volumio systemd[1]: Started Samba SMB Daemon. Feb 26 09:36:16 volumio smbd[938]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 26 09:36:16 volumio systemd[1]: Reached target Multi-User System. Feb 26 09:36:16 volumio systemd[1]: Reached target Graphical Interface. Feb 26 09:36:17 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 26 09:36:17 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 26 09:36:17 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 26 09:36:17 volumio systemd[1]: Startup finished in 18.877s (kernel) + 13.423s (userspace) = 32.300s. Feb 26 09:36:17 volumio volumio[704]: info: Loading plugin "network"... Feb 26 09:36:17 volumio volumio[704]: info: Refreshing Cached IP Addresses Feb 26 09:36:17 volumio volumio[704]: info: Loading plugin "services"... Feb 26 09:36:17 volumio volumio[704]: info: Loading plugin "alsa_controller"... Feb 26 09:36:17 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 09:36:17 volumio volumio[704]: info: Loading plugin "wizard"... Feb 26 09:36:18 volumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 09:36:18 volumio sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 09:36:18 volumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:18 volumio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 09:36:18 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:18 volumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:18 volumio volumio[704]: info: Loading plugin "networkfs"... Feb 26 09:36:18 volumio sudo[951]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:18 volumio sudo[955]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:18 volumio sudo[953]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:18 volumio volumio[704]: info: Starting Udev Watcher for removable devices Feb 26 09:36:18 volumio sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=v,password=vwy321,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.8/Music /mnt/NAS/Music Feb 26 09:36:18 volumio sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:18 volumio volumio[704]: info: Ignoring mount for partition: boot Feb 26 09:36:18 volumio volumio[704]: info: Ignoring mount for partition: volumio Feb 26 09:36:18 volumio volumio[704]: info: Ignoring mount for partition: volumio_data Feb 26 09:36:18 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 09:36:18 volumio volumio[704]: info: Loading plugin "volumio_command_line_client"... Feb 26 09:36:18 volumio volumio[704]: info: Loading plugin "upnp"... Feb 26 09:36:18 volumio volumio[704]: info: [1740533778139] Starting Upmpd Daemon Feb 26 09:36:18 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 09:36:18 volumio volumio[704]: info: Loading plugin "my_music"... Feb 26 09:36:18 volumio volumio[704]: info: Loading plugin "mpd"... Feb 26 09:36:18 volumio kernel: FS-Cache: Loaded Feb 26 09:36:18 volumio kernel: Key type dns_resolver registered Feb 26 09:36:18 volumio kernel: Key type cifs.spnego registered Feb 26 09:36:18 volumio kernel: Key type cifs.idmap registered Feb 26 09:36:18 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 26 09:36:18 volumio kernel: CIFS: Attempting to mount //192.168.1.8/Music Feb 26 09:36:18 volumio sudo[975]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:18 volumio volumio[704]: info: Loading plugin "upnp_browser"... Feb 26 09:36:19 volumio volumio[704]: info: Loading plugin "alarm-clock"... Feb 26 09:36:19 volumio dbus-daemon[430]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.7' (uid=0 pid=889 comm="/usr/lib/chromium/chromium --show-component-extens") Feb 26 09:36:19 volumio systemd[1]: Starting Bluetooth service... Feb 26 09:36:19 volumio volumio[704]: info: Loading plugin "airplay_emulation"... Feb 26 09:36:19 volumio volumio[704]: info: Starting Shairport Sync Feb 26 09:36:19 volumio volumio[704]: info: Loading plugin "last_100"... Feb 26 09:36:19 volumio volumio[704]: info: Loading plugin "webradio"... Feb 26 09:36:19 volumio kernel: Bluetooth: Core ver 2.22 Feb 26 09:36:19 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Feb 26 09:36:19 volumio kernel: Bluetooth: HCI device and connection manager initialized Feb 26 09:36:19 volumio kernel: Bluetooth: HCI socket layer initialized Feb 26 09:36:19 volumio kernel: Bluetooth: L2CAP socket layer initialized Feb 26 09:36:19 volumio kernel: Bluetooth: SCO socket layer initialized Feb 26 09:36:19 volumio bluetoothd[1012]: Bluetooth daemon 5.50 Feb 26 09:36:19 volumio bluetoothd[1012]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 26 09:36:19 volumio dbus-daemon[430]: [system] Successfully activated service 'org.bluez' Feb 26 09:36:19 volumio systemd[1]: Started Bluetooth service. Feb 26 09:36:19 volumio bluetoothd[1012]: Starting SDP server Feb 26 09:36:19 volumio bluetoothd[1012]: Excluding (cli) sap Feb 26 09:36:19 volumio volumio[704]: info: Loading plugin "i2s_dacs"... Feb 26 09:36:19 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 26 09:36:19 volumio kernel: Bluetooth: BNEP filters: protocol multicast Feb 26 09:36:19 volumio kernel: Bluetooth: BNEP socket layer initialized Feb 26 09:36:19 volumio bluetoothd[1012]: Bluetooth management interface 1.22 initialized Feb 26 09:36:19 volumio dbus-daemon[430]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.8' (uid=0 pid=1012 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 26 09:36:19 volumio volumio[704]: info: I2S DAC not set, start Auto-detection Feb 26 09:36:19 volumio volumio[704]: info: Loading plugin "volumiodiscovery"... Feb 26 09:36:19 volumio systemd[1]: Starting Hostname Service... Feb 26 09:36:19 volumio volumio[704]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 09:36:19 volumio node[704]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 09:36:19 volumio volumio[704]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 09:36:19 volumio node[704]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 09:36:19 volumio volumio[704]: *** WARNING *** For more information see Feb 26 09:36:19 volumio node[704]: *** WARNING *** For more information see Feb 26 09:36:19 volumio volumio[704]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 09:36:19 volumio node[704]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 09:36:19 volumio volumio[704]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 09:36:19 volumio node[704]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 09:36:19 volumio volumio[704]: *** WARNING *** For more information see Feb 26 09:36:19 volumio node[704]: *** WARNING *** For more information see Feb 26 09:36:19 volumio volumio[704]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 09:36:19 volumio volumio[704]: info: Discovery: Started advertising with name: Volumio Feb 26 09:36:20 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 09:36:20 volumio volumio[704]: info: Loading plugin "outputs"... Feb 26 09:36:20 volumio volumio[704]: info: Loading plugin "albumart"... Feb 26 09:36:20 volumio volumio[704]: info: Plugin example_plugin is not enabled Feb 26 09:36:20 volumio volumio[704]: info: Loading plugin "inputs"... Feb 26 09:36:20 volumio dbus-daemon[430]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 26 09:36:20 volumio systemd[1]: Started Hostname Service. Feb 26 09:36:20 volumio volumio[704]: info: Loading plugin "updater_comm"... Feb 26 09:36:20 volumio volumio[704]: Forking 3 albumart workers Feb 26 09:36:20 volumio volumio[704]: info: Plugin mpdemulation is not enabled Feb 26 09:36:20 volumio volumio[704]: info: Loading plugin "rest_api"... Feb 26 09:36:20 volumio volumio[704]: info: Loading plugin "websocket"... Feb 26 09:36:20 volumio volumio[704]: info: Loading i18n strings for locale zh Feb 26 09:36:20 volumio volumio[704]: Updating browse sources language Feb 26 09:36:20 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 09:36:20 volumio volumio[704]: Starting albumart workers Feb 26 09:36:20 volumio volumio[704]: Starting albumart workers Feb 26 09:36:21 volumio volumio[704]: Starting albumart workers Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::initPlayerControls Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:21 volumio volumio[704]: Express server listening on port 3000 Feb 26 09:36:21 volumio volumio[704]: [Metrics] WebUI: 8s 559.63ms Feb 26 09:36:21 volumio volumio[704]: info: Setting Device type: x86 Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::resetVolumioState Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::getcurrentVolume Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:21 volumio volumio[704]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 26 09:36:21 volumio volumio[704]: info: Completed loading Core Plugins Feb 26 09:36:21 volumio volumio[704]: info: Preparing to generate the ALSA configuration file Feb 26 09:36:21 volumio volumio[704]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 26 09:36:21 volumio volumio[704]: wlan0 Interface doesn't support scanning. Feb 26 09:36:21 volumio volumio[704]: info: Cannot use regular scanning, forcing with ap-force Feb 26 09:36:21 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 26 09:36:21 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:21 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:21 volumio volumio[704]: command failed: No such device (-19) Feb 26 09:36:21 volumio volumio[704]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 26 09:36:21 volumio volumio[704]: command failed: No such device (-19) Feb 26 09:36:21 volumio volumio[704]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 26 09:36:21 volumio volumio[704]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 26 09:36:21 volumio volumio[704]: info: Reading ALSA contributions from plugins. Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:21 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::updateTrackBlock Feb 26 09:36:21 volumio volumio[704]: info: CorePlayQueue::getTrackBlock Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 09:36:21 volumio volumio[704]: info: Reloading queue from file Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::setRepeat false single undefined Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:21 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::setRandom null Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:21 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:21 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:21 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:21 volumio volumio[704]: info: Asound.conf file unchanged, so no further update is needed Feb 26 09:36:21 volumio volumio[704]: info: Output device has changed, restarting MPD Feb 26 09:36:21 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 09:36:21 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:21 volumio volumio[704]: info: Output device has changed, restarting Shairport Sync Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:21 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:21 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:21 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 09:36:21 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:21 volumio systemd[1]: Listening on mpd.socket. Feb 26 09:36:21 volumio volumio[704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 09:36:21 volumio volumio[704]: info: ___________ START PLUGINS ___________ Feb 26 09:36:21 volumio volumio[704]: info: ControllerMpd::onStart: Initializing MPD Feb 26 09:36:21 volumio volumio[704]: info: Creating MPD Configuration file Feb 26 09:36:22 volumio systemd[1]: Starting Music Player Daemon... Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 09:36:22 volumio volumio[704]: info: [1740533782023] CoreMusicLibrary::Adding element 媒体服务器 Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 09:36:22 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 09:36:22 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:22 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:22 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 09:36:22 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 26 09:36:22 volumio systemd[1]: mpd.service: Succeeded. Feb 26 09:36:22 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 09:36:22 volumio systemd[1]: Starting Music Player Daemon... Feb 26 09:36:22 volumio volumio[704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 09:36:22 volumio volumio[704]: info: [1740533782148] CoreMusicLibrary::Adding element Last_100 Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 09:36:22 volumio volumio[704]: info: [1740533782152] CoreMusicLibrary::Adding element Webradio Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 09:36:22 volumio volumio[704]: info: Volumio Calling Home Feb 26 09:36:22 volumio volumio[704]: info: MPD Permissions set Feb 26 09:36:22 volumio volumio[704]: info: MPD Permissions set Feb 26 09:36:22 volumio volumio[704]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:22 volumio volumio[704]: info: Discovery: adding 5a69fd5e-403a-4b5f-870a-686bf3ef9205 Feb 26 09:36:22 volumio volumio[704]: info: Discovery: Found device Volumio Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:22 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:22 volumio volumio[704]: info: Starting Shairport Sync Feb 26 09:36:22 volumio volumio[704]: info: Starting Shairport Sync Feb 26 09:36:22 volumio volumio[704]: info: Starting Shairport Sync Feb 26 09:36:22 volumio sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 09:36:22 volumio sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:22 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 09:36:22 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 26 09:36:22 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 26 09:36:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 26 09:36:22 volumio sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 09:36:22 volumio sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 26 09:36:22 volumio sudo[1229]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 26 09:36:22 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 26 09:36:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 26 09:36:22 volumio volumio[704]: info: Shairport-Sync Started Feb 26 09:36:22 volumio volumio[704]: Error adding Membership: Error: addMembership EINVAL Feb 26 09:36:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 26 09:36:22 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:22 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:22 volumio sudo[1234]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:22 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:22 volumio volumio[704]: info: Shairport-Sync Started Feb 26 09:36:22 volumio volumio[704]: info: Shairport-Sync Started Feb 26 09:36:22 volumio volumio[704]: info: Volumio called home Feb 26 09:36:23 volumio mpd[1207]: Feb 26 09:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 09:36:24 volumio systemd[1]: Started Music Player Daemon. Feb 26 09:36:24 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:24 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:24 volumio volumio[704]: info: Completed starting Core Plugins Feb 26 09:36:24 volumio volumio[704]: info: ------------------------------------------- Feb 26 09:36:24 volumio volumio[704]: info: ----- MyVolumio plugins startup ---- Feb 26 09:36:24 volumio volumio[704]: info: ------------------------------------------- Feb 26 09:36:24 volumio volumio[704]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 26 09:36:24 volumio volumio[704]: error: MPD error: The expression evaluated to a falsy value: Feb 26 09:36:24 volumio volumio[704]: assert.ok(self.idling) Feb 26 09:36:24 volumio volumio[704]: error: The expression evaluated to a falsy value: Feb 26 09:36:24 volumio volumio[704]: assert.ok(self.idling) Feb 26 09:36:24 volumio volumio[704]: error: updateQueue error: null Feb 26 09:36:24 volumio volumio[704]: info: MPD running with PID1207 Feb 26 09:36:24 volumio volumio[704]: ,establishing connection Feb 26 09:36:24 volumio volumio[704]: error: updateQueue error: null Feb 26 09:36:24 volumio volumio[704]: 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 Total Clients: 2 Feb 26 09:36:25 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 09:36:25 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:25 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:25 volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 09:36:25 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:25 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:25 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 26 09:36:25 volumio volumio[704]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.217 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Total Clients: 3 Feb 26 09:36:26 volumio volumio[704]: 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 Total Clients: 4 Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:26 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 09:36:26 volumio volumio[704]: info: Received Get System Info Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 09:36:26 volumio volumio[704]: info: Discovery: Getting this device information Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:26 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:26 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:26 volumio volumio[704]: info: Listing playlists Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 09:36:26 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 09:36:27 volumio volumio-remote-updater[426]: [2025-02-26 09:36:27] [connect] Successful connection Feb 26 09:36:27 volumio volumio-remote-updater[426]: [2025-02-26 09:36:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740533787 101 Feb 26 09:36:27 volumio volumio[704]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Feb 26 09:36:27 volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 09:36:27 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:27 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:27 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 09:36:27 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:27 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:27 volumio volumio[704]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.217 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Total Clients: 6 Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:27 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 09:36:27 volumio volumio[704]: info: Received Get System Info Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 09:36:27 volumio volumio[704]: info: Discovery: Getting this device information Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:27 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:27 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:27 volumio volumio[704]: info: Listing playlists Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 09:36:27 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:27 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:28 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 09:36:28 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:28 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:28 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 09:36:28 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:28 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:28 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 09:36:28 volumio volumio[704]: info: Received Get System Info Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 09:36:28 volumio volumio[704]: info: Discovery: Getting this device information Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:28 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:28 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:28 volumio volumio[704]: info: Listing playlists Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 09:36:28 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 26 09:36:28 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:28 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 09:36:28 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 26 09:36:28 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:29 volumio volumio[704]: info: Received Get System Info Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 09:36:29 volumio volumio[704]: info: Discovery: Getting this device information Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:29 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:29 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:29 volumio volumio[704]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 26 09:36:29 volumio volumio[704]: info: Upmpdcli Daemon Started Feb 26 09:36:29 volumio volumio[1275]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 09:36:29 volumio volumio[704]: info: Received Get System Info Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 09:36:29 volumio volumio[704]: info: Discovery: Getting this device information Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:29 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 09:36:29 volumio volumio[1275]: .................++++ Feb 26 09:36:32 volumio volumio[1275]: ..................................................................................................++++ Feb 26 09:36:32 volumio volumio[1275]: e is 65537 (0x010001) Feb 26 09:36:32 volumio volumio[1275]: writing RSA key Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 26 09:36:33 volumio volumio[704]: info: Adding plugin bluetooth to MyMusic Plugins Feb 26 09:36:33 volumio volumio[704]: info: Adding plugin multiroom to MyMusic Plugins Feb 26 09:36:33 volumio volumio[704]: info: Adding plugin metavolumio to MyMusic Plugins Feb 26 09:36:33 volumio volumio[704]: info: Adding plugin cd_controller to MyMusic Plugins Feb 26 09:36:33 volumio volumio[704]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 26 09:36:33 volumio volumio[704]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 26 09:36:33 volumio volumio[704]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 26 09:36:34 volumio volumio[704]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 26 09:36:34 volumio volumio[704]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 26 09:36:34 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:34 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:34 volumio volumio[704]: info: Starting MyVolumio Remote Streaming Endpoints Feb 26 09:36:34 volumio volumio[704]: info: MyVolumio not started Feb 26 09:36:34 volumio volumio[704]: info: Initializing device activation check Feb 26 09:36:34 volumio volumio[704]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 26 09:36:34 volumio volumio[704]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 26 09:36:35 volumio volumio[704]: info: Starting Streaming Service Transparent Proxy Feb 26 09:36:35 volumio volumio[704]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 26 09:36:35 volumio volumio[704]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 26 09:36:35 volumio volumio[704]: info: Streaming services startup Feb 26 09:36:35 volumio volumio[704]: info: Starting Streaming Daemon Feb 26 09:36:35 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 26 09:36:35 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:36:35 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root Feb 26 09:36:35 volumio volumio[704]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 26 09:36:35 volumio volumio[704]: info: CoreCommandRouter::volumioPlay Feb 26 09:36:35 volumio volumio[704]: info: CoreStateMachine::play index undefined Feb 26 09:36:35 volumio volumio[704]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 09:36:35 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:35 volumio volumio[704]: info: CoreStateMachine::startPlaybackTimer Feb 26 09:36:35 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:35 volumio volumio[704]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3 Feb 26 09:36:35 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 09:36:35 volumio volumio[704]: error: Cannot start Volumio Streaming Daemon Feb 26 09:36:35 volumio volumio[704]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 26 09:36:35 volumio volumio[704]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 26 09:36:35 volumio volumio[704]: info: sendMpdCommand stop took 27 milliseconds Feb 26 09:36:35 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 09:36:35 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 09:36:35 volumio volumio[704]: info: sendMpdCommand clear took 5 milliseconds Feb 26 09:36:35 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3" Feb 26 09:36:35 volumio volumio[704]: error: updateQueue error: null Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 09:36:35 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 09:36:35 volumio volumio[704]: info: ------------------------------ 16ms Feb 26 09:36:35 volumio volumio[704]: info: sendMpdCommand add "NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3" took 8 milliseconds Feb 26 09:36:35 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand play Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 09:36:35 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 09:36:35 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 09:36:35 volumio volumio[704]: info: ------------------------------ 15ms Feb 26 09:36:35 volumio volumio[704]: info: sendMpdCommand play took 14 milliseconds Feb 26 09:36:35 volumio volumio[704]: info: ------------------------------ 15ms Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 09:36:35 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 09:36:35 volumio volumio[704]: info: ------------------------------ 17ms Feb 26 09:36:35 volumio volumio[704]: info: ------------------------------ 5ms Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 09:36:35 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 09:36:35 volumio volumio[704]: info: ------------------------------ 6ms Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:35 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:35 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:35 volumio volumio[704]: info: Feb 26 09:36:35 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:35 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:36 volumio volumio[704]: info: Feb 26 09:36:36 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand status took 149 milliseconds Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:36 volumio volumio[704]: info: Feb 26 09:36:36 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:36 volumio volumio[704]: info: Feb 26 09:36:36 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:36 volumio volumio[704]: info: Feb 26 09:36:36 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand status took 186 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand status took 183 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand playlistinfo took 46 milliseconds Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:36 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus stop Feb 26 09:36:36 volumio volumio[704]: info: ------------------------------ 211ms Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand status took 22 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand status took 19 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand status took 16 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":717,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:36 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 09:36:36 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:36 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 09:36:36 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: ------------------------------ 268ms Feb 26 09:36:36 volumio volumio[704]: info: ------------------------------ 260ms Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 26 09:36:36 volumio volumio[704]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:36 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:36 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 09:36:36 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:36 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 09:36:36 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:36 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 09:36:36 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:36 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:36 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:36 volumio volumio[704]: info: ------------------------------ 152ms Feb 26 09:36:36 volumio volumio[704]: info: ------------------------------ 150ms Feb 26 09:36:36 volumio volumio[704]: info: ------------------------------ 148ms Feb 26 09:36:36 volumio volumio[704]: STREAMING PROXY: Starting server on port 3245 Feb 26 09:36:36 volumio volumio[704]: Node JS runtime: 14 Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::volumioPause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::pause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::stPlaybackTimer Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::servicePause Feb 26 09:36:37 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::servicePause Feb 26 09:36:37 volumio volumio[704]: info: ControllerMpd::pause Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand pause Feb 26 09:36:37 volumio volumio[704]: info: sendMpdCommand pause took 12 milliseconds Feb 26 09:36:37 volumio volumio[704]: info: Feb 26 09:36:37 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:37 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:37 volumio volumio[704]: info: Feb 26 09:36:37 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:37 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:37 volumio volumio[704]: info: Feb 26 09:36:37 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:37 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:37 volumio volumio[704]: info: sendMpdCommand status took 6 milliseconds Feb 26 09:36:37 volumio volumio[704]: info: sendMpdCommand status took 4 milliseconds Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:37 volumio volumio[704]: info: sendMpdCommand status took 5 milliseconds Feb 26 09:36:37 volumio volumio[704]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 09:36:37 volumio volumio[704]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:37 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:37 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:37 volumio volumio[704]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1980,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:37 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::syncState stateService pause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus pause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:37 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::stPlaybackTimer Feb 26 09:36:37 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:37 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:37 volumio volumio[704]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1980,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:37 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::syncState stateService pause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus pause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:37 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::stPlaybackTimer Feb 26 09:36:37 volumio volumio[704]: info: ------------------------------ 37ms Feb 26 09:36:37 volumio volumio[704]: info: ------------------------------ 36ms Feb 26 09:36:37 volumio volumio[704]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 26 09:36:37 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:37 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:37 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:37 volumio volumio[704]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1980,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:37 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::syncState stateService pause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus pause Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:37 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:37 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:37 volumio volumio[704]: info: CoreStateMachine::stPlaybackTimer Feb 26 09:36:37 volumio volumio[704]: info: ------------------------------ 50ms Feb 26 09:36:38 volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 26 09:36:41 volumio volumio[704]: info: VolumeController::SetAlsaVolume22 Feb 26 09:36:41 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:41 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:41 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:41 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::volumioPlay Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::play index undefined Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::startPlaybackTimer Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: info: ControllerMpd::resume Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand play Feb 26 09:36:44 volumio volumio[704]: info: Feb 26 09:36:44 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:44 volumio volumio[704]: info: sendMpdCommand play took 192 milliseconds Feb 26 09:36:44 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:44 volumio volumio[704]: info: sendMpdCommand status took 5 milliseconds Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:44 volumio volumio[704]: info: Feb 26 09:36:44 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:44 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:44 volumio volumio[704]: info: Feb 26 09:36:44 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 09:36:44 volumio volumio[704]: info: ControllerMpd::getState Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 09:36:44 volumio volumio[704]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 26 09:36:44 volumio volumio[704]: info: sendMpdCommand status took 16 milliseconds Feb 26 09:36:44 volumio volumio[704]: info: sendMpdCommand status took 9 milliseconds Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 09:36:44 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2845,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:44 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus pause Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:44 volumio volumio[704]: info: ------------------------------ 77ms Feb 26 09:36:44 volumio volumio[704]: info: sendMpdCommand playlistinfo took 38 milliseconds Feb 26 09:36:44 volumio volumio[704]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 26 09:36:44 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 09:36:44 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2845,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:44 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 09:36:44 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:44 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2845,"duration":125,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沉默是金-(翻唱)","artist":"刘德华&张学友","album":null,"uri":"NAS/Music/Music/刘德华&张学友-沉默是金-(翻唱).mp3","trackType":"mp3"} Feb 26 09:36:44 volumio volumio[704]: verbose: CURRENT POSITION 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 09:36:44 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:44 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:44 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:44 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:44 volumio volumio[704]: info: ------------------------------ 118ms Feb 26 09:36:44 volumio volumio[704]: info: ------------------------------ 112ms Feb 26 09:36:49 volumio volumio[704]: info: VolumeController::SetAlsaVolume36 Feb 26 09:36:49 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:49 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:49 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:49 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:49 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 09:36:49 volumio volumio[704]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Feb 26 09:36:49 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 09:36:49 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:50 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Feb 26 09:36:50 volumio volumio[704]: info: VolumeController::SetAlsaVolume46 Feb 26 09:36:50 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:36:50 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:36:50 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:36:50 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:36:52 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 09:36:52 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:52 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 26 09:36:53 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 26 09:36:54 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:54 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:54 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 26 09:36:54 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 09:36:54 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 26 09:36:54 volumio volumio[704]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 09:36:54 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 09:36:55 volumio volumio[704]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 26 09:36:55 volumio volumio[704]: info: Completed starting MyVolumio Plugin Feb 26 09:36:55 volumio volumio[704]: info: BOOT COMPLETED Feb 26 09:36:55 volumio volumio[704]: [Metrics] CommandRouter: 42s 32.60ms Feb 26 09:36:55 volumio volumio[704]: info: CoreCommandRouter::volumiosetStartupVolume Feb 26 09:36:55 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:36:55 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:36:55 volumio volumio[704]: info: CoreCommandRouter::Close All Modals sent Feb 26 09:36:55 volumio volumio[704]: info: CoreCommandRouter::Close All Modals sent Feb 26 09:36:55 volumio volumio[704]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 26 09:36:55 volumio volumio[704]: aplay: main:828: audio open error: Device or resource busy Feb 26 09:36:56 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 26 09:36:56 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 09:36:56 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 26 09:37:01 volumio volumio[704]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Feb 26 09:37:01 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Feb 26 09:37:01 volumio sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 09:37:01 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:37:01 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:37:01 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:37:01 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Feb 26 09:37:01 volumio sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 09:37:01 volumio sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 09:37:01 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 09:37:01 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 09:37:01 volumio systemd[1]: Stopping Music Player Daemon... Feb 26 09:37:01 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 09:37:01 volumio volumio[704]: info: MPD Permissions set Feb 26 09:37:01 volumio volumio[704]: error: Upnp client error: Error: This socket has been ended by the other party Feb 26 09:37:01 volumio systemd[1]: mpd.service: Succeeded. Feb 26 09:37:01 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 09:37:01 volumio systemd[1]: Starting Music Player Daemon... Feb 26 09:37:02 volumio mpd[1568]: Feb 26 09:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 09:37:02 volumio systemd[1]: Started Music Player Daemon. Feb 26 09:37:02 volumio sudo[1559]: pam_unix(sudo:session): session closed for user root Feb 26 09:37:02 volumio volumio[704]: error: updateQueue error: null Feb 26 09:37:07 volumio volumio-remote-updater[426]: No test mode Feb 26 09:37:07 volumio volumio-remote-updater[426]: No alpha test mode Feb 26 09:37:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 26 09:37:08 volumio volumio[704]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations
    • Fix Multiroom Buffer Sizes
    • Increase results for Kana-Kanji Conversion
    • Fix for streaming interruptions

 

 

","title":"Update v3.779","updateavailable":true} Feb 26 09:37:08 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 26 09:37:11 volumio volumio[704]: info: CoreCommandRouter::volumioPause Feb 26 09:37:11 volumio volumio[704]: info: CoreStateMachine::pause Feb 26 09:37:11 volumio volumio[704]: info: CoreStateMachine::stPlaybackTimer Feb 26 09:37:11 volumio volumio[704]: info: CoreStateMachine::servicePause Feb 26 09:37:11 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:37:11 volumio volumio[704]: info: CoreCommandRouter::servicePause Feb 26 09:37:11 volumio volumio[704]: info: ControllerMpd::pause Feb 26 09:37:11 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand pause Feb 26 09:37:11 volumio volumio[704]: info: sendMpdCommand pause took 3 milliseconds Feb 26 09:37:13 volumio volumio[704]: info: CoreCommandRouter::volumioPause Feb 26 09:37:13 volumio volumio[704]: info: CoreStateMachine::pause Feb 26 09:37:14 volumio volumio[704]: info: CoreCommandRouter::volumioPrevious Feb 26 09:37:14 volumio volumio[704]: info: CoreStateMachine::previous Feb 26 09:37:14 volumio volumio[704]: info: CoreStateMachine::updateTrackBlock Feb 26 09:37:14 volumio volumio[704]: info: CorePlayQueue::getTrackBlock Feb 26 09:37:14 volumio volumio[704]: info: CoreStateMachine::serviceClearAddPlay Feb 26 09:37:14 volumio volumio[704]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 26 09:37:14 volumio volumio[704]: error: WARNING: No clearAddPlayTracks method for service mpd Feb 26 09:37:17 volumio ntpd[686]: Soliciting pool server 95.111.202.5 Feb 26 09:37:17 volumio ntpd[686]: Soliciting pool server 193.182.111.12 Feb 26 09:37:18 volumio ntpd[686]: Soliciting pool server 113.207.49.85 Feb 26 09:37:18 volumio ntpd[686]: Soliciting pool server 193.182.111.143 Feb 26 09:37:18 volumio volumio[704]: info: VolumeController::SetAlsaVolume76 Feb 26 09:37:18 volumio ntpd[686]: Soliciting pool server 185.209.85.222 Feb 26 09:37:18 volumio ntpd[686]: Soliciting pool server 193.182.111.14 Feb 26 09:37:18 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:37:18 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:37:18 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:37:18 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:37:19 volumio ntpd[686]: Soliciting pool server 202.112.31.197 Feb 26 09:37:19 volumio ntpd[686]: Soliciting pool server 202.118.1.81 Feb 26 09:37:19 volumio ntpd[686]: Soliciting pool server 162.159.200.1 Feb 26 09:37:19 volumio ntpd[686]: Soliciting pool server 139.199.214.202 Feb 26 09:37:20 volumio volumio[704]: info: VolumeController::SetAlsaVolume52 Feb 26 09:37:20 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 09:37:20 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:37:20 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:37:20 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 09:37:20 volumio ntpd[686]: Soliciting pool server 84.16.67.12 Feb 26 09:37:20 volumio ntpd[686]: Soliciting pool server 2001:1600:4:1::123 Feb 26 09:37:20 volumio ntpd[686]: Soliciting pool server 193.182.111.141 Feb 26 09:37:20 volumio ntpd[686]: Soliciting pool server 78.46.102.180 Feb 26 09:37:22 volumio volumio[704]: info: CoreCommandRouter::volumioPlay Feb 26 09:37:22 volumio volumio[704]: info: CoreStateMachine::play index undefined Feb 26 09:37:22 volumio volumio[704]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 09:37:22 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:37:22 volumio volumio[704]: info: CoreStateMachine::startPlaybackTimer Feb 26 09:37:22 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:37:22 volumio volumio[704]: info: ControllerMpd::resume Feb 26 09:37:22 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand play Feb 26 09:37:22 volumio volumio[704]: info: sendMpdCommand play took 5 milliseconds Feb 26 09:37:24 volumio volumio[704]: info: CoreCommandRouter::volumioPlay Feb 26 09:37:24 volumio volumio[704]: info: CoreStateMachine::play index undefined Feb 26 09:37:24 volumio volumio[704]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 09:37:24 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:37:24 volumio volumio[704]: info: CoreStateMachine::startPlaybackTimer Feb 26 09:37:24 volumio volumio[704]: info: CorePlayQueue::getTrack 0 Feb 26 09:37:24 volumio volumio[704]: info: ControllerMpd::resume Feb 26 09:37:24 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand play Feb 26 09:37:24 volumio volumio[704]: info: sendMpdCommand play took 3 milliseconds Feb 26 01:39:03 volumio volumio[704]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::volumioGetState Feb 26 01:39:03 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:03 volumio volumio[704]: info: Restoring Previous Volume level: 53 false true Feb 26 01:39:03 volumio volumio[704]: info: VolumeController::SetAlsaVolume100 Feb 26 01:39:03 volumio volumio[704]: info: Enable softmixer device for audio device number 5 Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::volumioStop Feb 26 01:39:03 volumio volumio[704]: info: CoreStateMachine::stop Feb 26 01:39:03 volumio volumio[704]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 01:39:03 volumio volumio[704]: info: CoreStateMachine::stPlaybackTimer Feb 26 01:39:03 volumio volumio[704]: info: CoreStateMachine::updateTrackBlock Feb 26 01:39:03 volumio volumio[704]: info: CorePlayQueue::getTrackBlock Feb 26 01:39:03 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:03 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:03 volumio volumio[704]: info: CoreStateMachine::serviceStop Feb 26 01:39:03 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::serviceStop Feb 26 01:39:03 volumio volumio[704]: info: ControllerMpd::stop Feb 26 01:39:03 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 01:39:03 volumio volumio[704]: info: Enable softmixer device for audio device undefined Feb 26 01:39:03 volumio volumio[704]: info: Output device has changed, restarting MPD Feb 26 01:39:03 volumio volumio[704]: info: Output device has changed, restarting Shairport Sync Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:03 volumio sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 01:39:03 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:03 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:03 volumio sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 01:39:03 volumio sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:03 volumio systemd[1]: Stopping Music Player Daemon... Feb 26 01:39:03 volumio volumio[704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 01:39:03 volumio volumio[704]: info: Volume configurations have been set Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 26 01:39:03 volumio volumio[704]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 ,1 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 26 01:39:03 volumio volumio[704]: info: Disabling external Volume Control Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 01:39:03 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:03 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:03 volumio volumio[704]: info: MPD Permissions set Feb 26 01:39:03 volumio volumio[704]: info: Software Volume ALSA configuration written Feb 26 01:39:03 volumio volumio[704]: info: Preparing to generate the ALSA configuration file Feb 26 01:39:03 volumio volumio[704]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 26 01:39:03 volumio volumio[704]: info: Reading ALSA contributions from plugins. Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:03 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 01:39:04 volumio systemd[1]: mpd.service: Succeeded. Feb 26 01:39:04 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:04 volumio systemd[1]: Starting Music Player Daemon... Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 26 01:39:04 volumio volumio[704]: error: MPD error: Error: read ECONNRESET Feb 26 01:39:04 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:04 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:04 volumio volumio[704]: info: Starting Shairport Sync Feb 26 01:39:04 volumio sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 01:39:04 volumio volumio[704]: info: Asound.conf file written Feb 26 01:39:04 volumio sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 26 01:39:04 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 26 01:39:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 26 01:39:04 volumio sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 26 01:39:04 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:04 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 26 01:39:04 volumio sudo[1821]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:04 volumio volumio[704]: No state is present for card x20 Feb 26 01:39:04 volumio volumio[704]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Feb 26 01:39:04 volumio volumio[704]: Hardware is initialized using a generic method Feb 26 01:39:04 volumio volumio[704]: No state is present for card x20 Feb 26 01:39:04 volumio volumio[704]: info: Output device has changed, restarting MPD Feb 26 01:39:04 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 01:39:04 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:04 volumio volumio[704]: info: Output device has changed, restarting Shairport Sync Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:04 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:04 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:04 volumio sudo[1834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 01:39:04 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 26 01:39:04 volumio systemd[1]: mpd.service: Succeeded. Feb 26 01:39:04 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 01:39:04 volumio volumio[704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 01:39:04 volumio systemd[1]: Starting Music Player Daemon... Feb 26 01:39:05 volumio mpd[1840]: Feb 26 01:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 01:39:05 volumio systemd[1]: Started Music Player Daemon. Feb 26 01:39:05 volumio sudo[1834]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:05 volumio sudo[1793]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:06 volumio volumio[704]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 26 01:39:06 volumio volumio[704]: No state is present for card x20 Feb 26 01:39:06 volumio volumio[704]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Feb 26 01:39:06 volumio volumio[704]: Hardware is initialized using a generic method Feb 26 01:39:06 volumio volumio[704]: No state is present for card x20 Feb 26 01:39:06 volumio volumio[704]: info: Output device has changed, restarting MPD Feb 26 01:39:06 volumio volumio[704]: info: Output device has changed, restarting Shairport Sync Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:06 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:06 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:06 volumio sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 01:39:06 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:06 volumio volumio[704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 01:39:06 volumio systemd[1]: Stopping Music Player Daemon... Feb 26 01:39:06 volumio systemd[1]: mpd.service: Succeeded. Feb 26 01:39:06 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 26 01:39:06 volumio volumio[704]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 26 01:39:06 volumio systemd[1]: Starting Music Player Daemon... Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 26 01:39:06 volumio volumio[704]: info: Disabling external Volume Control Feb 26 01:39:06 volumio volumio[704]: info: MPD Permissions set Feb 26 01:39:06 volumio volumio[704]: info: MPD Permissions set Feb 26 01:39:06 volumio volumio[704]: info: Shairport-Sync Started Feb 26 01:39:06 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:06 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 01:39:06 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:06 volumio volumio[704]: info: Starting Shairport Sync Feb 26 01:39:06 volumio volumio[704]: info: Starting Shairport Sync Feb 26 01:39:06 volumio sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 01:39:06 volumio sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 26 01:39:06 volumio sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 01:39:06 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 26 01:39:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 26 01:39:06 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 26 01:39:06 volumio sudo[1879]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:06 volumio volumio[704]: info: Shairport-Sync Started Feb 26 01:39:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 26 01:39:06 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 26 01:39:06 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 26 01:39:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 26 01:39:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 26 01:39:06 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:06 volumio volumio[704]: info: Shairport-Sync Started Feb 26 01:39:07 volumio volumio[704]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Feb 26 01:39:07 volumio sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 01:39:07 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:07 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:07 volumio volumio[704]: info: MPD Permissions set Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:07 volumio sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 01:39:07 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 01:39:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 26 01:39:07 volumio systemd[1]: mpd.service: Succeeded. Feb 26 01:39:07 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 01:39:07 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:07 volumio systemd[1]: Starting Music Player Daemon... Feb 26 01:39:07 volumio volumio[704]: info: VolumeController::SetAlsaVolume53 Feb 26 01:39:08 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:08 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:08 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:08 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:08 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 26 01:39:08 volumio sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 01:39:08 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 26 01:39:08 volumio systemd[1]: mpd.service: Succeeded. Feb 26 01:39:08 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 01:39:08 volumio systemd[1]: Starting Music Player Daemon... Feb 26 01:39:09 volumio mpd[1905]: Feb 26 01:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 01:39:09 volumio systemd[1]: Started Music Player Daemon. Feb 26 01:39:09 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:09 volumio volumio[704]: error: MPD error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:09 volumio volumio[704]: error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio volumio[704]: error: MPD error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio volumio[704]: error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:09 volumio volumio[704]: error: updateQueue error: null Feb 26 01:39:09 volumio volumio[704]: error: MPD error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio volumio[704]: error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio volumio[704]: error: MPD error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio volumio[704]: error: The expression evaluated to a falsy value: Feb 26 01:39:09 volumio volumio[704]: assert.ok(self.idling) Feb 26 01:39:09 volumio volumio[704]: error: updateQueue error: null Feb 26 01:39:22 volumio volumio[704]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Feb 26 01:39:22 volumio sudo[1954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 01:39:22 volumio sudo[1954]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio sudo[1954]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio sudo[1956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 01:39:22 volumio sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 01:39:22 volumio systemd[1]: Stopping Music Player Daemon... Feb 26 01:39:22 volumio systemd[1]: mpd.service: Succeeded. Feb 26 01:39:22 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 01:39:22 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:22 volumio volumio[704]: info: MPD Permissions set Feb 26 01:39:23 volumio systemd[1]: Starting Music Player Daemon... Feb 26 01:39:24 volumio mpd[1965]: Feb 26 01:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 01:39:24 volumio systemd[1]: Started Music Player Daemon. Feb 26 01:39:24 volumio sudo[1956]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:24 volumio volumio[704]: error: updateQueue error: null Feb 26 01:39:25 volumio volumio[704]: info: CoreCommandRouter::volumioPlay Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::play index undefined Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 01:39:25 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::startPlaybackTimer Feb 26 01:39:25 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Music/周杰伦-兰亭序(网友自制).mp3 Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand stop took 4 milliseconds Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 01:39:25 volumio volumio[704]: info: Feb 26 01:39:25 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 01:39:25 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand clear took 5 milliseconds Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Music/周杰伦-兰亭序(网友自制).mp3" Feb 26 01:39:25 volumio volumio[704]: error: updateQueue error: null Feb 26 01:39:25 volumio volumio[704]: info: Feb 26 01:39:25 volumio volumio[704]: ---------------------------- MPD announces system playlist update Feb 26 01:39:25 volumio volumio[704]: info: Ignoring MPD Status Update Feb 26 01:39:25 volumio volumio[704]: info: ------------------------------ 10ms Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand add "NAS/Music/Music/周杰伦-兰亭序(网友自制).mp3" took 7 milliseconds Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand play Feb 26 01:39:25 volumio volumio[704]: info: ------------------------------ 7ms Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand play took 8 milliseconds Feb 26 01:39:25 volumio volumio[704]: info: Feb 26 01:39:25 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 01:39:25 volumio volumio[704]: info: ControllerMpd::getState Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 01:39:25 volumio volumio[704]: info: Feb 26 01:39:25 volumio volumio[704]: ---------------------------- MPD announces state update: player Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand status took 155 milliseconds Feb 26 01:39:25 volumio volumio[704]: info: ControllerMpd::getState Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand status Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand status took 4 milliseconds Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::parseState Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 01:39:25 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 01:39:25 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 01:39:25 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:25 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"兰亭序(网友自制)","artist":"周杰伦","album":null,"uri":"NAS/Music/Music/周杰伦-兰亭序(网友自制).mp3","trackType":"mp3"} Feb 26 01:39:25 volumio volumio[704]: verbose: CURRENT POSITION 2 Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus stop Feb 26 01:39:25 volumio volumio[704]: info: ------------------------------ 168ms Feb 26 01:39:25 volumio volumio[704]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 01:39:25 volumio volumio[704]: verbose: ControllerMpd::parseTrackInfo Feb 26 01:39:25 volumio volumio[704]: info: ControllerMpd::pushState Feb 26 01:39:25 volumio volumio[704]: info: CoreCommandRouter::servicePushState Feb 26 01:39:25 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:25 volumio volumio[704]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"兰亭序(网友自制)","artist":"周杰伦","album":null,"uri":"NAS/Music/Music/周杰伦-兰亭序(网友自制).mp3","trackType":"mp3"} Feb 26 01:39:25 volumio volumio[704]: verbose: CURRENT POSITION 2 Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::syncState stateService play Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::syncState currentStatus play Feb 26 01:39:25 volumio volumio[704]: info: Received an update from plugin. extracting info from payload Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:25 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:25 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:25 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:25 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:25 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:25 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:25 volumio volumio[704]: info: ------------------------------ 63ms Feb 26 01:39:29 volumio volumio[704]: info: VolumeController::SetAlsaVolume78 Feb 26 01:39:29 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:29 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:29 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:29 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:31 volumio volumio[704]: info: VolumeController::SetAlsaVolume92 Feb 26 01:39:31 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:31 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:31 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:31 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:32 volumio volumio[704]: info: VolumeController::SetAlsaVolume100 Feb 26 01:39:32 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:32 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:32 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:32 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:33 volumio volumio[704]: info: VolumeController::SetAlsaVolume89 Feb 26 01:39:33 volumio volumio[704]: info: CoreStateMachine::pushState Feb 26 01:39:33 volumio volumio[704]: info: CorePlayQueue::getTrack 2 Feb 26 01:39:33 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 01:39:33 volumio volumio[704]: info: CoreCommandRouter::volumioPushState Feb 26 01:39:50 volumio volumio[704]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Feb 26 01:39:50 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Feb 26 01:39:50 volumio sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 01:39:50 volumio sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:50 volumio sudo[1976]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:50 volumio volumio[704]: info: MPD Permissions set Feb 26 01:39:50 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 01:39:50 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:50 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 01:39:50 volumio systemd[1]: Stopping Music Player Daemon... Feb 26 01:39:50 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 01:39:50 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 01:39:50 volumio volumio[704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 01:39:51 volumio systemd[1]: mpd.service: Succeeded. Feb 26 01:39:51 volumio systemd[1]: Stopped Music Player Daemon. Feb 26 01:39:51 volumio systemd[1]: Starting Music Player Daemon... Feb 26 01:39:52 volumio mpd[1987]: Feb 26 01:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 01:39:52 volumio systemd[1]: Started Music Player Daemon. Feb 26 01:39:52 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Feb 26 01:39:52 volumio volumio[704]: error: updateQueue error: null Feb 26 01:40:23 volumio volumio[704]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 01:40:23 volumio volumio[704]: Error: connect ETIMEDOUT 208.43.237.140:80 Feb 26 01:40:23 volumio volumio[704]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 26 01:40:23 volumio volumio[704]: errno: -110, Feb 26 01:40:23 volumio volumio[704]: code: 'ETIMEDOUT', Feb 26 01:40:23 volumio volumio[704]: syscall: 'connect', Feb 26 01:40:23 volumio volumio[704]: address: '208.43.237.140', Feb 26 01:40:23 volumio volumio[704]: port: 80 Feb 26 01:40:23 volumio volumio[704]: } Feb 26 01:40:23 volumio volumio[704]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 01:40:23 volumio sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-26 01:39 Feb 26 01:40:23 volumio sudo[2043]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"