Dec 21 08:00:27 volumio-4.0 kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Dec 21 08:00:27 volumio-4.0 kernel: Linux version 6.12.47-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1904 SMP PREEMPT Mon Sep 15 13:10:52 BST 2025 Dec 21 08:00:27 volumio-4.0 kernel: KASLR enabled Dec 21 08:00:27 volumio-4.0 kernel: random: crng init done Dec 21 08:00:27 volumio-4.0 kernel: Machine model: Raspberry Pi Compute Module 5 Lite Rev 1.0 Dec 21 08:00:27 volumio-4.0 kernel: efi: UEFI not found. Dec 21 08:00:27 volumio-4.0 kernel: Reserved memory: created CMA memory pool at 0x000000003b800000, size 64 MiB Dec 21 08:00:27 volumio-4.0 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Dec 21 08:00:27 volumio-4.0 kernel: OF: reserved mem: 0x000000003b800000..0x000000003f7fffff (65536 KiB) map reusable linux,cma Dec 21 08:00:27 volumio-4.0 kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Dec 21 08:00:27 volumio-4.0 kernel: OF: reserved mem: 0x000000003fd23200..0x000000003fd23253 (0 KiB) nomap non-reusable nvram@0 Dec 21 08:00:27 volumio-4.0 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000000ffffffff] Dec 21 08:00:27 volumio-4.0 kernel: Faking node 0 at [mem 0x0000000000000000-0x000000001fffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: Faking node 1 at [mem 0x0000000020000000-0x000000003fffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: Faking node 2 at [mem 0x0000000040000000-0x000000005fffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: Faking node 3 at [mem 0x0000000060000000-0x000000007fffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: Faking node 4 at [mem 0x0000000080000000-0x000000009fffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: Faking node 5 at [mem 0x00000000a0000000-0x00000000bfffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: Faking node 6 at [mem 0x00000000c0000000-0x00000000dfffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: Faking node 7 at [mem 0x00000000e0000000-0x00000000ffffffff] (512MB) Dec 21 08:00:27 volumio-4.0 kernel: NUMA: Initialized distance table, cnt=8 Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(0) allocated [mem 0x1fffd300-0x1fffffff] Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(1) allocated [mem 0x3b7fd300-0x3b7fffff] Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(2) allocated [mem 0x5fffd300-0x5fffffff] Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(3) allocated [mem 0x7fffd300-0x7fffffff] Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(4) allocated [mem 0x9fffd300-0x9fffffff] Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(5) allocated [mem 0xbfffd300-0xbfffffff] Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(6) allocated [mem 0xdfffd300-0xdfffffff] Dec 21 08:00:27 volumio-4.0 kernel: NODE_DATA(7) allocated [mem 0xff7a6300-0xff7a8fff] Dec 21 08:00:27 volumio-4.0 kernel: Zone ranges: Dec 21 08:00:27 volumio-4.0 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Dec 21 08:00:27 volumio-4.0 kernel: DMA32 empty Dec 21 08:00:27 volumio-4.0 kernel: Normal empty Dec 21 08:00:27 volumio-4.0 kernel: Movable zone start for each node Dec 21 08:00:27 volumio-4.0 kernel: Early memory node ranges Dec 21 08:00:27 volumio-4.0 kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Dec 21 08:00:27 volumio-4.0 kernel: node 0: [mem 0x0000000000080000-0x000000001fffffff] Dec 21 08:00:27 volumio-4.0 kernel: node 1: [mem 0x0000000020000000-0x000000003f7fffff] Dec 21 08:00:27 volumio-4.0 kernel: node 2: [mem 0x0000000040000000-0x000000005fffffff] Dec 21 08:00:27 volumio-4.0 kernel: node 3: [mem 0x0000000060000000-0x000000007fffffff] Dec 21 08:00:27 volumio-4.0 kernel: node 4: [mem 0x0000000080000000-0x000000009fffffff] Dec 21 08:00:27 volumio-4.0 kernel: node 5: [mem 0x00000000a0000000-0x00000000bfffffff] Dec 21 08:00:27 volumio-4.0 kernel: node 6: [mem 0x00000000c0000000-0x00000000dfffffff] Dec 21 08:00:27 volumio-4.0 kernel: node 7: [mem 0x00000000e0000000-0x00000000ffffffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000001fffffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 1 [mem 0x0000000020000000-0x000000003f7fffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 2 [mem 0x0000000040000000-0x000000005fffffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 3 [mem 0x0000000060000000-0x000000007fffffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 4 [mem 0x0000000080000000-0x000000009fffffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 5 [mem 0x00000000a0000000-0x00000000bfffffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 6 [mem 0x00000000c0000000-0x00000000dfffffff] Dec 21 08:00:27 volumio-4.0 kernel: Initmem setup node 7 [mem 0x00000000e0000000-0x00000000ffffffff] Dec 21 08:00:27 volumio-4.0 kernel: On node 2, zone DMA: 2048 pages in unavailable ranges Dec 21 08:00:27 volumio-4.0 kernel: psci: probing for conduit method from DT. Dec 21 08:00:27 volumio-4.0 kernel: psci: PSCIv1.1 detected in firmware. Dec 21 08:00:27 volumio-4.0 kernel: psci: Using standard PSCI v0.2 function IDs Dec 21 08:00:27 volumio-4.0 kernel: psci: MIGRATE_INFO_TYPE not supported. Dec 21 08:00:27 volumio-4.0 kernel: psci: SMC Calling Convention v1.2 Dec 21 08:00:27 volumio-4.0 kernel: percpu: Embedded 33 pages/cpu s95192 r8192 d31784 u135168 Dec 21 08:00:27 volumio-4.0 kernel: pcpu-alloc: s95192 r8192 d31784 u135168 alloc=33*4096 Dec 21 08:00:27 volumio-4.0 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 21 08:00:27 volumio-4.0 kernel: Detected PIPT I-cache on CPU0 Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Virtualization Host Extensions Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Spectre-v4 Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Spectre-BHB Dec 21 08:00:27 volumio-4.0 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: SSBS not fully self-synchronizing Dec 21 08:00:27 volumio-4.0 kernel: alternatives: applying boot alternatives Dec 21 08:00:27 volumio-4.0 kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=2C:CF:67:C2:73:FB vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc imgfile=/volumio_current.sqsh bootpart=UUID=F765-E57D datapart=UUID=c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 21 08:00:27 volumio-4.0 kernel: cgroup: Disabling memory control group subsystem Dec 21 08:00:27 volumio-4.0 kernel: mempolicy: NUMA default policy overridden to 'interleave:0-7' Dec 21 08:00:27 volumio-4.0 kernel: DMA IOMMU NUMA default policy overridden to 'interleave:0-7' Dec 21 08:00:27 volumio-4.0 kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc imgfile=/volumio_current.sqsh bootpart=UUID=F765-E57D datapart=UUID=c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 0: 0 1 2 3 4 5 6 7 Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 1: 1 2 3 4 5 6 7 0 Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 2: 2 3 4 5 6 7 0 1 Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 3: 3 4 5 6 7 0 1 2 Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 4: 4 5 6 7 0 1 2 3 Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 5: 5 6 7 0 1 2 3 4 Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 6: 6 7 0 1 2 3 4 5 Dec 21 08:00:27 volumio-4.0 kernel: Fallback order for Node 7: 7 0 1 2 3 4 5 6 Dec 21 08:00:27 volumio-4.0 kernel: Built 8 zonelists, mobility grouping on. Total pages: 1046528 Dec 21 08:00:27 volumio-4.0 kernel: Policy zone: DMA Dec 21 08:00:27 volumio-4.0 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 21 08:00:27 volumio-4.0 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=8 Dec 21 08:00:27 volumio-4.0 kernel: ftrace: allocating 45342 entries in 178 pages Dec 21 08:00:27 volumio-4.0 kernel: ftrace: allocated 178 pages with 4 groups Dec 21 08:00:27 volumio-4.0 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 21 08:00:27 volumio-4.0 kernel: rcu: RCU event tracing is enabled. Dec 21 08:00:27 volumio-4.0 kernel: Trampoline variant of Tasks RCU enabled. Dec 21 08:00:27 volumio-4.0 kernel: Rude variant of Tasks RCU enabled. Dec 21 08:00:27 volumio-4.0 kernel: Tracing variant of Tasks RCU enabled. Dec 21 08:00:27 volumio-4.0 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Dec 21 08:00:27 volumio-4.0 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 21 08:00:27 volumio-4.0 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 21 08:00:27 volumio-4.0 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 21 08:00:27 volumio-4.0 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 21 08:00:27 volumio-4.0 kernel: Root IRQ handler: gic_handle_irq Dec 21 08:00:27 volumio-4.0 kernel: GIC: Using split EOI/Deactivate mode Dec 21 08:00:27 volumio-4.0 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 21 08:00:27 volumio-4.0 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Dec 21 08:00:27 volumio-4.0 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Dec 21 08:00:27 volumio-4.0 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Dec 21 08:00:27 volumio-4.0 kernel: Console: colour dummy device 80x25 Dec 21 08:00:27 volumio-4.0 kernel: printk: legacy console [tty1] enabled Dec 21 08:00:27 volumio-4.0 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Dec 21 08:00:27 volumio-4.0 kernel: pid_max: default: 32768 minimum: 301 Dec 21 08:00:27 volumio-4.0 kernel: LSM: initializing lsm=capability Dec 21 08:00:27 volumio-4.0 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: rcu: Hierarchical SRCU implementation. Dec 21 08:00:27 volumio-4.0 kernel: rcu: Max phase no-delay instances is 1000. Dec 21 08:00:27 volumio-4.0 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 0 crossnode level Dec 21 08:00:27 volumio-4.0 kernel: EFI services will not be available. Dec 21 08:00:27 volumio-4.0 kernel: smp: Bringing up secondary CPUs ... Dec 21 08:00:27 volumio-4.0 kernel: Detected PIPT I-cache on CPU1 Dec 21 08:00:27 volumio-4.0 kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Dec 21 08:00:27 volumio-4.0 kernel: Detected PIPT I-cache on CPU2 Dec 21 08:00:27 volumio-4.0 kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Dec 21 08:00:27 volumio-4.0 kernel: Detected PIPT I-cache on CPU3 Dec 21 08:00:27 volumio-4.0 kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Dec 21 08:00:27 volumio-4.0 kernel: smp: Brought up 8 nodes, 4 CPUs Dec 21 08:00:27 volumio-4.0 kernel: SMP: Total of 4 processors activated. Dec 21 08:00:27 volumio-4.0 kernel: CPU: All CPU(s) started at EL2 Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: 32-bit EL0 Support Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Common not Private translations Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: CRC32 instructions Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: LSE atomic instructions Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Privileged Access Never Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: RAS Extension Support Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Dec 21 08:00:27 volumio-4.0 kernel: alternatives: applying system-wide alternatives Dec 21 08:00:27 volumio-4.0 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Dec 21 08:00:27 volumio-4.0 kernel: Memory: 3967248K/4186112K available (14080K kernel code, 2406K rwdata, 4820K rodata, 5440K init, 577K bss, 138644K reserved, 65536K cma-reserved) Dec 21 08:00:27 volumio-4.0 kernel: devtmpfs: initialized Dec 21 08:00:27 volumio-4.0 kernel: Enabled cp15_barrier support Dec 21 08:00:27 volumio-4.0 kernel: Enabled setend support Dec 21 08:00:27 volumio-4.0 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Dec 21 08:00:27 volumio-4.0 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Dec 21 08:00:27 volumio-4.0 kernel: 0 pages in range for non-PLT usage Dec 21 08:00:27 volumio-4.0 kernel: 517392 pages in range for PLT usage Dec 21 08:00:27 volumio-4.0 kernel: pinctrl core: initialized pinctrl subsystem Dec 21 08:00:27 volumio-4.0 kernel: DMI not present or invalid. Dec 21 08:00:27 volumio-4.0 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 21 08:00:27 volumio-4.0 kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Dec 21 08:00:27 volumio-4.0 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 21 08:00:27 volumio-4.0 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 21 08:00:27 volumio-4.0 kernel: audit: initializing netlink subsys (disabled) Dec 21 08:00:27 volumio-4.0 kernel: audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 Dec 21 08:00:27 volumio-4.0 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 21 08:00:27 volumio-4.0 kernel: cpuidle: using governor menu Dec 21 08:00:27 volumio-4.0 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 21 08:00:27 volumio-4.0 kernel: ASID allocator initialised with 32768 entries Dec 21 08:00:27 volumio-4.0 kernel: Serial: AMBA PL011 UART driver Dec 21 08:00:27 volumio-4.0 kernel: /soc@107c000000/interrupt-controller@7fff9000: Fixed dependency cycle(s) with /soc@107c000000/interrupt-controller@7fff9000 Dec 21 08:00:27 volumio-4.0 kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Dec 21 08:00:27 volumio-4.0 kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 16, base_baud = 0) is a PL011 rev3 Dec 21 08:00:27 volumio-4.0 kernel: printk: legacy console [ttyAMA10] enabled Dec 21 08:00:27 volumio-4.0 kernel: raspberrypi-firmware soc@107c000000:firmware: Attached to firmware from 2025-11-05T17:37:18, variant start_cd Dec 21 08:00:27 volumio-4.0 kernel: raspberrypi-firmware soc@107c000000:firmware: Firmware hash is 57db150d00000000000000000000000000000000 Dec 21 08:00:27 volumio-4.0 kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Dec 21 08:00:27 volumio-4.0 kernel: iommu: Default domain type: Translated Dec 21 08:00:27 volumio-4.0 kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 21 08:00:27 volumio-4.0 kernel: SCSI subsystem initialized Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new interface driver usbfs Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new interface driver hub Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new device driver usb Dec 21 08:00:27 volumio-4.0 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 21 08:00:27 volumio-4.0 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 21 08:00:27 volumio-4.0 kernel: PTP clock support registered Dec 21 08:00:27 volumio-4.0 kernel: vgaarb: loaded Dec 21 08:00:27 volumio-4.0 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 21 08:00:27 volumio-4.0 kernel: VFS: Disk quotas dquot_6.6.0 Dec 21 08:00:27 volumio-4.0 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 21 08:00:27 volumio-4.0 kernel: NET: Registered PF_INET protocol family Dec 21 08:00:27 volumio-4.0 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 21 08:00:27 volumio-4.0 kernel: MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, vmalloc) Dec 21 08:00:27 volumio-4.0 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 21 08:00:27 volumio-4.0 kernel: RPC: Registered named UNIX socket transport module. Dec 21 08:00:27 volumio-4.0 kernel: RPC: Registered udp transport module. Dec 21 08:00:27 volumio-4.0 kernel: RPC: Registered tcp transport module. Dec 21 08:00:27 volumio-4.0 kernel: RPC: Registered tcp-with-tls transport module. Dec 21 08:00:27 volumio-4.0 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 21 08:00:27 volumio-4.0 kernel: PCI: CLS 0 bytes, default 64 Dec 21 08:00:27 volumio-4.0 kernel: Trying to unpack rootfs image as initramfs... Dec 21 08:00:27 volumio-4.0 kernel: kvm [1]: nv: 554 coarse grained trap handlers Dec 21 08:00:27 volumio-4.0 kernel: kvm [1]: IPA Size Limit: 40 bits Dec 21 08:00:27 volumio-4.0 kernel: kvm [1]: vgic interrupt IRQ9 Dec 21 08:00:27 volumio-4.0 kernel: kvm [1]: VHE mode initialized successfully Dec 21 08:00:27 volumio-4.0 kernel: Initialise system trusted keyrings Dec 21 08:00:27 volumio-4.0 kernel: workingset: timestamp_bits=42 max_order=20 bucket_order=0 Dec 21 08:00:27 volumio-4.0 kernel: NFS: Registering the id_resolver key type Dec 21 08:00:27 volumio-4.0 kernel: Key type id_resolver registered Dec 21 08:00:27 volumio-4.0 kernel: Key type id_legacy registered Dec 21 08:00:27 volumio-4.0 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Dec 21 08:00:27 volumio-4.0 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Dec 21 08:00:27 volumio-4.0 kernel: Key type asymmetric registered Dec 21 08:00:27 volumio-4.0 kernel: Asymmetric key parser 'x509' registered Dec 21 08:00:27 volumio-4.0 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Dec 21 08:00:27 volumio-4.0 kernel: io scheduler mq-deadline registered Dec 21 08:00:27 volumio-4.0 kernel: io scheduler kyber registered Dec 21 08:00:27 volumio-4.0 kernel: io scheduler bfq registered Dec 21 08:00:27 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7d510600, parent irq: 27) Dec 21 08:00:27 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7c502000, parent irq: 28) Dec 21 08:00:27 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508380, parent irq: 29) Dec 21 08:00:27 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508400, parent irq: 30) Dec 21 08:00:27 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d503000, parent irq: 31) Dec 21 08:00:27 volumio-4.0 kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 21 08:00:27 volumio-4.0 kernel: Freeing initrd memory: 35748K Dec 21 08:00:27 volumio-4.0 kernel: simple-framebuffer 3f800000.framebuffer: framebuffer at 0x3f800000, 0x3f4800 bytes Dec 21 08:00:27 volumio-4.0 kernel: simple-framebuffer 3f800000.framebuffer: format=r5g6b5, mode=1920x1080x16, linelength=3840 Dec 21 08:00:27 volumio-4.0 kernel: Console: switching to colour frame buffer device 240x67 Dec 21 08:00:27 volumio-4.0 kernel: simple-framebuffer 3f800000.framebuffer: fb0: simplefb registered! Dec 21 08:00:27 volumio-4.0 kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Dec 21 08:00:27 volumio-4.0 kernel: iproc-rng200 107d208000.rng: hwrng registered Dec 21 08:00:27 volumio-4.0 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Dec 21 08:00:27 volumio-4.0 kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Dec 21 08:00:27 volumio-4.0 kernel: brd: module loaded Dec 21 08:00:27 volumio-4.0 kernel: loop: module loaded Dec 21 08:00:27 volumio-4.0 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 21 08:00:27 volumio-4.0 kernel: Loading iSCSI transport class v2.0-870. Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new interface driver lan78xx Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new interface driver smsc95xx Dec 21 08:00:27 volumio-4.0 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 21 08:00:27 volumio-4.0 kernel: dwc_otg: FIQ enabled Dec 21 08:00:27 volumio-4.0 kernel: dwc_otg: NAK holdoff enabled Dec 21 08:00:27 volumio-4.0 kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 21 08:00:27 volumio-4.0 kernel: Module dwc_common_port init Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new interface driver uas Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new interface driver usb-storage Dec 21 08:00:27 volumio-4.0 kernel: mousedev: PS/2 mouse device common for all mice Dec 21 08:00:27 volumio-4.0 kernel: rpi-rtc soc@107c000000:rpi_rtc: registered as rtc0 Dec 21 08:00:27 volumio-4.0 kernel: rpi-rtc soc@107c000000:rpi_rtc: setting system clock to 2025-12-21T07:00:16 UTC (1766300416) Dec 21 08:00:27 volumio-4.0 kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Dec 21 08:00:27 volumio-4.0 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 21 08:00:27 volumio-4.0 kernel: sdhci: Secure Digital Host Controller Interface driver Dec 21 08:00:27 volumio-4.0 kernel: sdhci: Copyright(c) Pierre Ossman Dec 21 08:00:27 volumio-4.0 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 21 08:00:27 volumio-4.0 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Dec 21 08:00:27 volumio-4.0 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 21 08:00:27 volumio-4.0 kernel: usbcore: registered new interface driver usbhid Dec 21 08:00:27 volumio-4.0 kernel: usbhid: USB HID core driver Dec 21 08:00:27 volumio-4.0 kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 (0,8000003f) counters available Dec 21 08:00:27 volumio-4.0 kernel: NET: Registered PF_PACKET protocol family Dec 21 08:00:27 volumio-4.0 kernel: Key type dns_resolver registered Dec 21 08:00:27 volumio-4.0 kernel: registered taskstats version 1 Dec 21 08:00:27 volumio-4.0 kernel: Loading compiled-in X.509 certificates Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 0: null Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 1: null Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 2: null Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 3: null Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 4: null Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 5: null Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 6: null Dec 21 08:00:27 volumio-4.0 kernel: Demotion targets for Node 7: null Dec 21 08:00:27 volumio-4.0 kernel: Key type .fscrypt registered Dec 21 08:00:27 volumio-4.0 kernel: Key type fscrypt-provisioning registered Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@1000110000 ranges: Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@1000110000, using [bus 00-ff] Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00 Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0001:00: root bus resource [bus 00-ff] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: PCI bridge to [bus 00] Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1bbfffffff] Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: PME# supported from D0 D3hot Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: clkreq-mode set to default Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: link up, 5.0 GT/s PCIe x1 (!SSC) Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:01:00.0: [144d:a809] type 00 class 0x010802 PCIe Endpoint Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:01:00.0: BAR 0 [mem 0x00000000-0x00003fff 64bit] Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 5.0 GT/s PCIe x1 link at 0001:00:00.0 (capable of 31.504 Gb/s with 8.0 GT/s PCIe x4 link) Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01 Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff]: assigned Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:01:00.0: BAR 0 [mem 0x1b80000000-0x1b80003fff 64bit]: assigned Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: PCI bridge to [bus 01] Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0001:01: resource 1 [mem 0x1b80000000-0x1b800fffff] Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Dec 21 08:00:27 volumio-4.0 kernel: pci 0001:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Dec 21 08:00:27 volumio-4.0 kernel: pcieport 0001:00:00.0: enabling device (0000 -> 0002) Dec 21 08:00:27 volumio-4.0 kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 37 Dec 21 08:00:27 volumio-4.0 kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 37 Dec 21 08:00:27 volumio-4.0 kernel: nvme nvme0: pci function 0001:01:00.0 Dec 21 08:00:27 volumio-4.0 kernel: nvme 0001:01:00.0: enabling device (0000 -> 0002) Dec 21 08:00:27 volumio-4.0 kernel: nvme nvme0: D3 entry latency set to 8 seconds Dec 21 08:00:27 volumio-4.0 kernel: hwmon hwmon1: temp1_input not attached to any thermal zone Dec 21 08:00:27 volumio-4.0 kernel: hwmon hwmon1: temp2_input not attached to any thermal zone Dec 21 08:00:27 volumio-4.0 kernel: nvme nvme0: min host memory (16 MiB) above limit (0 MiB). Dec 21 08:00:27 volumio-4.0 kernel: nvme nvme0: 4/0/0 default/read/poll queues Dec 21 08:00:27 volumio-4.0 kernel: nvme0n1: p1 p2 p3 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges: Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@1000120000, using [bus 00-ff] Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000 Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00 Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0002:00: root bus resource [bus 00-ff] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: PCI bridge to [bus 00] Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: bridge window [mem 0x1f80000000-0x1fbfffffff] Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: PME# supported from D0 D3hot Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: clkreq-mode set to default Dec 21 08:00:27 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff] Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff] Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff] Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: supports D1 Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01 Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: PCI bridge to [bus 01] Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref] Dec 21 08:00:27 volumio-4.0 kernel: pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff] Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Dec 21 08:00:27 volumio-4.0 kernel: pci 0002:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Dec 21 08:00:27 volumio-4.0 kernel: pcieport 0002:00:00.0: enabling device (0000 -> 0002) Dec 21 08:00:27 volumio-4.0 kernel: pcieport 0002:00:00.0: PME: Signaling with IRQ 43 Dec 21 08:00:27 volumio-4.0 kernel: pcieport 0002:00:00.0: AER: enabled with IRQ 43 Dec 21 08:00:27 volumio-4.0 kernel: rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Dec 21 08:00:27 volumio-4.0 kernel: rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Dec 21 08:00:27 volumio-4.0 kernel: rp1 0002:01:00.0: enabling device (0000 -> 0002) Dec 21 08:00:27 volumio-4.0 kernel: rp1 0002:01:00.0: chip_id 0x20001927 Dec 21 08:00:27 volumio-4.0 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 21 08:00:27 volumio-4.0 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Dec 21 08:00:27 volumio-4.0 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 21 08:00:27 volumio-4.0 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Dec 21 08:00:27 volumio-4.0 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 21 08:00:27 volumio-4.0 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Dec 21 08:00:27 volumio-4.0 kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 105 Dec 21 08:00:27 volumio-4.0 kernel: mmc0: CQHCI version 5.10 Dec 21 08:00:27 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 111 (2c:cf:67:c2:73:fb) Dec 21 08:00:27 volumio-4.0 kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: irq 136, io mem 0x1f00200000 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Dec 21 08:00:27 volumio-4.0 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Dec 21 08:00:27 volumio-4.0 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 21 08:00:27 volumio-4.0 kernel: usb usb1: Product: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: usb usb1: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: usb usb1: SerialNumber: xhci-hcd.0 Dec 21 08:00:27 volumio-4.0 kernel: hub 1-0:1.0: USB hub found Dec 21 08:00:27 volumio-4.0 kernel: hub 1-0:1.0: 2 ports detected Dec 21 08:00:27 volumio-4.0 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Dec 21 08:00:27 volumio-4.0 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 21 08:00:27 volumio-4.0 kernel: usb usb2: Product: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: usb usb2: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: usb usb2: SerialNumber: xhci-hcd.0 Dec 21 08:00:27 volumio-4.0 kernel: hub 2-0:1.0: USB hub found Dec 21 08:00:27 volumio-4.0 kernel: hub 2-0:1.0: 1 port detected Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: irq 141, io mem 0x1f00300000 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Dec 21 08:00:27 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Dec 21 08:00:27 volumio-4.0 kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Dec 21 08:00:27 volumio-4.0 kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 21 08:00:27 volumio-4.0 kernel: usb usb3: Product: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: usb usb3: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: usb usb3: SerialNumber: xhci-hcd.1 Dec 21 08:00:27 volumio-4.0 kernel: hub 3-0:1.0: USB hub found Dec 21 08:00:27 volumio-4.0 kernel: hub 3-0:1.0: 2 ports detected Dec 21 08:00:27 volumio-4.0 kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Dec 21 08:00:27 volumio-4.0 kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 21 08:00:27 volumio-4.0 kernel: usb usb4: Product: xHCI Host Controller Dec 21 08:00:27 volumio-4.0 kernel: usb usb4: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: usb usb4: SerialNumber: xhci-hcd.1 Dec 21 08:00:27 volumio-4.0 kernel: hub 4-0:1.0: USB hub found Dec 21 08:00:27 volumio-4.0 kernel: hub 4-0:1.0: 1 port detected Dec 21 08:00:27 volumio-4.0 kernel: of_cfs_init Dec 21 08:00:27 volumio-4.0 kernel: of_cfs_init: OK Dec 21 08:00:27 volumio-4.0 kernel: clk: Disabling unused clocks Dec 21 08:00:27 volumio-4.0 kernel: PM: genpd: Disabling unused power domains Dec 21 08:00:27 volumio-4.0 kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Dec 21 08:00:27 volumio-4.0 kernel: Freeing unused kernel memory: 5440K Dec 21 08:00:27 volumio-4.0 kernel: Run /init as init process Dec 21 08:00:27 volumio-4.0 kernel: with arguments: Dec 21 08:00:27 volumio-4.0 kernel: /init Dec 21 08:00:27 volumio-4.0 kernel: splash Dec 21 08:00:27 volumio-4.0 kernel: nodebug Dec 21 08:00:27 volumio-4.0 kernel: with environment: Dec 21 08:00:27 volumio-4.0 kernel: HOME=/ Dec 21 08:00:27 volumio-4.0 kernel: TERM=linux Dec 21 08:00:27 volumio-4.0 kernel: imgpart=UUID=1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc Dec 21 08:00:27 volumio-4.0 kernel: imgfile=/volumio_current.sqsh Dec 21 08:00:27 volumio-4.0 kernel: bootpart=UUID=F765-E57D Dec 21 08:00:27 volumio-4.0 kernel: datapart=UUID=c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 Dec 21 08:00:27 volumio-4.0 kernel: uuidconfig=cmdline.txt Dec 21 08:00:27 volumio-4.0 kernel: bootdelay=7 Dec 21 08:00:27 volumio-4.0 kernel: use_kmsg=no Dec 21 08:00:27 volumio-4.0 kernel: rp1-firmware rp1_firmware: RP1 Firmware version 23fca1cd776798b50bd3e640ecb242215778e7f8 Dec 21 08:00:27 volumio-4.0 kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Dec 21 08:00:27 volumio-4.0 kernel: rp1-pio 1f00178000.pio: Created instance as pio0 Dec 21 08:00:27 volumio-4.0 kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Dec 21 08:00:27 volumio-4.0 kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Dec 21 08:00:27 volumio-4.0 kernel: v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform! Dec 21 08:00:27 volumio-4.0 kernel: [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0 Dec 21 08:00:27 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: Adding to iommu group 1 Dec 21 08:00:27 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: Adding to iommu group 1 Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Dec 21 08:00:27 volumio-4.0 kernel: Console: switching to colour dummy device 80x25 Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Dec 21 08:00:27 volumio-4.0 kernel: Registered IR keymap rc-cec Dec 21 08:00:27 volumio-4.0 kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0 Dec 21 08:00:27 volumio-4.0 kernel: input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input1 Dec 21 08:00:27 volumio-4.0 kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card0/input2 Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Dec 21 08:00:27 volumio-4.0 kernel: Registered IR keymap rc-cec Dec 21 08:00:27 volumio-4.0 kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1 Dec 21 08:00:27 volumio-4.0 kernel: input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input3 Dec 21 08:00:27 volumio-4.0 kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card1/input4 Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1: new high-speed USB device number 2 using xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: [drm] Initialized vc4 0.0.0 for axi:gpu on minor 1 Dec 21 08:00:27 volumio-4.0 kernel: Console: switching to colour frame buffer device 160x50 Dec 21 08:00:27 volumio-4.0 kernel: vc4-drm axi:gpu: [drm] fb0: vc4drmfb frame buffer device Dec 21 08:00:27 volumio-4.0 kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=86ef, bcdDevice= 3.12 Dec 21 08:00:27 volumio-4.0 kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 21 08:00:27 volumio-4.0 kernel: usb 1-1: Product: DENAFRIPS USB v3.12 | E15.2.0 Dec 21 08:00:27 volumio-4.0 kernel: usb 1-1: Manufacturer: DENAFRIPS Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1: New USB device found, idVendor=05e3, idProduct=0610, bcdDevice=94.05 Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1: Product: USB2.1 Hub Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1: Manufacturer: GenesysLogic Dec 21 08:00:27 volumio-4.0 kernel: hub 3-1:1.0: USB hub found Dec 21 08:00:27 volumio-4.0 kernel: hub 3-1:1.0: 4 ports detected Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1: new SuperSpeed USB device number 2 using xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1: New USB device found, idVendor=05e3, idProduct=0625, bcdDevice=94.05 Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1: Product: USB3.1 Hub Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1: Manufacturer: GenesysLogic Dec 21 08:00:27 volumio-4.0 kernel: fuse: init (API version 7.41) Dec 21 08:00:27 volumio-4.0 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 21 08:00:27 volumio-4.0 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 21 08:00:27 volumio-4.0 kernel: hub 4-1:1.0: USB hub found Dec 21 08:00:27 volumio-4.0 kernel: hub 4-1:1.0: 4 ports detected Dec 21 08:00:27 volumio-4.0 kernel: fbtft: module is from the staging directory, the quality is unknown, you have been warned. Dec 21 08:00:27 volumio-4.0 kernel: fb_ili9340: module is from the staging directory, the quality is unknown, you have been warned. Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_ili9340 has no spi_device_id for ilitek,ili9340 Dec 21 08:00:27 volumio-4.0 kernel: fb_ili9341: module is from the staging directory, the quality is unknown, you have been warned. Dec 21 08:00:27 volumio-4.0 kernel: fb_st7735r: module is from the staging directory, the quality is unknown, you have been warned. Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_st7735r has no spi_device_id for sitronix,st7735r Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,sainsmart18 Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18 Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18_green Dec 21 08:00:27 volumio-4.0 kernel: fb_st7789v: module is from the staging directory, the quality is unknown, you have been warned. Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_st7789v has no spi_device_id for sitronix,st7789v Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_st7789v has no spi_device_id for fbtft,minipitft13 Dec 21 08:00:27 volumio-4.0 kernel: fb_hx8357d: module is from the staging directory, the quality is unknown, you have been warned. Dec 21 08:00:27 volumio-4.0 kernel: SPI driver fb_hx8357d has no spi_device_id for himax,hx8357d Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1.1: new SuperSpeed USB device number 3 using xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00 Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1.1: Product: USB3.1 Storage Device Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1.1: Manufacturer: Space keys Dec 21 08:00:27 volumio-4.0 kernel: usb 4-1.1: SerialNumber: 50026B72470114C4 Dec 21 08:00:27 volumio-4.0 kernel: scsi host0: uas Dec 21 08:00:27 volumio-4.0 kernel: scsi 0:0:0:0: Direct-Access Samsung SSD 870 EVO 1TB 0 PQ: 0 ANSI: 6 Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1.4: new full-speed USB device number 4 using xhci-hcd Dec 21 08:00:27 volumio-4.0 kernel: sda: sda1 Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1.4: New USB device found, idVendor=0712, idProduct=000a, bcdDevice= 0.00 Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1.4: Product: WaveShare Dec 21 08:00:27 volumio-4.0 kernel: usb 3-1.4: Manufacturer: WaveShare Dec 21 08:00:27 volumio-4.0 kernel: input: WaveShare WaveShare Touchscreen as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4:1.0/0003:0712:000A.0001/input/input5 Dec 21 08:00:27 volumio-4.0 kernel: hid-generic 0003:0712:000A.0001: input,hiddev96,hidraw0: USB HID v1.11 Device [WaveShare WaveShare ] on usb-xhci-hcd.1-1.4/input0 Dec 21 08:00:27 volumio-4.0 kernel: input: WaveShare WaveShare as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.4/3-1.4:1.0/0003:0712:000A.0001/input/input7 Dec 21 08:00:27 volumio-4.0 kernel: hid-multitouch 0003:0712:000A.0001: input,hiddev96,hidraw0: USB HID v1.11 Device [WaveShare WaveShare ] on usb-xhci-hcd.1-1.4/input0 Dec 21 08:00:27 volumio-4.0 kernel: EXT4-fs (nvme0n1p2): recovery complete Dec 21 08:00:27 volumio-4.0 kernel: EXT4-fs (nvme0n1p2): mounted filesystem 1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc r/w with ordered data mode. Quota mode: none. Dec 21 08:00:27 volumio-4.0 kernel: loop0: detected capacity change from 0 to 1423840 Dec 21 08:00:27 volumio-4.0 kernel: EXT4-fs (nvme0n1p3): recovery complete Dec 21 08:00:27 volumio-4.0 kernel: EXT4-fs (nvme0n1p3): mounted filesystem c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 r/w with ordered data mode. Quota mode: none. Dec 21 08:00:27 volumio-4.0 kernel: NET: Registered PF_INET6 protocol family Dec 21 08:00:27 volumio-4.0 kernel: Segment Routing with IPv6 Dec 21 08:00:27 volumio-4.0 kernel: In-situ OAM (IOAM) with IPv6 Dec 21 08:00:27 volumio-4.0 systemd[1]: systemd 252.39-1~deb12u1+rpi1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Dec 21 08:00:27 volumio-4.0 systemd[1]: Detected architecture arm64. Dec 21 08:00:27 volumio-4.0 systemd[1]: Hostname set to . Dec 21 08:00:27 volumio-4.0 systemd[1]: Queued start job for default target graphical.target. Dec 21 08:00:27 volumio-4.0 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 21 08:00:27 volumio-4.0 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 21 08:00:27 volumio-4.0 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 21 08:00:27 volumio-4.0 systemd[1]: Created slice user.slice - User and Session Slice. Dec 21 08:00:27 volumio-4.0 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 21 08:00:27 volumio-4.0 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 21 08:00:27 volumio-4.0 systemd[1]: Expecting device dev-disk-by\x2duuid-F765\x2dE57D.device - /dev/disk/by-uuid/F765-E57D... Dec 21 08:00:27 volumio-4.0 systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10... Dec 21 08:00:27 volumio-4.0 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 21 08:00:27 volumio-4.0 systemd[1]: Reached target slices.target - Slice Units. Dec 21 08:00:27 volumio-4.0 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 21 08:00:27 volumio-4.0 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 21 08:00:27 volumio-4.0 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 21 08:00:27 volumio-4.0 systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Dec 21 08:00:27 volumio-4.0 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 21 08:00:27 volumio-4.0 systemd[1]: Reached target swap.target - Swaps. Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 21 08:00:27 volumio-4.0 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 21 08:00:27 volumio-4.0 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 21 08:00:27 volumio-4.0 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 21 08:00:27 volumio-4.0 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 21 08:00:27 volumio-4.0 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 21 08:00:27 volumio-4.0 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 21 08:00:27 volumio-4.0 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 21 08:00:27 volumio-4.0 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 21 08:00:27 volumio-4.0 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 21 08:00:27 volumio-4.0 kernel: i2c_dev: i2c /dev entries driver Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 21 08:00:27 volumio-4.0 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 21 08:00:27 volumio-4.0 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 21 08:00:27 volumio-4.0 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Dec 21 08:00:27 volumio-4.0 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 21 08:00:27 volumio-4.0 systemd-journald[549]: Journal started Dec 21 08:00:27 volumio-4.0 systemd-journald[549]: Runtime Journal (/run/log/journal/843c13b42e6eacb566c0b060683dcd83) is 3.7M, max 30.0M, 26.2M free. Dec 21 08:00:27 volumio-4.0 systemd-modules-load[551]: Inserted module 'i2c_dev' Dec 21 08:00:27 volumio-4.0 fake-hwclock[539]: Current system time: 2025-12-21 07:00:27 Dec 21 08:00:27 volumio-4.0 fake-hwclock[539]: fake-hwclock saved clock information is in the past: 2025-12-20 16:36:39 Dec 21 08:00:27 volumio-4.0 fake-hwclock[539]: To set system time to this saved clock anyway, use "force" Dec 21 08:00:27 volumio-4.0 systemd[1]: Started systemd-journald.service - Journal Service. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Dec 21 08:00:27 volumio-4.0 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 21 08:00:27 volumio-4.0 systemd-udevd[576]: Using default interface naming scheme 'v252'. Dec 21 08:00:27 volumio-4.0 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 21 08:00:27 volumio-4.0 (udev-worker)[579]: Network interface NamePolicy= disabled on kernel command line. Dec 21 08:00:27 volumio-4.0 systemd[1]: Found device dev-disk-by\x2duuid-F765\x2dE57D.device - SAMSUNG MZ9LQ256HBJD-00BVL boot. Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Dec 21 08:00:27 volumio-4.0 kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Dec 21 08:00:27 volumio-4.0 systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-F765\x2dE57D.service - File System Check on /dev/disk/by-uuid/F765-E57D... Dec 21 08:00:27 volumio-4.0 systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Dec 21 08:00:27 volumio-4.0 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Dec 21 08:00:27 volumio-4.0 kernel: mc: Linux media interface: v0.10 Dec 21 08:00:27 volumio-4.0 systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Dec 21 08:00:27 volumio-4.0 systemd-fsck[654]: fsck.fat 4.2 (2021-01-31) Dec 21 08:00:27 volumio-4.0 systemd-fsck[654]: /dev/nvme0n1p1: 568 files, 23944/93500 clusters Dec 21 08:00:27 volumio-4.0 systemd[1]: Found device dev-ttyAMA10.device - /dev/ttyAMA10. Dec 21 08:00:27 volumio-4.0 systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-F765\x2dE57D.service - File System Check on /dev/disk/by-uuid/F765-E57D. Dec 21 08:00:27 volumio-4.0 systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Dec 21 08:00:27 volumio-4.0 kernel: videodev: Linux video capture interface: v2.00 Dec 21 08:00:27 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Dec 21 08:00:27 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Dec 21 08:00:28 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Dec 21 08:00:28 volumio-4.0 kernel: pispbe 1000880000.pisp_be: Adding to iommu group 0 Dec 21 08:00:28 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Dec 21 08:00:28 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Dec 21 08:00:28 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Dec 21 08:00:28 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Dec 21 08:00:28 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: Adding to iommu group 0 Dec 21 08:00:28 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Dec 21 08:00:28 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19 Dec 21 08:00:28 volumio-4.0 kernel: usb 1-1: Warning! Unlikely big volume range (=512), cval->res is probably wrong. Dec 21 08:00:28 volumio-4.0 kernel: usb 1-1: [10] FU [PCM Playback Volume] ch = 2, val = -16384/0/32 Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 21 08:00:28 volumio-4.0 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 21 08:00:28 volumio-4.0 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 21 08:00:28 volumio-4.0 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 21 08:00:28 volumio-4.0 kernel: usbcore: registered new interface driver snd-usb-audio Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 21 08:00:28 volumio-4.0 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 21 08:00:28 volumio-4.0 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 21 08:00:28 volumio-4.0 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 21 08:00:28 volumio-4.0 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 21 08:00:28 volumio-4.0 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 21 08:00:28 volumio-4.0 (udev-worker)[598]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Dec 21 08:00:28 volumio-4.0 systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounting boot.mount - /boot... Dec 21 08:00:28 volumio-4.0 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 21 08:00:28 volumio-4.0 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounting tmp.mount - /tmp... Dec 21 08:00:28 volumio-4.0 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounting var-log.mount - /var/log... Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounted tmp.mount - /tmp. Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounted var-log.mount - /var/log. Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounted boot.mount - /boot. Dec 21 08:00:28 volumio-4.0 systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Dec 21 08:00:28 volumio-4.0 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 21 08:00:28 volumio-4.0 systemd[1]: Started ifup@eth0.service - ifup for eth0. Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting networking.service - Raise network interfaces... Dec 21 08:00:28 volumio-4.0 systemd-journald[549]: Runtime Journal (/run/log/journal/843c13b42e6eacb566c0b060683dcd83) is 3.7M, max 30.0M, 26.2M free. Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Dec 21 08:00:28 volumio-4.0 systemd-journald[549]: Received client request to flush runtime journal. Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 21 08:00:28 volumio-4.0 systemd[1]: Received SIGRTMIN+20 from PID 404 (plymouthd). Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Dec 21 08:00:28 volumio-4.0 systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 865 (systemd-binfmt) Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 21 08:00:28 volumio-4.0 systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 21 08:00:28 volumio-4.0 systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting rpcbind.service - RPC bind portmap service... Dec 21 08:00:28 volumio-4.0 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 21 08:00:28 volumio-4.0 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 21 08:00:28 volumio-4.0 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 21 08:00:28 volumio-4.0 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 21 08:00:28 volumio-4.0 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 21 08:00:28 volumio-4.0 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 21 08:00:28 volumio-4.0 systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Dec 21 08:00:28 volumio-4.0 systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Dec 21 08:00:28 volumio-4.0 systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Dec 21 08:00:28 volumio-4.0 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 21 08:00:28 volumio-4.0 systemd[1]: Reached target paths.target - Path Units. Dec 21 08:00:28 volumio-4.0 haveged[888]: haveged: command socket is listening at fd 3 Dec 21 08:00:28 volumio-4.0 systemd[1]: Started rpcbind.service - RPC bind portmap service. Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 21 08:00:28 volumio-4.0 systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Dec 21 08:00:28 volumio-4.0 ifup[880]: command failed: No such device (-19) Dec 21 08:00:28 volumio-4.0 sh[879]: command failed: No such device (-19) Dec 21 08:00:28 volumio-4.0 sh[916]: dhcpcd-9.4.1 starting Dec 21 08:00:28 volumio-4.0 dhcpcd[916]: dhcpcd-9.4.1 starting Dec 21 08:00:28 volumio-4.0 sh[916]: DUID 00:01:00:01:2f:d0:8b:e9:e4:5f:01:b4:0d:29 Dec 21 08:00:28 volumio-4.0 sh[916]: dhcp_vendor: No such process Dec 21 08:00:28 volumio-4.0 dhcpcd[924]: DUID 00:01:00:01:2f:d0:8b:e9:e4:5f:01:b4:0d:29 Dec 21 08:00:28 volumio-4.0 dhcpcd[924]: dhcp_vendor: No such process Dec 21 08:00:28 volumio-4.0 ifup[931]: command failed: No such device (-19) Dec 21 08:00:28 volumio-4.0 kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 21 08:00:28 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:00] driver [Broadcom BCM54213PE] (irq=167) Dec 21 08:00:28 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Dec 21 08:00:28 volumio-4.0 dhcpcd[924]: eth0: waiting for carrier Dec 21 08:00:28 volumio-4.0 sh[916]: eth0: waiting for carrier Dec 21 08:00:28 volumio-4.0 kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Dec 21 08:00:28 volumio-4.0 systemd[1]: Finished networking.service - Raise network interfaces. Dec 21 08:00:29 volumio-4.0 haveged[888]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Dec 21 08:00:29 volumio-4.0 haveged[888]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356 Dec 21 08:00:29 volumio-4.0 haveged[888]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99679 Dec 21 08:00:29 volumio-4.0 haveged[888]: haveged: fills: 0, generated: 0 Dec 21 08:00:29 volumio-4.0 systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Dec 21 08:00:29 volumio-4.0 systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Dec 21 08:00:29 volumio-4.0 systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target rpc_pipefs.target. Dec 21 08:00:29 volumio-4.0 systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target nfs-client.target - NFS client services. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 21 08:00:29 volumio-4.0 systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target sysinit.target - System Initialization. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started apt-daily.timer - Daily apt download activities. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started man-db.timer - Daily man-db regeneration. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target timers.target - Timer Units. Dec 21 08:00:29 volumio-4.0 systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Dec 21 08:00:29 volumio-4.0 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target sockets.target - Socket Units. Dec 21 08:00:29 volumio-4.0 systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target basic.target - Basic System. Dec 21 08:00:29 volumio-4.0 systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Dec 21 08:00:29 volumio-4.0 systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting glamor-test.service - Check for glamor... Dec 21 08:00:29 volumio-4.0 systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Dec 21 08:00:29 volumio-4.0 systemd[1]: Started iptables.service - Volumio Iptables Module. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting rp1-test.service - Check for RP1 displays for Xorg... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 21 08:00:29 volumio-4.0 systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Dec 21 08:00:29 volumio-4.0 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Successfully dropped root privileges. Dec 21 08:00:29 volumio-4.0 systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: avahi-daemon 0.8 starting up. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting wireless.service - Wireless Services... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 21 08:00:29 volumio-4.0 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 21 08:00:29 volumio-4.0 thd[1002]: Unable to parse trigger line: Dec 21 08:00:29 volumio-4.0 thd[1002]: Unable to parse trigger line: Dec 21 08:00:29 volumio-4.0 volumio_rfkill_unblock.sh[1008]: Volumio WiFi Soft Blocked check script Dec 21 08:00:29 volumio-4.0 volumio_rfkill_unblock.sh[1008]: Check if rfkill is available Dec 21 08:00:29 volumio-4.0 volumio_rfkill_unblock.sh[1008]: Check if rfkill listed devices are already unblocked Dec 21 08:00:29 volumio-4.0 systemd[1]: e2scrub_reap.service: Deactivated successfully. Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Dec 21 08:00:29 volumio-4.0 volumio_rfkill_unblock.sh[1008]: Wi-Fi is already unblocked. Dec 21 08:00:29 volumio-4.0 alsactl[1031]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 21 08:00:29 volumio-4.0 alsactl[1031]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target sound.target - Sound Card. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Dec 21 08:00:29 volumio-4.0 systemd-logind[996]: New seat seat0. Dec 21 08:00:29 volumio-4.0 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Dec 21 08:00:29 volumio-4.0 systemd-logind[996]: Watching system buttons on /dev/input/event0 (pwr_button) Dec 21 08:00:29 volumio-4.0 systemd-logind[996]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Dec 21 08:00:29 volumio-4.0 systemd-logind[996]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Dec 21 08:00:29 volumio-4.0 th-udev-rebind.sh[1036]: Rebinding /dev/input/event0 to thd using --add... Dec 21 08:00:29 volumio-4.0 dhcpcd[1034]: dhcpcd-9.4.1 starting Dec 21 08:00:29 volumio-4.0 th-udev-rebind.sh[1036]: Rebinding /dev/input/event1 to thd using --add... Dec 21 08:00:29 volumio-4.0 th-udev-rebind.sh[1036]: Rebinding /dev/input/event2 to thd using --add... Dec 21 08:00:29 volumio-4.0 th-udev-rebind.sh[1036]: Rebinding /dev/input/event3 to thd using --add... Dec 21 08:00:29 volumio-4.0 th-udev-rebind.sh[1036]: Rebinding /dev/input/event4 to thd using --add... Dec 21 08:00:29 volumio-4.0 th-udev-rebind.sh[1036]: Rebinding /dev/input/event5 to thd using --add... Dec 21 08:00:29 volumio-4.0 dhcpcd[1045]: dev: loaded udev Dec 21 08:00:29 volumio-4.0 dhcpcd[1045]: DUID 00:01:00:01:2f:d0:8b:e9:e4:5f:01:b4:0d:29 Dec 21 08:00:29 volumio-4.0 dhcpcd[1034]: dhcp_vendor: No such process Dec 21 08:00:29 volumio-4.0 dhcpcd[1045]: dhcp_vendor: No such process Dec 21 08:00:29 volumio-4.0 systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Dec 21 08:00:29 volumio-4.0 volumio-remote-updater[1005]: Volumio OTA Client: 1.7.0 Dec 21 08:00:29 volumio-4.0 volumio-remote-updater[1005]: Error: No active session Dec 21 08:00:29 volumio-4.0 volumio-remote-updater[1005]: [2025-12-21 08:00:29] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 21 08:00:29 volumio-4.0 volumio-remote-updater[1005]: [2025-12-21 08:00:29] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 21 08:00:29 volumio-4.0 volumio-remote-updater[1005]: [2025-12-21 08:00:29] [error] handle_connect error: Connection refused Dec 21 08:00:29 volumio-4.0 systemd[1]: th-udev-rebind.service: Deactivated successfully. Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Dec 21 08:00:29 volumio-4.0 dhcpcd[1045]: eth0: waiting for carrier Dec 21 08:00:29 volumio-4.0 loadcpufreq[990]: Loading cpufreq kernel modules...done (none). Dec 21 08:00:29 volumio-4.0 systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished glamor-test.service - Check for glamor. Dec 21 08:00:29 volumio-4.0 upmpdcli[1003]: Could not open config: /tmp/upmpdcli.conf Dec 21 08:00:29 volumio-4.0 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 21 08:00:29 volumio-4.0 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Successfully called chroot(). Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Successfully dropped remaining capabilities. Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Loading service file /services/volumio.service. Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: New relevant interface lo.IPv4 for mDNS. Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Network interface enumeration completed. Dec 21 08:00:29 volumio-4.0 avahi-daemon[985]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 21 08:00:29 volumio-4.0 dbus-daemon[986]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished rp1-test.service - Check for RP1 displays for Xorg. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started systemd-logind.service - User Login Management. Dec 21 08:00:29 volumio-4.0 cpufrequtils[1100]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Dec 21 08:00:29 volumio-4.0 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 21 08:00:29 volumio-4.0 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Dec 21 08:00:29 volumio-4.0 systemd[1]: iptables.service: Deactivated successfully. Dec 21 08:00:29 volumio-4.0 wpa_supplicant[1125]: Successfully initialized wpa_supplicant Dec 21 08:00:29 volumio-4.0 systemd[1]: Started wpa_supplicant.service - WPA supplicant. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target network.target - Network. Dec 21 08:00:29 volumio-4.0 systemd[1]: Reached target network-online.target - Network is Online. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting nmbd.service - Samba NMB Daemon... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting ntpsec.service - Network Time Service... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 21 08:00:29 volumio-4.0 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 08:00:29 volumio-4.0 sm-notify[1146]: Version 2.6.2 starting Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 21 08:00:29 volumio-4.0 systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Dec 21 08:00:29 volumio-4.0 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 21 08:00:29 volumio-4.0 systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Dec 21 08:00:29 volumio-4.0 systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Dec 21 08:00:30 volumio-4.0 welcome[1160]: Resolved ip:[0] Dec 21 08:00:30 volumio-4.0 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 21 08:00:30 volumio-4.0 systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Dec 21 08:00:30 volumio-4.0 ntpd[1158]: INIT: ntpd ntpsec-1.2.2: Starting Dec 21 08:00:30 volumio-4.0 ntp-systemd-wrapper[1158]: 2025-12-21T08:00:30 ntpd[1158]: INIT: ntpd ntpsec-1.2.2: Starting Dec 21 08:00:30 volumio-4.0 ntp-systemd-wrapper[1158]: 2025-12-21T08:00:30 ntpd[1158]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 21 08:00:30 volumio-4.0 ntpd[1158]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 21 08:00:30 volumio-4.0 systemd[1]: Started ntpsec.service - Network Time Service. Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: precision = 0.370 usec (-21) Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: successfully locked into RAM Dec 21 08:00:30 volumio-4.0 ntpd[1176]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Dec 21 08:00:30 volumio-4.0 ntpd[1176]: CONFIG: restrict nopeer ignored Dec 21 08:00:30 volumio-4.0 ifplugd(eth0)[1187]: ifplugd 0.28 initializing. Dec 21 08:00:30 volumio-4.0 ifplugd(eth0)[1187]: Using interface eth0/2C:CF:67:C2:73:FB with driver (version: 6.12.47-v8+) Dec 21 08:00:30 volumio-4.0 ifplugd(eth0)[1187]: Using detection mode: SIOCETHTOOL Dec 21 08:00:30 volumio-4.0 ifplugd(eth0)[1187]: Initialization complete, link beat not detected. Dec 21 08:00:30 volumio-4.0 ntpd[1176]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 21 08:00:30 volumio-4.0 ntpd[1176]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: Using SO_TIMESTAMPNS(ns) Dec 21 08:00:30 volumio-4.0 ntpd[1176]: IO: Listen and drop on 0 v6wildcard [::]:123 Dec 21 08:00:30 volumio-4.0 ntpd[1176]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 21 08:00:30 volumio-4.0 ntpd[1176]: IO: Listen normally on 2 lo 127.0.0.1:123 Dec 21 08:00:30 volumio-4.0 ntpd[1176]: IO: Listening on routing socket on fd #19 for interface updates Dec 21 08:00:30 volumio-4.0 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: This system has a 32-bit time_t. Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Dec 21 08:00:30 volumio-4.0 ntpd[1176]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120 Dec 21 08:00:30 volumio-4.0 ntpd[1176]: NTSc: Using system default root certificates. Dec 21 08:00:30 volumio-4.0 sshd[1203]: Server listening on 0.0.0.0 port 22. Dec 21 08:00:30 volumio-4.0 sshd[1203]: Server listening on :: port 22. Dec 21 08:00:30 volumio-4.0 systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Dec 21 08:00:30 volumio-4.0 ntpd[1176]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Dec 21 08:00:30 volumio-4.0 ifplugd[1141]: Network Interface Plugging Daemon...start eth0...done. Dec 21 08:00:30 volumio-4.0 systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Dec 21 08:00:30 volumio-4.0 systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Dec 21 08:00:30 volumio-4.0 systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Dec 21 08:00:30 volumio-4.0 nmbd[1222]: [2025/12/21 08:00:30.498810, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 21 08:00:30 volumio-4.0 nmbd[1222]: nmbd version 4.17.8-Raspbian started. Dec 21 08:00:30 volumio-4.0 nmbd[1222]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 21 08:00:30 volumio-4.0 nmbd[1222]: [2025/12/21 08:00:30.504562, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 21 08:00:30 volumio-4.0 nmbd[1222]: started asyncdns process 1225 Dec 21 08:00:30 volumio-4.0 nmbd[1222]: [2025/12/21 08:00:30.505449, 0] ../../lib/util/become_daemon.c:150(daemon_status) Dec 21 08:00:30 volumio-4.0 nmbd[1222]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 21 08:00:30 volumio-4.0 nmbd[1222]: [2025/12/21 08:00:30.505655, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Dec 21 08:00:30 volumio-4.0 nmbd[1222]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 21 08:00:30 volumio-4.0 wireless.js[1010]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Dec 21 08:00:30 volumio-4.0 wireless.js[1010]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Dec 21 08:00:30 volumio-4.0 wireless.js[1010]: WIRELESS.JS - INFO: ERROR: wlan0 does not exist, exiting... Dec 21 08:00:30 volumio-4.0 systemd[1]: wireless.service: Main process exited, code=exited, status=1/FAILURE Dec 21 08:00:30 volumio-4.0 systemd[1]: wireless.service: Killing process 1232 (sh) with signal SIGKILL. Dec 21 08:00:30 volumio-4.0 systemd[1]: wireless.service: Killing process 1233 (pkill) with signal SIGKILL. Dec 21 08:00:30 volumio-4.0 systemd[1]: wireless.service: Failed with result 'exit-code'. Dec 21 08:00:30 volumio-4.0 systemd[1]: Failed to start wireless.service - Wireless Services. Dec 21 08:00:30 volumio-4.0 systemd[1]: Started volumio.service - Volumio Backend Module. Dec 21 08:00:30 volumio-4.0 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 21 08:00:30 volumio-4.0 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 21 08:00:30 volumio-4.0 systemd[1]: Received SIGRTMIN+21 from PID 404 (plymouthd). Dec 21 08:00:30 volumio-4.0 volumio-cpu-tweak[1241]: Setting RT Priority for mpd Dec 21 08:00:30 volumio-4.0 volumio-cpu-tweak[1255]: pid 35's current scheduling policy: SCHED_OTHER Dec 21 08:00:30 volumio-4.0 volumio-cpu-tweak[1255]: pid 35's current scheduling priority: 0 Dec 21 08:00:30 volumio-4.0 volumio-cpu-tweak[1241]: Setting MPD Affinity Dec 21 08:00:30 volumio-4.0 volumio-cpu-tweak[1256]: pid 3's current affinity mask: f Dec 21 08:00:30 volumio-4.0 avahi-daemon[985]: Server startup complete. Host name is volumio-4.local. Local service cookie is 2504071231. Dec 21 08:00:30 volumio-4.0 volumio-cpu-tweak[1241]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 21 08:00:30 volumio-4.0 volumio-cpu-tweak[1241]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Dec 21 08:00:30 volumio-4.0 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 21 08:00:30 volumio-4.0 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 21 08:00:30 volumio-4.0 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 21 08:00:30 volumio-4.0 systemd[1]: Received SIGRTMIN+21 from PID 404 (plymouthd). Dec 21 08:00:30 volumio-4.0 systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Dec 21 08:00:30 volumio-4.0 systemd[1]: Reached target getty.target - Login Prompts. Dec 21 08:00:31 volumio-4.0 ntpd[1176]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 08:00:31 volumio-4.0 ntpd[1176]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 21 08:00:31 volumio-4.0 ntpd[1176]: DNS: dns_check: DNS error: -11, System error Dec 21 08:00:31 volumio-4.0 ntpd[1176]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ------------------------------------------- Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ----- Volumio3 ---- Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ------------------------------------------- Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ----- System startup ---- Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ------------------------------------------- Dec 21 08:00:31 volumio-4.0 avahi-daemon[985]: Service "Volumio 4.0" (/services/volumio.service) successfully established. Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: MYVOLUMIO Environment detected Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Plugin folders cleanup Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning into folder /volumio/app/plugins/ Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category audio_interface Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category miscellanea Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category music_service Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category plugins.json Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category system_controller Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category user_interface Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning into folder /data/plugins/ Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category music_service Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category system_controller Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Scanning category user_interface Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Plugin folders cleanup completed Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ------------------------------------------- Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ----- Core plugins startup ---- Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: ------------------------------------------- Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Adding plugin upnp to MyMusic Plugins Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugins from folder /data/plugins/ Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugin "system"... Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugin "appearance"... Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugin "network"... Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Refreshing Cached IP Addresses Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugin "services"... Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugin "alsa_controller"... Dec 21 08:00:31 volumio-4.0 sudo[1283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 08:00:31 volumio-4.0 sudo[1290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 08:00:31 volumio-4.0 sudo[1281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugin "wizard"... Dec 21 08:00:31 volumio-4.0 sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:31 volumio-4.0 sudo[1283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:31 volumio-4.0 sudo[1290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:31 volumio-4.0 sudo[1281]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Loading plugin "networkfs"... Dec 21 08:00:31 volumio-4.0 sudo[1283]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:31 volumio-4.0 sudo[1290]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Starting Udev Watcher for removable devices Dec 21 08:00:31 volumio-4.0 sudo[1321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Underwood,password=rts%C0ntax_7c,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.123/music /mnt/NAS/DS216 Dec 21 08:00:31 volumio-4.0 sudo[1321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Ignoring mount for partition: boot Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Ignoring mount for partition: volumio Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Ignoring mount for partition: volumio_data Dec 21 08:00:31 volumio-4.0 dhcpcd[924]: eth0: carrier acquired Dec 21 08:00:31 volumio-4.0 sh[916]: eth0: carrier acquired Dec 21 08:00:31 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control tx Dec 21 08:00:31 volumio-4.0 dhcpcd[1045]: eth0: carrier acquired Dec 21 08:00:31 volumio-4.0 sh[916]: eth0: IAID 67:c2:73:fb Dec 21 08:00:31 volumio-4.0 sh[916]: eth0: adding address fe80::a29e:7d86:eb08:8fef Dec 21 08:00:31 volumio-4.0 sh[916]: ipv6_addaddr1: Permission denied Dec 21 08:00:31 volumio-4.0 dhcpcd[924]: eth0: IAID 67:c2:73:fb Dec 21 08:00:31 volumio-4.0 dhcpcd[924]: eth0: adding address fe80::a29e:7d86:eb08:8fef Dec 21 08:00:31 volumio-4.0 dhcpcd[924]: ipv6_addaddr1: Permission denied Dec 21 08:00:31 volumio-4.0 dhcpcd[1045]: eth0: IAID 67:c2:73:fb Dec 21 08:00:31 volumio-4.0 dhcpcd[1045]: eth0: adding address fe80::a29e:7d86:eb08:8fef Dec 21 08:00:31 volumio-4.0 dhcpcd[1045]: ipv6_addaddr1: Permission denied Dec 21 08:00:31 volumio-4.0 volumio[1238]: info: Mounting Device ROSEDISK Dec 21 08:00:31 volumio-4.0 kernel: netfs: FS-Cache loaded Dec 21 08:00:31 volumio-4.0 sudo[1334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ROSEDISK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 21 08:00:31 volumio-4.0 sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:31 volumio-4.0 sudo[1321]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:31 volumio-4.0 kernel: Key type cifs.spnego registered Dec 21 08:00:31 volumio-4.0 kernel: Key type cifs.idmap registered Dec 21 08:00:31 volumio-4.0 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. Dec 21 08:00:31 volumio-4.0 kernel: CIFS: Attempting to mount //192.168.1.123/music Dec 21 08:00:31 volumio-4.0 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 21 08:00:31 volumio-4.0 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 21 08:00:32 volumio-4.0 ntpd[1176]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 08:00:32 volumio-4.0 ntpd[1176]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 21 08:00:32 volumio-4.0 ntpd[1176]: DNS: dns_check: DNS error: -11, System error Dec 21 08:00:32 volumio-4.0 ntpd[1176]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 21 08:00:32 volumio-4.0 ifplugd(eth0)[1187]: Link beat detected. Dec 21 08:00:32 volumio-4.0 sh[916]: eth0: soliciting a DHCP lease Dec 21 08:00:32 volumio-4.0 dhcpcd[924]: eth0: soliciting a DHCP lease Dec 21 08:00:32 volumio-4.0 dhcpcd[1045]: eth0: soliciting a DHCP lease Dec 21 08:00:32 volumio-4.0 sh[916]: eth0: offered 192.168.1.110 from 192.168.1.1 Dec 21 08:00:32 volumio-4.0 dhcpcd[924]: eth0: offered 192.168.1.110 from 192.168.1.1 Dec 21 08:00:32 volumio-4.0 ntfs-3g[1350]: Version 2022.10.3 integrated FUSE 28 Dec 21 08:00:32 volumio-4.0 ntfs-3g[1350]: Mounted /dev/sda1 (Read-Write, label "ROSEDISK", NTFS 3.1) Dec 21 08:00:32 volumio-4.0 ntfs-3g[1350]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 21 08:00:32 volumio-4.0 ntfs-3g[1350]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 21 08:00:32 volumio-4.0 ntfs-3g[1350]: Global ownership and permissions enforced, configuration type 7 Dec 21 08:00:32 volumio-4.0 sudo[1334]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "volumio_command_line_client"... Dec 21 08:00:32 volumio-4.0 sh[916]: eth0: probing address 192.168.1.110/24 Dec 21 08:00:32 volumio-4.0 dhcpcd[924]: eth0: probing address 192.168.1.110/24 Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "upnp"... Dec 21 08:00:32 volumio-4.0 dhcpcd[1045]: eth0: offered 192.168.1.110 from 192.168.1.1 Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: [1766300432188] Starting Upmpd Daemon Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "my_music"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "mpd"... Dec 21 08:00:32 volumio-4.0 dhcpcd[1045]: eth0: probing address 192.168.1.110/24 Dec 21 08:00:32 volumio-4.0 ifplugd(eth0)[1187]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 21 08:00:32 volumio-4.0 ifplugd(eth0)[1187]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "upnp_browser"... Dec 21 08:00:32 volumio-4.0 sh[916]: eth0: soliciting an IPv6 router Dec 21 08:00:32 volumio-4.0 dhcpcd[924]: eth0: soliciting an IPv6 router Dec 21 08:00:32 volumio-4.0 dhcpcd[1045]: eth0: soliciting an IPv6 router Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Starting UPNP Browser Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "alarm-clock"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "airplay_emulation"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Starting Shairport Sync Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "last_100"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "webradio"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "i2s_dacs"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "volumiodiscovery"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:32 volumio-4.0 volumio[1238]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:32 volumio-4.0 volumio[1238]: *** WARNING *** For more information see Dec 21 08:00:32 volumio-4.0 volumio[1238]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:32 volumio-4.0 volumio[1238]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:32 volumio-4.0 volumio[1238]: *** WARNING *** For more information see Dec 21 08:00:32 volumio-4.0 node[1238]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:32 volumio-4.0 node[1238]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:32 volumio-4.0 node[1238]: *** WARNING *** For more information see Dec 21 08:00:32 volumio-4.0 node[1238]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:32 volumio-4.0 node[1238]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:32 volumio-4.0 node[1238]: *** WARNING *** For more information see Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Discovery: Started advertising with name: Volumio 4.0 Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "squeezelite_mc"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Plugin now_playing is not enabled Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "outputs"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "albumart"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Plugin example_plugin is not enabled Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "inputs"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Loading plugin "updater_comm"... Dec 21 08:00:32 volumio-4.0 volumio[1238]: info: Plugin mpdemulation is not enabled Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading plugin "rest_api"... Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading plugin "websocket"... Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Starting Socket.io Server version 1.7.4 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading plugin "motherearthradio"... Dec 21 08:00:33 volumio-4.0 volumio[1369]: Forking 3 albumart workers Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Applying required configuration parameters for plugin motherearthradio Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [1766300433082] [MotherEarth] API delay: 6 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading plugin "rpi_eeprom_config"... Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Applying required configuration parameters for plugin rpi_eeprom_config Dec 21 08:00:33 volumio-4.0 ntpd[1176]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 08:00:33 volumio-4.0 ntpd[1176]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 21 08:00:33 volumio-4.0 ntpd[1176]: DNS: dns_check: DNS error: -11, System error Dec 21 08:00:33 volumio-4.0 ntpd[1176]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Hardware capabilities loaded Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading plugin "rpi_eeprom_updater"... Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Applying required configuration parameters for plugin rpi_eeprom_updater Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading plugin "Systeminfo"... Dec 21 08:00:33 volumio-4.0 volumio[1380]: Starting albumart workers Dec 21 08:00:33 volumio-4.0 volumio[1381]: Starting albumart workers Dec 21 08:00:33 volumio-4.0 volumio[1379]: Starting albumart workers Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading plugin "touch_display"... Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Applying required configuration parameters for plugin touch_display Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading i18n strings for locale de Dec 21 08:00:33 volumio-4.0 volumio[1238]: Updating browse sources language Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::initPlayerControls Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: Express server listening on port 3000 Dec 21 08:00:33 volumio-4.0 volumio[1238]: [Metrics] WebUI: 2s 572.42ms Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::resetVolumioState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::getcurrentVolume Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Volumio Network Manager: Network status updated: 0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Cannot mount NAS DS216 at system boot, trial number 1 ,retrying in 5 seconds Dec 21 08:00:33 volumio-4.0 volumio[1238]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 21 08:00:33 volumio-4.0 volumio[1238]: wlan0 Interface doesn't support scanning. Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Cannot use regular scanning, forcing with ap-force Dec 21 08:00:33 volumio-4.0 sudo[1437]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 21 08:00:33 volumio-4.0 sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 sudo[1437]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:33 volumio-4.0 volumio[1238]: command failed: No such device (-19) Dec 21 08:00:33 volumio-4.0 volumio[1238]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 21 08:00:33 volumio-4.0 volumio[1238]: command failed: No such device (-19) Dec 21 08:00:33 volumio-4.0 volumio[1238]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Reloading queue from file Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: VolumeController:: Volume=100 Mute =false Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::pushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::updateTrackBlock Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CorePlayQueue::getTrackBlock Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::setRepeat false single undefined Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::pushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::setRandom false Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::pushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Setting Device type: Raspberry PI Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c041a0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Discovery: adding 4b1bb0d3-be4f-4462-be1f-474d9ad5fc5a Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Discovery: Found device Volumio 4.0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioGetState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: VolumeController:: Volume=100 Mute =false Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreStateMachine::pushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Completed loading Core Plugins Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Preparing to generate the ALSA configuration file Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Asound.conf file unchanged, so no further update is needed Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Output device has changed, restarting MPD Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Output device has changed, restarting Shairport Sync Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:33 volumio-4.0 sudo[1457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 08:00:33 volumio-4.0 sudo[1457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 sudo[1459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 08:00:33 volumio-4.0 sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 sudo[1457]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: ___________ START PLUGINS ___________ Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: ControllerMpd::onStart: Initializing MPD Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Creating MPD Configuration file Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [1766300433723] CoreMusicLibrary::Adding element Medienserver Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:33 volumio-4.0 sudo[1467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 08:00:33 volumio-4.0 sudo[1467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: UPNP Browser: Client initialized successfully Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:33 volumio-4.0 sudo[1467]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:33 volumio-4.0 sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 08:00:33 volumio-4.0 sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [1766300433745] CoreMusicLibrary::Adding element Last_100 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [1766300433746] CoreMusicLibrary::Adding element Webradio Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:33 volumio-4.0 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 08:00:33 volumio-4.0 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 08:00:33 volumio-4.0 systemd[1]: mpd.service: Deactivated successfully. Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Initializing BBC Radios Dec 21 08:00:33 volumio-4.0 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 08:00:33 volumio-4.0 systemd[1]: mpd.socket: Deactivated successfully. Dec 21 08:00:33 volumio-4.0 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 08:00:33 volumio-4.0 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 08:00:33 volumio-4.0 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 08:00:33 volumio-4.0 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [squeezelite_mc] Starting proxy server... Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [1766300433788] CoreMusicLibrary::Adding element Mother Earth Radio Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:33 volumio-4.0 volumio[1238]: Cannot find translation for source Mother Earth Radio Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Starting Raspberry Pi EEPROM Configuration Manager Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Detected full model string: Raspberry Pi Compute Module 5 Lite Rev 1.0 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Matched to capability profile: Raspberry Pi 5 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Detected model: Raspberry Pi 5 Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Boot modes: sd, usb, nvme Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Using rpi-eeprom-config at: /usr/bin/rpi-eeprom-config Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Backup directory ensured: /data/configuration/system_controller/rpi_eeprom_config/backup Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromConfig] Plugin started successfully Dec 21 08:00:33 volumio-4.0 sudo[1487]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 08:00:33 volumio-4.0 sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 08:00:33 volumio-4.0 sudo[1506]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 21 08:00:33 volumio-4.0 sudo[1487]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [RpiEepromUpdater] Plugin started successfully Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Loading i18n strings for locale de Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: Volumio Calling Home Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: [squeezelite_mc] Proxy server started on port 40805 Dec 21 08:00:33 volumio-4.0 sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 21 08:00:33 volumio-4.0 sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 21 08:00:33 volumio-4.0 sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 sudo[1528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 21 08:00:33 volumio-4.0 sudo[1528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:33 volumio-4.0 systemd[1]: Reloading. Dec 21 08:00:33 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 08:00:34 volumio-4.0 volumio[1238]: Unhandled rejection Error: No sockets available, cannot start. Dec 21 08:00:34 volumio-4.0 volumio[1238]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 21 08:00:34 volumio-4.0 volumio[1238]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: touch_display: No backlight interface detected. Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: MPD Permissions set Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: MPD Permissions set Dec 21 08:00:34 volumio-4.0 volumio[1238]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: VolumeController:: Volume=100 Mute =false Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: CoreStateMachine::pushState Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: [squeezelite_mc] Server discovery started Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: [squeezelite_mc] Player finder started Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:34 volumio-4.0 volumio[1238]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 21 08:00:34 volumio-4.0 volumio[1238]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 08:00:34 volumio-4.0 volumio[1238]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 08:00:34 volumio-4.0 volumio[1238]: at doSend (node:dgram:717:16) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at afterDns (node:dgram:663:5) Dec 21 08:00:34 volumio-4.0 volumio[1238]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Dec 21 08:00:34 volumio-4.0 volumio[1238]: errno: -101, Dec 21 08:00:34 volumio-4.0 volumio[1238]: code: 'ENETUNREACH', Dec 21 08:00:34 volumio-4.0 volumio[1238]: syscall: 'send', Dec 21 08:00:34 volumio-4.0 volumio[1238]: address: '255.255.255.255', Dec 21 08:00:34 volumio-4.0 volumio[1238]: port: 3483 Dec 21 08:00:34 volumio-4.0 volumio[1238]: } Dec 21 08:00:34 volumio-4.0 volumio[1238]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 08:00:34 volumio-4.0 ntpd[1176]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 08:00:34 volumio-4.0 ntpd[1176]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 21 08:00:34 volumio-4.0 ntpd[1176]: DNS: dns_check: DNS error: -11, System error Dec 21 08:00:34 volumio-4.0 ntpd[1176]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 21 08:00:34 volumio-4.0 sudo[1572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 07:59' Dec 21 08:00:34 volumio-4.0 sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:34 volumio-4.0 sudo[1572]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:34 volumio-4.0 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 21 08:00:34 volumio-4.0 sudo[1530]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:34 volumio-4.0 systemd[1]: media-ROSEDISK.mount: Deactivated successfully. Dec 21 08:00:34 volumio-4.0 sudo[1459]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:34 volumio-4.0 sudo[1528]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:34 volumio-4.0 sudo[1526]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:34 volumio-4.0 sudo[1469]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:34 volumio-4.0 ntfs-3g[1350]: Unmounting /dev/sda1 (ROSEDISK) Dec 21 08:00:34 volumio-4.0 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 21 08:00:34 volumio-4.0 systemd[1]: volumio.service: Consumed 5.689s CPU time. Dec 21 08:00:34 volumio-4.0 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 21 08:00:34 volumio-4.0 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 21 08:00:34 volumio-4.0 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 21 08:00:34 volumio-4.0 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 21 08:00:34 volumio-4.0 systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 21 08:00:34 volumio-4.0 systemd[1]: volumio.service: Consumed 5.689s CPU time. Dec 21 08:00:34 volumio-4.0 systemd[1]: Started volumio.service - Volumio Backend Module. Dec 21 08:00:34 volumio-4.0 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 21 08:00:34 volumio-4.0 volumio-remote-updater[1005]: [2025-12-21 08:00:34] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 21 08:00:34 volumio-4.0 volumio-remote-updater[1005]: [2025-12-21 08:00:34] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 21 08:00:34 volumio-4.0 volumio-remote-updater[1005]: [2025-12-21 08:00:34] [error] handle_connect error: Connection refused Dec 21 08:00:34 volumio-4.0 volumio[1599]: info: ------------------------------------------- Dec 21 08:00:34 volumio-4.0 volumio[1599]: info: ----- Volumio3 ---- Dec 21 08:00:34 volumio-4.0 volumio[1599]: info: ------------------------------------------- Dec 21 08:00:34 volumio-4.0 volumio[1599]: info: ----- System startup ---- Dec 21 08:00:34 volumio-4.0 volumio[1599]: info: ------------------------------------------- Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: MYVOLUMIO Environment detected Dec 21 08:00:35 volumio-4.0 mpd[1509]: 2025-12-21T08:00:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Plugin folders cleanup Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning into folder /volumio/app/plugins/ Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category audio_interface Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category miscellanea Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category music_service Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category plugins.json Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category system_controller Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category user_interface Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning into folder /data/plugins/ Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category music_service Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category system_controller Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Scanning category user_interface Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Plugin folders cleanup completed Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: ------------------------------------------- Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: ----- Core plugins startup ---- Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: ------------------------------------------- Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Adding plugin upnp to MyMusic Plugins Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugins from folder /data/plugins/ Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "system"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "appearance"... Dec 21 08:00:35 volumio-4.0 systemd[1]: Started mpd.service - Music Player Daemon. Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "network"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Refreshing Cached IP Addresses Dec 21 08:00:35 volumio-4.0 sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 08:00:35 volumio-4.0 sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:35 volumio-4.0 sudo[1637]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 08:00:35 volumio-4.0 sudo[1637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:35 volumio-4.0 sudo[1635]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "services"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "alsa_controller"... Dec 21 08:00:35 volumio-4.0 sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 08:00:35 volumio-4.0 sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:35 volumio-4.0 sudo[1637]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:35 volumio-4.0 sudo[1647]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "wizard"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "networkfs"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Starting Udev Watcher for removable devices Dec 21 08:00:35 volumio-4.0 sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Underwood,password=rts%C0ntax_7c,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.123/music /mnt/NAS/DS216 Dec 21 08:00:35 volumio-4.0 sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:35 volumio-4.0 sudo[1673]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:35 volumio-4.0 kernel: CIFS: Attempting to mount //192.168.1.123/music Dec 21 08:00:35 volumio-4.0 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 21 08:00:35 volumio-4.0 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Ignoring mount for partition: boot Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Ignoring mount for partition: volumio Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Ignoring mount for partition: volumio_data Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Mounting Device ROSEDISK Dec 21 08:00:35 volumio-4.0 sudo[1683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ROSEDISK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 21 08:00:35 volumio-4.0 sudo[1683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:35 volumio-4.0 ntfs-3g[1686]: Version 2022.10.3 integrated FUSE 28 Dec 21 08:00:35 volumio-4.0 ntfs-3g[1686]: Mounted /dev/sda1 (Read-Write, label "ROSEDISK", NTFS 3.1) Dec 21 08:00:35 volumio-4.0 ntfs-3g[1686]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 21 08:00:35 volumio-4.0 ntfs-3g[1686]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 21 08:00:35 volumio-4.0 ntfs-3g[1686]: Global ownership and permissions enforced, configuration type 7 Dec 21 08:00:35 volumio-4.0 sudo[1683]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "volumio_command_line_client"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "upnp"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: [1766300435594] Starting Upmpd Daemon Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "my_music"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "mpd"... Dec 21 08:00:35 volumio-4.0 bash[1154]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 21 08:00:35 volumio-4.0 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 21 08:00:35 volumio-4.0 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "upnp_browser"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Starting UPNP Browser Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "alarm-clock"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "airplay_emulation"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Starting Shairport Sync Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "last_100"... Dec 21 08:00:35 volumio-4.0 volumio[1599]: info: Loading plugin "webradio"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "i2s_dacs"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "volumiodiscovery"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:36 volumio-4.0 volumio[1599]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:36 volumio-4.0 volumio[1599]: *** WARNING *** For more information see Dec 21 08:00:36 volumio-4.0 volumio[1599]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:36 volumio-4.0 node[1599]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:36 volumio-4.0 volumio[1599]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:36 volumio-4.0 volumio[1599]: *** WARNING *** For more information see Dec 21 08:00:36 volumio-4.0 node[1599]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:36 volumio-4.0 node[1599]: *** WARNING *** For more information see Dec 21 08:00:36 volumio-4.0 node[1599]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 08:00:36 volumio-4.0 node[1599]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 08:00:36 volumio-4.0 node[1599]: *** WARNING *** For more information see Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Discovery: Started advertising with name: Volumio 4.0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "squeezelite_mc"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Plugin now_playing is not enabled Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "outputs"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "albumart"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Plugin example_plugin is not enabled Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "inputs"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "updater_comm"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Plugin mpdemulation is not enabled Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "rest_api"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "websocket"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Starting Socket.io Server version 1.7.4 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "motherearthradio"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Applying required configuration parameters for plugin motherearthradio Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [1766300436217] [MotherEarth] API delay: 6 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "rpi_eeprom_config"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Applying required configuration parameters for plugin rpi_eeprom_config Dec 21 08:00:36 volumio-4.0 volumio[1691]: Forking 3 albumart workers Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Hardware capabilities loaded Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "rpi_eeprom_updater"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Applying required configuration parameters for plugin rpi_eeprom_updater Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "Systeminfo"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading plugin "touch_display"... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Applying required configuration parameters for plugin touch_display Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading i18n strings for locale de Dec 21 08:00:36 volumio-4.0 volumio[1599]: Updating browse sources language Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::initPlayerControls Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: Express server listening on port 3000 Dec 21 08:00:36 volumio-4.0 volumio[1599]: [Metrics] WebUI: 1s 664.85ms Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::resetVolumioState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::getcurrentVolume Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 08:00:36 volumio-4.0 volumio[1703]: Starting albumart workers Dec 21 08:00:36 volumio-4.0 volumio[1702]: Starting albumart workers Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Volumio Network Manager: Network status updated: 0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Cannot mount NAS DS216 at system boot, trial number 1 ,retrying in 5 seconds Dec 21 08:00:36 volumio-4.0 volumio[1599]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 21 08:00:36 volumio-4.0 volumio[1599]: wlan0 Interface doesn't support scanning. Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Cannot use regular scanning, forcing with ap-force Dec 21 08:00:36 volumio-4.0 sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 21 08:00:36 volumio-4.0 sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 sudo[1740]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:36 volumio-4.0 volumio[1701]: Starting albumart workers Dec 21 08:00:36 volumio-4.0 volumio[1599]: command failed: No such device (-19) Dec 21 08:00:36 volumio-4.0 volumio[1599]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 21 08:00:36 volumio-4.0 volumio[1599]: command failed: No such device (-19) Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: VolumeController:: Volume=100 Mute =false Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::pushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::updateTrackBlock Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CorePlayQueue::getTrackBlock Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 08:00:36 volumio-4.0 volumio[1599]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Reloading queue from file Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::setRepeat false single undefined Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::pushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::setRandom false Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::pushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Setting Device type: Raspberry PI Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c041a0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: VolumeController:: Volume=100 Mute =false Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::pushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Completed loading Core Plugins Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Preparing to generate the ALSA configuration file Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Asound.conf file unchanged, so no further update is needed Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Output device has changed, restarting MPD Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Output device has changed, restarting Shairport Sync Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:36 volumio-4.0 sudo[1764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 08:00:36 volumio-4.0 sudo[1764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 sudo[1764]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:36 volumio-4.0 sudo[1766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 08:00:36 volumio-4.0 sudo[1766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: ___________ START PLUGINS ___________ Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: ControllerMpd::onStart: Initializing MPD Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Creating MPD Configuration file Dec 21 08:00:36 volumio-4.0 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [1766300436718] CoreMusicLibrary::Adding element Medienserver Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:36 volumio-4.0 sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 08:00:36 volumio-4.0 sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: UPNP Browser: Client initialized successfully Dec 21 08:00:36 volumio-4.0 sudo[1774]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:36 volumio-4.0 sudo[1776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 08:00:36 volumio-4.0 sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [1766300436739] CoreMusicLibrary::Adding element Last_100 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [1766300436739] CoreMusicLibrary::Adding element Webradio Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Initializing BBC Radios Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [squeezelite_mc] Starting proxy server... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [1766300436779] CoreMusicLibrary::Adding element Mother Earth Radio Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 08:00:36 volumio-4.0 volumio[1599]: Cannot find translation for source Mother Earth Radio Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Starting Raspberry Pi EEPROM Configuration Manager Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Detected full model string: Raspberry Pi Compute Module 5 Lite Rev 1.0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Matched to capability profile: Raspberry Pi 5 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Detected model: Raspberry Pi 5 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Boot modes: sd, usb, nvme Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Using rpi-eeprom-config at: /usr/bin/rpi-eeprom-config Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Backup directory ensured: /data/configuration/system_controller/rpi_eeprom_config/backup Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromConfig] Plugin started successfully Dec 21 08:00:36 volumio-4.0 systemd[1]: mpd.service: Deactivated successfully. Dec 21 08:00:36 volumio-4.0 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 08:00:36 volumio-4.0 systemd[1]: mpd.service: Consumed 1.237s CPU time. Dec 21 08:00:36 volumio-4.0 systemd[1]: mpd.socket: Deactivated successfully. Dec 21 08:00:36 volumio-4.0 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 08:00:36 volumio-4.0 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [RpiEepromUpdater] Plugin started successfully Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Loading i18n strings for locale de Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: Volumio Calling Home Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [squeezelite_mc] Proxy server started on port 34549 Dec 21 08:00:36 volumio-4.0 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 08:00:36 volumio-4.0 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 08:00:36 volumio-4.0 sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 21 08:00:36 volumio-4.0 sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 sudo[1834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 21 08:00:36 volumio-4.0 sudo[1834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 sudo[1836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 21 08:00:36 volumio-4.0 sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:36 volumio-4.0 systemd[1]: Reloading. Dec 21 08:00:36 volumio-4.0 sudo[1831]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 08:00:36 volumio-4.0 sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 08:00:36 volumio-4.0 sudo[1831]: pam_unix(sudo:session): session closed for user root Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 08:00:36 volumio-4.0 volumio[1599]: Unhandled rejection Error: No sockets available, cannot start. Dec 21 08:00:36 volumio-4.0 volumio[1599]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 21 08:00:36 volumio-4.0 volumio[1599]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 21 08:00:36 volumio-4.0 volumio[1599]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: touch_display: No backlight interface detected. Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: MPD Permissions set Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: MPD Permissions set Dec 21 08:00:36 volumio-4.0 volumio[1599]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: VolumeController:: Volume=100 Mute =false Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreStateMachine::pushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CorePlayQueue::getTrack 0 Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::volumioPushState Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [squeezelite_mc] Server discovery started Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [squeezelite_mc] Player finder started Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 08:00:36 volumio-4.0 volumio[1599]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 21 08:00:37 volumio-4.0 volumio[1599]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 08:00:37 volumio-4.0 volumio[1599]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 08:00:37 volumio-4.0 volumio[1599]: at doSend (node:dgram:717:16) Dec 21 08:00:37 volumio-4.0 volumio[1599]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Dec 21 08:00:37 volumio-4.0 volumio[1599]: at afterDns (node:dgram:663:5) Dec 21 08:00:37 volumio-4.0 volumio[1599]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Dec 21 08:00:37 volumio-4.0 volumio[1599]: errno: -101, Dec 21 08:00:37 volumio-4.0 volumio[1599]: code: 'ENETUNREACH', Dec 21 08:00:37 volumio-4.0 volumio[1599]: syscall: 'send', Dec 21 08:00:37 volumio-4.0 volumio[1599]: address: '255.255.255.255', Dec 21 08:00:37 volumio-4.0 volumio[1599]: port: 3483 Dec 21 08:00:37 volumio-4.0 volumio[1599]: } Dec 21 08:00:37 volumio-4.0 volumio[1599]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 08:00:37 volumio-4.0 sudo[1876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 07:59' Dec 21 08:00:37 volumio-4.0 sudo[1876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 08:00:37 volumio-4.0 dhcpcd[1045]: eth0: leased 192.168.1.110 for 86400 seconds Dec 21 08:00:37 volumio-4.0 avahi-daemon[985]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.110. Dec 21 08:00:37 volumio-4.0 avahi-daemon[985]: New relevant interface eth0.IPv4 for mDNS. Dec 21 08:00:37 volumio-4.0 avahi-daemon[985]: Registering new address record for 192.168.1.110 on eth0.IPv4. Dec 21 08:00:37 volumio-4.0 dhcpcd[1045]: eth0: adding route to 192.168.1.0/24 Dec 21 08:00:37 volumio-4.0 dhcpcd[1045]: eth0: adding default route via 192.168.1.1 PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5250573efb99f2852fa27ea7e92def8586093039" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="d9e2b6784b9276d5a676bf564ce7524ad160509b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 19 15:53:15 UTC 2025" VOLUMIO_VERSION="4.082" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="47d7740e8c55792fe06a847eb7ad6b29"