Oct 16 15:06:04 volumio-4 kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Oct 16 15:06:04 volumio-4 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 Oct 16 15:06:04 volumio-4 kernel: KASLR enabled Oct 16 15:06:04 volumio-4 kernel: random: crng init done Oct 16 15:06:04 volumio-4 kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Oct 16 15:06:04 volumio-4 kernel: efi: UEFI not found. Oct 16 15:06:04 volumio-4 kernel: Reserved memory: created CMA memory pool at 0x000000003bc00000, size 64 MiB Oct 16 15:06:04 volumio-4 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Oct 16 15:06:04 volumio-4 kernel: OF: reserved mem: 0x000000003bc00000..0x000000003fbfffff (65536 KiB) map reusable linux,cma Oct 16 15:06:04 volumio-4 kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Oct 16 15:06:04 volumio-4 kernel: OF: reserved mem: 0x000000003fd16240..0x000000003fd16277 (0 KiB) nomap non-reusable nvram@0 Oct 16 15:06:04 volumio-4 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff] Oct 16 15:06:04 volumio-4 kernel: Faking node 0 at [mem 0x0000000000000000-0x000000003fffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: Faking node 1 at [mem 0x0000000040000000-0x000000007fffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: Faking node 2 at [mem 0x0000000080000000-0x00000000bfffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: Faking node 3 at [mem 0x00000000c0000000-0x00000000ffffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: Faking node 4 at [mem 0x0000000100000000-0x000000013fffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: Faking node 5 at [mem 0x0000000140000000-0x000000017fffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: Faking node 6 at [mem 0x0000000180000000-0x00000001bfffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: Faking node 7 at [mem 0x00000001c0000000-0x00000001ffffffff] (1024MB) Oct 16 15:06:04 volumio-4 kernel: NUMA: Initialized distance table, cnt=8 Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(0) allocated [mem 0x3bbfd300-0x3bbfffff] Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(1) allocated [mem 0x7fffd300-0x7fffffff] Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(2) allocated [mem 0xbfffd300-0xbfffffff] Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(3) allocated [mem 0xffffd300-0xffffffff] Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(4) allocated [mem 0x13fffd300-0x13fffffff] Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(5) allocated [mem 0x17fffd300-0x17fffffff] Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(6) allocated [mem 0x1bfffd300-0x1bfffffff] Oct 16 15:06:04 volumio-4 kernel: NODE_DATA(7) allocated [mem 0x1fef9f300-0x1fefa1fff] Oct 16 15:06:04 volumio-4 kernel: Zone ranges: Oct 16 15:06:04 volumio-4 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Oct 16 15:06:04 volumio-4 kernel: DMA32 empty Oct 16 15:06:04 volumio-4 kernel: Normal [mem 0x0000000100000000-0x00000001ffffffff] Oct 16 15:06:04 volumio-4 kernel: Movable zone start for each node Oct 16 15:06:04 volumio-4 kernel: Early memory node ranges Oct 16 15:06:04 volumio-4 kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Oct 16 15:06:04 volumio-4 kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Oct 16 15:06:04 volumio-4 kernel: node 1: [mem 0x0000000040000000-0x000000007fffffff] Oct 16 15:06:04 volumio-4 kernel: node 2: [mem 0x0000000080000000-0x00000000bfffffff] Oct 16 15:06:04 volumio-4 kernel: node 3: [mem 0x00000000c0000000-0x00000000ffffffff] Oct 16 15:06:04 volumio-4 kernel: node 4: [mem 0x0000000100000000-0x000000013fffffff] Oct 16 15:06:04 volumio-4 kernel: node 5: [mem 0x0000000140000000-0x000000017fffffff] Oct 16 15:06:04 volumio-4 kernel: node 6: [mem 0x0000000180000000-0x00000001bfffffff] Oct 16 15:06:04 volumio-4 kernel: node 7: [mem 0x00000001c0000000-0x00000001ffffffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003fbfffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 1 [mem 0x0000000040000000-0x000000007fffffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 2 [mem 0x0000000080000000-0x00000000bfffffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 3 [mem 0x00000000c0000000-0x00000000ffffffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 4 [mem 0x0000000100000000-0x000000013fffffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 5 [mem 0x0000000140000000-0x000000017fffffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 6 [mem 0x0000000180000000-0x00000001bfffffff] Oct 16 15:06:04 volumio-4 kernel: Initmem setup node 7 [mem 0x00000001c0000000-0x00000001ffffffff] Oct 16 15:06:04 volumio-4 kernel: On node 1, zone DMA: 1024 pages in unavailable ranges Oct 16 15:06:04 volumio-4 kernel: psci: probing for conduit method from DT. Oct 16 15:06:04 volumio-4 kernel: psci: PSCIv1.1 detected in firmware. Oct 16 15:06:04 volumio-4 kernel: psci: Using standard PSCI v0.2 function IDs Oct 16 15:06:04 volumio-4 kernel: psci: MIGRATE_INFO_TYPE not supported. Oct 16 15:06:04 volumio-4 kernel: psci: SMC Calling Convention v1.2 Oct 16 15:06:04 volumio-4 kernel: percpu: Embedded 33 pages/cpu s95192 r8192 d31784 u135168 Oct 16 15:06:04 volumio-4 kernel: pcpu-alloc: s95192 r8192 d31784 u135168 alloc=33*4096 Oct 16 15:06:04 volumio-4 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 16 15:06:04 volumio-4 kernel: Detected PIPT I-cache on CPU0 Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Virtualization Host Extensions Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Spectre-v4 Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Spectre-BHB Oct 16 15:06:04 volumio-4 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 16 15:06:04 volumio-4 kernel: alternatives: applying boot alternatives Oct 16 15:06:04 volumio-4 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 smsc95xx.macaddr=2C:CF:67:8D:B7:8D 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=44379c43-1756-419c-8f0c-a49103976c4d imgfile=/volumio_current.sqsh bootpart=UUID=30D3-927D datapart=UUID=63b827ef-c5d1-4a68-9975-b78edcf0a70c uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Oct 16 15:06:04 volumio-4 kernel: cgroup: Disabling memory control group subsystem Oct 16 15:06:04 volumio-4 kernel: mempolicy: NUMA default policy overridden to 'interleave:0-7' Oct 16 15:06:04 volumio-4 kernel: PCIe ASPM is disabled Oct 16 15:06:04 volumio-4 kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=44379c43-1756-419c-8f0c-a49103976c4d imgfile=/volumio_current.sqsh bootpart=UUID=30D3-927D datapart=UUID=63b827ef-c5d1-4a68-9975-b78edcf0a70c uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 0: 0 1 2 3 4 5 6 7 Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 1: 1 2 3 4 5 6 7 0 Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 2: 2 3 4 5 6 7 0 1 Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 3: 3 4 5 6 7 0 1 2 Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 4: 4 5 6 7 0 1 2 3 Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 5: 5 6 7 0 1 2 3 4 Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 6: 6 7 0 1 2 3 4 5 Oct 16 15:06:04 volumio-4 kernel: Fallback order for Node 7: 7 0 1 2 3 4 5 6 Oct 16 15:06:04 volumio-4 kernel: Built 8 zonelists, mobility grouping on. Total pages: 2096128 Oct 16 15:06:04 volumio-4 kernel: Policy zone: Normal Oct 16 15:06:04 volumio-4 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 16 15:06:04 volumio-4 kernel: software IO TLB: area num 4. Oct 16 15:06:04 volumio-4 kernel: software IO TLB: mapped [mem 0x00000000fae00000-0x00000000fee00000] (64MB) Oct 16 15:06:04 volumio-4 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=8 Oct 16 15:06:04 volumio-4 kernel: ftrace: allocating 45342 entries in 178 pages Oct 16 15:06:04 volumio-4 kernel: ftrace: allocated 178 pages with 4 groups Oct 16 15:06:04 volumio-4 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 16 15:06:04 volumio-4 kernel: rcu: RCU event tracing is enabled. Oct 16 15:06:04 volumio-4 kernel: Trampoline variant of Tasks RCU enabled. Oct 16 15:06:04 volumio-4 kernel: Rude variant of Tasks RCU enabled. Oct 16 15:06:04 volumio-4 kernel: Tracing variant of Tasks RCU enabled. Oct 16 15:06:04 volumio-4 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Oct 16 15:06:04 volumio-4 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 16 15:06:04 volumio-4 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 16 15:06:04 volumio-4 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 16 15:06:04 volumio-4 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 16 15:06:04 volumio-4 kernel: Root IRQ handler: gic_handle_irq Oct 16 15:06:04 volumio-4 kernel: GIC: Using split EOI/Deactivate mode Oct 16 15:06:04 volumio-4 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 16 15:06:04 volumio-4 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Oct 16 15:06:04 volumio-4 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Oct 16 15:06:04 volumio-4 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Oct 16 15:06:04 volumio-4 kernel: Console: colour dummy device 80x25 Oct 16 15:06:04 volumio-4 kernel: printk: legacy console [tty1] enabled Oct 16 15:06:04 volumio-4 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Oct 16 15:06:04 volumio-4 kernel: pid_max: default: 32768 minimum: 301 Oct 16 15:06:04 volumio-4 kernel: LSM: initializing lsm=capability Oct 16 15:06:04 volumio-4 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: rcu: Hierarchical SRCU implementation. Oct 16 15:06:04 volumio-4 kernel: rcu: Max phase no-delay instances is 1000. Oct 16 15:06:04 volumio-4 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 0 crossnode level Oct 16 15:06:04 volumio-4 kernel: EFI services will not be available. Oct 16 15:06:04 volumio-4 kernel: smp: Bringing up secondary CPUs ... Oct 16 15:06:04 volumio-4 kernel: Detected PIPT I-cache on CPU1 Oct 16 15:06:04 volumio-4 kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Oct 16 15:06:04 volumio-4 kernel: Detected PIPT I-cache on CPU2 Oct 16 15:06:04 volumio-4 kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Oct 16 15:06:04 volumio-4 kernel: Detected PIPT I-cache on CPU3 Oct 16 15:06:04 volumio-4 kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Oct 16 15:06:04 volumio-4 kernel: smp: Brought up 8 nodes, 4 CPUs Oct 16 15:06:04 volumio-4 kernel: SMP: Total of 4 processors activated. Oct 16 15:06:04 volumio-4 kernel: CPU: All CPU(s) started at EL2 Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: 32-bit EL0 Support Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Common not Private translations Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: CRC32 instructions Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: LSE atomic instructions Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Privileged Access Never Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: RAS Extension Support Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 16 15:06:04 volumio-4 kernel: alternatives: applying system-wide alternatives Oct 16 15:06:04 volumio-4 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 16 15:06:04 volumio-4 kernel: Memory: 8017264K/8384512K available (14080K kernel code, 2406K rwdata, 4820K rodata, 5440K init, 577K bss, 276112K reserved, 65536K cma-reserved) Oct 16 15:06:04 volumio-4 kernel: devtmpfs: initialized Oct 16 15:06:04 volumio-4 kernel: Enabled cp15_barrier support Oct 16 15:06:04 volumio-4 kernel: Enabled setend support Oct 16 15:06:04 volumio-4 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Oct 16 15:06:04 volumio-4 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 16 15:06:04 volumio-4 kernel: 0 pages in range for non-PLT usage Oct 16 15:06:04 volumio-4 kernel: 517392 pages in range for PLT usage Oct 16 15:06:04 volumio-4 kernel: pinctrl core: initialized pinctrl subsystem Oct 16 15:06:04 volumio-4 kernel: DMI not present or invalid. Oct 16 15:06:04 volumio-4 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 16 15:06:04 volumio-4 kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Oct 16 15:06:04 volumio-4 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 16 15:06:04 volumio-4 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 16 15:06:04 volumio-4 kernel: audit: initializing netlink subsys (disabled) Oct 16 15:06:04 volumio-4 kernel: audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 Oct 16 15:06:04 volumio-4 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 16 15:06:04 volumio-4 kernel: cpuidle: using governor menu Oct 16 15:06:04 volumio-4 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 16 15:06:04 volumio-4 kernel: ASID allocator initialised with 32768 entries Oct 16 15:06:04 volumio-4 kernel: Serial: AMBA PL011 UART driver Oct 16 15:06:04 volumio-4 kernel: /soc@107c000000/interrupt-controller@7fff9000: Fixed dependency cycle(s) with /soc@107c000000/interrupt-controller@7fff9000 Oct 16 15:06:04 volumio-4 kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Oct 16 15:06:04 volumio-4 kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 16, base_baud = 0) is a PL011 rev3 Oct 16 15:06:04 volumio-4 kernel: printk: legacy console [ttyAMA10] enabled Oct 16 15:06:04 volumio-4 kernel: raspberrypi-firmware soc@107c000000:firmware: Attached to firmware from 2025-05-08T14:13:17, variant start_cd Oct 16 15:06:04 volumio-4 kernel: raspberrypi-firmware soc@107c000000:firmware: Firmware hash is 6947117700000000000000000000000000000000 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000/bridge@0 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /panel_disp@1 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000/bridge@0 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /panel_disp@1 Oct 16 15:06:04 volumio-4 kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Oct 16 15:06:04 volumio-4 kernel: iommu: Default domain type: Translated Oct 16 15:06:04 volumio-4 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 16 15:06:04 volumio-4 kernel: SCSI subsystem initialized Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new interface driver usbfs Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new interface driver hub Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new device driver usb Oct 16 15:06:04 volumio-4 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 16 15:06:04 volumio-4 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 16 15:06:04 volumio-4 kernel: PTP clock support registered Oct 16 15:06:04 volumio-4 kernel: vgaarb: loaded Oct 16 15:06:04 volumio-4 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 16 15:06:04 volumio-4 kernel: VFS: Disk quotas dquot_6.6.0 Oct 16 15:06:04 volumio-4 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 16 15:06:04 volumio-4 kernel: NET: Registered PF_INET protocol family Oct 16 15:06:04 volumio-4 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: TCP: Hash tables configured (established 65536 bind 65536) Oct 16 15:06:04 volumio-4 kernel: MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, vmalloc) Oct 16 15:06:04 volumio-4 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 16 15:06:04 volumio-4 kernel: RPC: Registered named UNIX socket transport module. Oct 16 15:06:04 volumio-4 kernel: RPC: Registered udp transport module. Oct 16 15:06:04 volumio-4 kernel: RPC: Registered tcp transport module. Oct 16 15:06:04 volumio-4 kernel: RPC: Registered tcp-with-tls transport module. Oct 16 15:06:04 volumio-4 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 16 15:06:04 volumio-4 kernel: PCI: CLS 0 bytes, default 64 Oct 16 15:06:04 volumio-4 kernel: Trying to unpack rootfs image as initramfs... Oct 16 15:06:04 volumio-4 kernel: kvm [1]: nv: 554 coarse grained trap handlers Oct 16 15:06:04 volumio-4 kernel: kvm [1]: IPA Size Limit: 40 bits Oct 16 15:06:04 volumio-4 kernel: kvm [1]: vgic interrupt IRQ9 Oct 16 15:06:04 volumio-4 kernel: kvm [1]: VHE mode initialized successfully Oct 16 15:06:04 volumio-4 kernel: Initialise system trusted keyrings Oct 16 15:06:04 volumio-4 kernel: workingset: timestamp_bits=42 max_order=21 bucket_order=0 Oct 16 15:06:04 volumio-4 kernel: NFS: Registering the id_resolver key type Oct 16 15:06:04 volumio-4 kernel: Key type id_resolver registered Oct 16 15:06:04 volumio-4 kernel: Key type id_legacy registered Oct 16 15:06:04 volumio-4 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Oct 16 15:06:04 volumio-4 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Oct 16 15:06:04 volumio-4 kernel: Key type asymmetric registered Oct 16 15:06:04 volumio-4 kernel: Asymmetric key parser 'x509' registered Oct 16 15:06:04 volumio-4 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Oct 16 15:06:04 volumio-4 kernel: io scheduler mq-deadline registered Oct 16 15:06:04 volumio-4 kernel: io scheduler kyber registered Oct 16 15:06:04 volumio-4 kernel: io scheduler bfq registered Oct 16 15:06:04 volumio-4 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7d510600, parent irq: 27) Oct 16 15:06:04 volumio-4 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7c502000, parent irq: 28) Oct 16 15:06:04 volumio-4 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508380, parent irq: 29) Oct 16 15:06:04 volumio-4 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508400, parent irq: 30) Oct 16 15:06:04 volumio-4 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d503000, parent irq: 31) Oct 16 15:06:04 volumio-4 kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 16 15:06:04 volumio-4 kernel: Freeing initrd memory: 33152K Oct 16 15:06:04 volumio-4 kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Oct 16 15:06:04 volumio-4 kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Oct 16 15:06:04 volumio-4 kernel: serial serial0: tty port ttyS0 registered Oct 16 15:06:04 volumio-4 kernel: iproc-rng200 107d208000.rng: hwrng registered Oct 16 15:06:04 volumio-4 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Oct 16 15:06:04 volumio-4 kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Oct 16 15:06:04 volumio-4 kernel: brd: module loaded Oct 16 15:06:04 volumio-4 kernel: loop: module loaded Oct 16 15:06:04 volumio-4 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Oct 16 15:06:04 volumio-4 kernel: Loading iSCSI transport class v2.0-870. Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new interface driver lan78xx Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new interface driver smsc95xx Oct 16 15:06:04 volumio-4 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Oct 16 15:06:04 volumio-4 kernel: dwc_otg: FIQ enabled Oct 16 15:06:04 volumio-4 kernel: dwc_otg: NAK holdoff enabled Oct 16 15:06:04 volumio-4 kernel: dwc_otg: FIQ split-transaction FSM enabled Oct 16 15:06:04 volumio-4 kernel: Module dwc_common_port init Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new interface driver uas Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new interface driver usb-storage Oct 16 15:06:04 volumio-4 kernel: mousedev: PS/2 mouse device common for all mice Oct 16 15:06:04 volumio-4 kernel: rpi-rtc soc@107c000000:rpi_rtc: registered as rtc0 Oct 16 15:06:04 volumio-4 kernel: rpi-rtc soc@107c000000:rpi_rtc: setting system clock to 2025-10-16T13:05:52 UTC (1760619952) Oct 16 15:06:04 volumio-4 kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Oct 16 15:06:04 volumio-4 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Oct 16 15:06:04 volumio-4 kernel: sdhci: Secure Digital Host Controller Interface driver Oct 16 15:06:04 volumio-4 kernel: sdhci: Copyright(c) Pierre Ossman Oct 16 15:06:04 volumio-4 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Oct 16 15:06:04 volumio-4 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Oct 16 15:06:04 volumio-4 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 16 15:06:04 volumio-4 kernel: usbcore: registered new interface driver usbhid Oct 16 15:06:04 volumio-4 kernel: usbhid: USB HID core driver Oct 16 15:06:04 volumio-4 kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 (0,8000003f) counters available Oct 16 15:06:04 volumio-4 kernel: NET: Registered PF_PACKET protocol family Oct 16 15:06:04 volumio-4 kernel: Key type dns_resolver registered Oct 16 15:06:04 volumio-4 kernel: registered taskstats version 1 Oct 16 15:06:04 volumio-4 kernel: Loading compiled-in X.509 certificates Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 0: null Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 1: null Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 2: null Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 3: null Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 4: null Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 5: null Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 6: null Oct 16 15:06:04 volumio-4 kernel: Demotion targets for Node 7: null Oct 16 15:06:04 volumio-4 kernel: Key type .fscrypt registered Oct 16 15:06:04 volumio-4 kernel: Key type fscrypt-provisioning registered Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@1000110000 ranges: Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@1000110000, using [bus 00-ff] Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00 Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:00: root bus resource [bus 00-ff] Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: PCI bridge to [bus 00] Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: bridge window [mem 0x00000000-0x000fffff] Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: bridge window [mem 0x00000000-0x000fffff 64bit pref] Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: PME# supported from D0 D3hot Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000110000.pcie: link down Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01 Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: PCI bridge to [bus 01] Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff] Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref] Oct 16 15:06:04 volumio-4 kernel: pci 0001:00:00.0: Max Payload Size set to 512/ 512 (was 128), Max Read Rq 512 Oct 16 15:06:04 volumio-4 kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 38 Oct 16 15:06:04 volumio-4 kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 38 Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:01: busn_res: [bus 01] is released Oct 16 15:06:04 volumio-4 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] is released Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges: Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@1000120000, using [bus 00-ff] Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000 Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00 Oct 16 15:06:04 volumio-4 kernel: pci_bus 0002:00: root bus resource [bus 00-ff] Oct 16 15:06:04 volumio-4 kernel: pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Oct 16 15:06:04 volumio-4 kernel: pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: PCI bridge to [bus 00] Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: bridge window [mem 0x1f80000000-0x1fbfffffff] Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: PME# supported from D0 D3hot Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: clkreq-mode set to default Oct 16 15:06:04 volumio-4 kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff] Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff] Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff] Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: supports D1 Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold Oct 16 15:06:04 volumio-4 kernel: pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01 Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: PCI bridge to [bus 01] Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Oct 16 15:06:04 volumio-4 kernel: pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb] Oct 16 15:06:04 volumio-4 kernel: pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref] Oct 16 15:06:04 volumio-4 kernel: pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff] Oct 16 15:06:04 volumio-4 kernel: pci 0002:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Oct 16 15:06:04 volumio-4 kernel: pci 0002:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Oct 16 15:06:04 volumio-4 kernel: pcieport 0002:00:00.0: enabling device (0000 -> 0002) Oct 16 15:06:04 volumio-4 kernel: pcieport 0002:00:00.0: PME: Signaling with IRQ 39 Oct 16 15:06:04 volumio-4 kernel: pcieport 0002:00:00.0: AER: enabled with IRQ 39 Oct 16 15:06:04 volumio-4 kernel: rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Oct 16 15:06:04 volumio-4 kernel: rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Oct 16 15:06:04 volumio-4 kernel: rp1 0002:01:00.0: enabling device (0000 -> 0002) Oct 16 15:06:04 volumio-4 kernel: rp1 0002:01:00.0: chip_id 0x20001927 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000/bridge@0 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /panel_disp@1 Oct 16 15:06:04 volumio-4 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Oct 16 15:06:04 volumio-4 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Oct 16 15:06:04 volumio-4 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Oct 16 15:06:04 volumio-4 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Oct 16 15:06:04 volumio-4 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Oct 16 15:06:04 volumio-4 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Oct 16 15:06:04 volumio-4 kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Oct 16 15:06:04 volumio-4 kernel: mmc0: CQHCI version 5.10 Oct 16 15:06:04 volumio-4 kernel: mmc1: CQHCI version 5.10 Oct 16 15:06:04 volumio-4 kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 101 Oct 16 15:06:04 volumio-4 kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (2c:cf:67:8d:b7:8d) Oct 16 15:06:04 volumio-4 kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Oct 16 15:06:04 volumio-4 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Oct 16 15:06:04 volumio-4 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 16 15:06:04 volumio-4 kernel: usb usb1: Product: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: usb usb1: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Oct 16 15:06:04 volumio-4 kernel: usb usb1: SerialNumber: xhci-hcd.0 Oct 16 15:06:04 volumio-4 kernel: hub 1-0:1.0: USB hub found Oct 16 15:06:04 volumio-4 kernel: hub 1-0:1.0: 2 ports detected Oct 16 15:06:04 volumio-4 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Oct 16 15:06:04 volumio-4 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 16 15:06:04 volumio-4 kernel: usb usb2: Product: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: usb usb2: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Oct 16 15:06:04 volumio-4 kernel: usb usb2: SerialNumber: xhci-hcd.0 Oct 16 15:06:04 volumio-4 kernel: hub 2-0:1.0: USB hub found Oct 16 15:06:04 volumio-4 kernel: hub 2-0:1.0: 1 port detected Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Oct 16 15:06:04 volumio-4 kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Oct 16 15:06:04 volumio-4 kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Oct 16 15:06:04 volumio-4 kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 16 15:06:04 volumio-4 kernel: usb usb3: Product: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: usb usb3: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Oct 16 15:06:04 volumio-4 kernel: usb usb3: SerialNumber: xhci-hcd.1 Oct 16 15:06:04 volumio-4 kernel: hub 3-0:1.0: USB hub found Oct 16 15:06:04 volumio-4 kernel: hub 3-0:1.0: 2 ports detected Oct 16 15:06:04 volumio-4 kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Oct 16 15:06:04 volumio-4 kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 16 15:06:04 volumio-4 kernel: usb usb4: Product: xHCI Host Controller Oct 16 15:06:04 volumio-4 kernel: usb usb4: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Oct 16 15:06:04 volumio-4 kernel: usb usb4: SerialNumber: xhci-hcd.1 Oct 16 15:06:04 volumio-4 kernel: hub 4-0:1.0: USB hub found Oct 16 15:06:04 volumio-4 kernel: hub 4-0:1.0: 1 port detected Oct 16 15:06:04 volumio-4 kernel: of_cfs_init Oct 16 15:06:04 volumio-4 kernel: of_cfs_init: OK Oct 16 15:06:04 volumio-4 kernel: clk: Disabling unused clocks Oct 16 15:06:04 volumio-4 kernel: PM: genpd: Disabling unused power domains Oct 16 15:06:04 volumio-4 kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Oct 16 15:06:04 volumio-4 kernel: mmc0: new ultra high speed SDR104 SDHC card at address aaaa Oct 16 15:06:04 volumio-4 kernel: mmcblk0: mmc0:aaaa SE32G 29.7 GiB Oct 16 15:06:04 volumio-4 kernel: mmcblk0: p1 p2 p3 Oct 16 15:06:04 volumio-4 kernel: mmcblk0: mmc0:aaaa SE32G 29.7 GiB (quirks 0x00004000) Oct 16 15:06:04 volumio-4 kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Oct 16 15:06:04 volumio-4 kernel: Freeing unused kernel memory: 5440K Oct 16 15:06:04 volumio-4 kernel: Run /init as init process Oct 16 15:06:04 volumio-4 kernel: with arguments: Oct 16 15:06:04 volumio-4 kernel: /init Oct 16 15:06:04 volumio-4 kernel: splash Oct 16 15:06:04 volumio-4 kernel: nodebug Oct 16 15:06:04 volumio-4 kernel: with environment: Oct 16 15:06:04 volumio-4 kernel: HOME=/ Oct 16 15:06:04 volumio-4 kernel: TERM=linux Oct 16 15:06:04 volumio-4 kernel: imgpart=UUID=44379c43-1756-419c-8f0c-a49103976c4d Oct 16 15:06:04 volumio-4 kernel: imgfile=/volumio_current.sqsh Oct 16 15:06:04 volumio-4 kernel: bootpart=UUID=30D3-927D Oct 16 15:06:04 volumio-4 kernel: datapart=UUID=63b827ef-c5d1-4a68-9975-b78edcf0a70c Oct 16 15:06:04 volumio-4 kernel: uuidconfig=cmdline.txt Oct 16 15:06:04 volumio-4 kernel: bootdelay=7 Oct 16 15:06:04 volumio-4 kernel: use_kmsg=no Oct 16 15:06:04 volumio-4 kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_info_register (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_device_new (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Oct 16 15:06:04 volumio-4 kernel: snd_timer: Unknown symbol snd_register_device (err -2) Oct 16 15:06:04 volumio-4 kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Oct 16 15:06:04 volumio-4 kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Oct 16 15:06:04 volumio-4 kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: bcm2712_iommu_of_xlate: MMU 1000005280.iommu Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: bcm2712_iommu_probe_device: MMU 1000005280.iommu Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: bcm2712_iommu_device_group: MMU 1000005280.iommu Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: Adding to iommu group 2 Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: bcm2712_iommu_attach_dev: MMU 1000005280.iommu Oct 16 15:06:04 volumio-4 kernel: v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform! Oct 16 15:06:04 volumio-4 kernel: [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000/bridge@0 Oct 16 15:06:04 volumio-4 kernel: /panel_disp@1: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000/bridge@0 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /axi/pcie@1000120000/rp1/dsi@128000 Oct 16 15:06:04 volumio-4 kernel: /axi/pcie@1000120000/rp1/dsi@128000/bridge@0: Fixed dependency cycle(s) with /panel_disp@1 Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: rp1dsi_host_attach: Attach DSI device name=tc358762 channel=0 lanes=1 format=0 flags=0x815 hs_rate=0 lp_rate=0 Oct 16 15:06:04 volumio-4 kernel: [drm] Initialized drm-rp1-dsi 1.0.0 for 1f00130000.dsi on minor 1 Oct 16 15:06:04 volumio-4 kernel: fuse: init (API version 7.41) Oct 16 15:06:04 volumio-4 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Command time (outvact): 33 Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal Byte clock 90000000 DPI clock 30000000 (parent rate 90000000) Oct 16 15:06:04 volumio-4 kernel: Console: switching to colour frame buffer device 100x30 Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: [drm] fb0: drm-rp1-dsidrmf frame buffer device Oct 16 15:06:04 volumio-4 kernel: drm-rp1-dsi 1f00130000.dsi: rp1dsi_bind succeeded Oct 16 15:06:04 volumio-4 kernel: EXT4-fs (mmcblk0p2): recovery complete Oct 16 15:06:04 volumio-4 kernel: EXT4-fs (mmcblk0p2): mounted filesystem 44379c43-1756-419c-8f0c-a49103976c4d r/w with ordered data mode. Quota mode: none. Oct 16 15:06:04 volumio-4 kernel: loop0: detected capacity change from 0 to 1390216 Oct 16 15:06:04 volumio-4 kernel: platform rp1_firmware: deferred probe pending: (reason unknown) Oct 16 15:06:04 volumio-4 kernel: platform 1f00178000.pio: deferred probe pending: rp1-pio: failed to find RP1 firmware driver Oct 16 15:06:04 volumio-4 kernel: EXT4-fs (mmcblk0p3): recovery complete Oct 16 15:06:04 volumio-4 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 63b827ef-c5d1-4a68-9975-b78edcf0a70c r/w with ordered data mode. Quota mode: none. Oct 16 15:06:04 volumio-4 kernel: NET: Registered PF_INET6 protocol family Oct 16 15:06:04 volumio-4 kernel: Segment Routing with IPv6 Oct 16 15:06:04 volumio-4 kernel: In-situ OAM (IOAM) with IPv6 Oct 16 15:06:04 volumio-4 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) Oct 16 15:06:04 volumio-4 systemd[1]: Detected architecture arm64. Oct 16 15:06:04 volumio-4 systemd[1]: Hostname set to . Oct 16 15:06:04 volumio-4 systemd[1]: Queued start job for default target graphical.target. Oct 16 15:06:04 volumio-4 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 16 15:06:04 volumio-4 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 16 15:06:04 volumio-4 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 16 15:06:04 volumio-4 systemd[1]: Created slice user.slice - User and Session Slice. Oct 16 15:06:04 volumio-4 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 16 15:06:04 volumio-4 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 16 15:06:04 volumio-4 systemd[1]: Expecting device dev-disk-by\x2duuid-30D3\x2d927D.device - /dev/disk/by-uuid/30D3-927D... Oct 16 15:06:04 volumio-4 systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10... Oct 16 15:06:04 volumio-4 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 16 15:06:04 volumio-4 systemd[1]: Reached target slices.target - Slice Units. Oct 16 15:06:04 volumio-4 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 16 15:06:04 volumio-4 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Oct 16 15:06:04 volumio-4 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 16 15:06:04 volumio-4 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 16 15:06:04 volumio-4 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 16 15:06:04 volumio-4 systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Oct 16 15:06:04 volumio-4 systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 16 15:06:04 volumio-4 systemd[1]: Reached target swap.target - Swaps. Oct 16 15:06:04 volumio-4 systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Oct 16 15:06:04 volumio-4 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 16 15:06:04 volumio-4 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 16 15:06:04 volumio-4 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 16 15:06:04 volumio-4 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 16 15:06:04 volumio-4 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 16 15:06:04 volumio-4 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 16 15:06:04 volumio-4 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 16 15:06:04 volumio-4 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 16 15:06:04 volumio-4 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 16 15:06:04 volumio-4 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 16 15:06:04 volumio-4 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 16 15:06:04 volumio-4 systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Oct 16 15:06:04 volumio-4 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 16 15:06:04 volumio-4 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 16 15:06:04 volumio-4 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 16 15:06:04 volumio-4 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 16 15:06:04 volumio-4 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 16 15:06:04 volumio-4 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 16 15:06:04 volumio-4 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 16 15:06:04 volumio-4 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 16 15:06:04 volumio-4 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 16 15:06:04 volumio-4 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 16 15:06:04 volumio-4 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 16 15:06:04 volumio-4 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 16 15:06:04 volumio-4 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:04 volumio-4 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 16 15:06:04 volumio-4 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 16 15:06:04 volumio-4 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 16 15:06:04 volumio-4 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 16 15:06:04 volumio-4 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 16 15:06:04 volumio-4 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 16 15:06:04 volumio-4 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Oct 16 15:06:04 volumio-4 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:04 volumio-4 systemd-journald[446]: Journal started Oct 16 15:06:04 volumio-4 systemd-journald[446]: Runtime Journal (/run/log/journal/b1962fb56bdd2928edaf232868689118) is 3.7M, max 30.0M, 26.2M free. Oct 16 15:06:04 volumio-4 systemd[1]: Started systemd-journald.service - Journal Service. Oct 16 15:06:04 volumio-4 kernel: i2c_dev: i2c /dev entries driver Oct 16 15:06:04 volumio-4 fake-hwclock[436]: Current system time: 2025-10-16 13:06:04 Oct 16 15:06:04 volumio-4 fake-hwclock[436]: fake-hwclock saved clock information is in the past: 2025-10-16 13:05:40 Oct 16 15:06:04 volumio-4 fake-hwclock[436]: To set system time to this saved clock anyway, use "force" Oct 16 15:06:04 volumio-4 systemd-modules-load[447]: Inserted module 'i2c_dev' Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 16 15:06:04 volumio-4 systemd[1]: dynamicswap.service: Deactivated successfully. Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 16 15:06:04 volumio-4 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 16 15:06:04 volumio-4 systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Oct 16 15:06:04 volumio-4 systemd-udevd[471]: Using default interface naming scheme 'v252'. Oct 16 15:06:04 volumio-4 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 16 15:06:04 volumio-4 systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Oct 16 15:06:04 volumio-4 systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Oct 16 15:06:04 volumio-4 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). Oct 16 15:06:04 volumio-4 systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Oct 16 15:06:04 volumio-4 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 16 15:06:04 volumio-4 systemd[1]: Reached target paths.target - Path Units. Oct 16 15:06:04 volumio-4 (udev-worker)[494]: Network interface NamePolicy= disabled on kernel command line. Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Oct 16 15:06:04 volumio-4 kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Oct 16 15:06:04 volumio-4 kernel: rp1-firmware rp1_firmware: RP1 Firmware version eb39cfd516f8c90628aa9d91f52370aade5d0a55 Oct 16 15:06:04 volumio-4 kernel: rp1-pio 1f00178000.pio: Created instance as pio0 Oct 16 15:06:04 volumio-4 systemd[1]: Found device dev-disk-by\x2duuid-30D3\x2d927D.device - /dev/disk/by-uuid/30D3-927D. Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-30D3\x2d927D.service - File System Check on /dev/disk/by-uuid/30D3-927D... Oct 16 15:06:04 volumio-4 systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Oct 16 15:06:04 volumio-4 systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Oct 16 15:06:04 volumio-4 kernel: mc: Linux media interface: v0.10 Oct 16 15:06:04 volumio-4 systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Oct 16 15:06:04 volumio-4 systemd[1]: Found device dev-ttyAMA10.device - /dev/ttyAMA10. Oct 16 15:06:04 volumio-4 kernel: edt_ft5x06 11-0038: supply iovcc not found, using dummy regulator Oct 16 15:06:04 volumio-4 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 16 15:06:04 volumio-4 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Oct 16 15:06:04 volumio-4 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Oct 16 15:06:04 volumio-4 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 16 15:06:04 volumio-4 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Oct 16 15:06:04 volumio-4 systemd[1]: Created slice system-systemd\x2dbacklight.slice - Slice /system/systemd-backlight. Oct 16 15:06:04 volumio-4 systemd-fsck[680]: fsck.fat 4.2 (2021-01-31) Oct 16 15:06:04 volumio-4 systemd-fsck[680]: /dev/mmcblk0p1: 432 files, 22812/93500 clusters Oct 16 15:06:04 volumio-4 systemd[1]: Starting systemd-backlight@backlight:11-0045.service - Load/Save Screen Backlight Brightness of backlight:11-0045... Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-30D3\x2d927D.service - File System Check on /dev/disk/by-uuid/30D3-927D. Oct 16 15:06:04 volumio-4 systemd[1]: Finished systemd-backlight@backlight:11-0045.service - Load/Save Screen Backlight Brightness of backlight:11-0045. Oct 16 15:06:04 volumio-4 systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 16 15:06:04 volumio-4 kernel: input: 11-0038 generic ft5x06 (79) as /devices/platform/axi/1000120000.pcie/1f00080000.i2c/i2c-11/11-0038/input/input1 Oct 16 15:06:05 volumio-4 kernel: videodev: Linux video capture interface: v2.00 Oct 16 15:06:05 volumio-4 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Oct 16 15:06:05 volumio-4 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Oct 16 15:06:05 volumio-4 kernel: usbcore: registered new interface driver brcmfmac Oct 16 15:06:05 volumio-4 kernel: Bluetooth: Core ver 2.22 Oct 16 15:06:05 volumio-4 kernel: NET: Registered PF_BLUETOOTH protocol family Oct 16 15:06:05 volumio-4 kernel: Bluetooth: HCI device and connection manager initialized Oct 16 15:06:05 volumio-4 kernel: Bluetooth: HCI socket layer initialized Oct 16 15:06:05 volumio-4 kernel: Bluetooth: L2CAP socket layer initialized Oct 16 15:06:05 volumio-4 kernel: Bluetooth: SCO socket layer initialized Oct 16 15:06:05 volumio-4 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: pispbe 1000880000.pisp_be: Adding to iommu group 0 Oct 16 15:06:05 volumio-4 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: rpi-hevc-dec 1000800000.codec: Adding to iommu group 0 Oct 16 15:06:05 volumio-4 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Oct 16 15:06:05 volumio-4 kernel: Bluetooth: HCI UART driver ver 2.3 Oct 16 15:06:05 volumio-4 kernel: Bluetooth: HCI UART protocol H4 registered Oct 16 15:06:05 volumio-4 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Oct 16 15:06:05 volumio-4 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Oct 16 15:06:05 volumio-4 kernel: Bluetooth: HCI UART protocol Broadcom registered Oct 16 15:06:05 volumio-4 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Oct 16 15:06:05 volumio-4 kernel: rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19 Oct 16 15:06:05 volumio-4 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). Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 16 15:06:05 volumio-4 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:05 volumio-4 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 16 15:06:05 volumio-4 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 16 15:06:05 volumio-4 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 16 15:06:05 volumio-4 systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Oct 16 15:06:05 volumio-4 systemd[1]: Mounting boot.mount - /boot... Oct 16 15:06:05 volumio-4 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Oct 16 15:06:05 volumio-4 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 16 15:06:05 volumio-4 systemd[1]: Mounting tmp.mount - /tmp... Oct 16 15:06:05 volumio-4 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 16 15:06:05 volumio-4 systemd[1]: Mounting var-log.mount - /var/log... Oct 16 15:06:05 volumio-4 systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Oct 16 15:06:05 volumio-4 systemd[1]: Mounted boot.mount - /boot. Oct 16 15:06:05 volumio-4 systemd[1]: Mounted tmp.mount - /tmp. Oct 16 15:06:05 volumio-4 systemd[1]: Mounted var-log.mount - /var/log. Oct 16 15:06:05 volumio-4 systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Oct 16 15:06:05 volumio-4 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 16 15:06:05 volumio-4 systemd-journald[446]: Runtime Journal (/run/log/journal/b1962fb56bdd2928edaf232868689118) is 3.7M, max 30.0M, 26.2M free. Oct 16 15:06:05 volumio-4 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 16 15:06:05 volumio-4 systemd-journald[446]: Received client request to flush runtime journal. Oct 16 15:06:05 volumio-4 (udev-worker)[501]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Oct 16 15:06:05 volumio-4 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Oct 16 15:06:05 volumio-4 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b Oct 16 15:06:05 volumio-4 systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Oct 16 15:06:05 volumio-4 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4_hvs 107c580000.hvs: Adding to iommu group 1 Oct 16 15:06:05 volumio-4 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: Adding to iommu group 1 Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Oct 16 15:06:05 volumio-4 kernel: Registered IR keymap rc-cec Oct 16 15:06:05 volumio-4 kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0 Oct 16 15:06:05 volumio-4 kernel: input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input2 Oct 16 15:06:05 volumio-4 kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card1/input3 Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Oct 16 15:06:05 volumio-4 kernel: Registered IR keymap rc-cec Oct 16 15:06:05 volumio-4 kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1 Oct 16 15:06:05 volumio-4 kernel: input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input4 Oct 16 15:06:05 volumio-4 systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Oct 16 15:06:05 volumio-4 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). Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 16 15:06:05 volumio-4 (udev-worker)[499]: Network interface NamePolicy= disabled on kernel command line. Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 16 15:06:05 volumio-4 kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card2/input5 Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 16 15:06:05 volumio-4 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:05 volumio-4 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 16 15:06:05 volumio-4 kernel: [drm] Initialized vc4 0.0.0 for axi:gpu on minor 2 Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 16 15:06:05 volumio-4 kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Oct 16 15:06:05 volumio-4 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). Oct 16 15:06:05 volumio-4 systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 16 15:06:05 volumio-4 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 16 15:06:05 volumio-4 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:05 volumio-4 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 16 15:06:05 volumio-4 systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 16 15:06:05 volumio-4 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 16 15:06:05 volumio-4 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 16 15:06:05 volumio-4 kernel: Bluetooth: hci0: BCM: chip id 107 Oct 16 15:06:05 volumio-4 kernel: Bluetooth: hci0: BCM: features 0x2f Oct 16 15:06:05 volumio-4 systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Oct 16 15:06:05 volumio-4 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 16 15:06:05 volumio-4 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 16 15:06:05 volumio-4 kernel: Bluetooth: hci0: BCM4345C0 Oct 16 15:06:05 volumio-4 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Oct 16 15:06:05 volumio-4 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,5-model-b.hcd' Patch Oct 16 15:06:06 volumio-4 kernel: Bluetooth: hci0: BCM: features 0x2f Oct 16 15:06:06 volumio-4 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Oct 16 15:06:06 volumio-4 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Oct 16 15:06:06 volumio-4 kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Oct 16 15:06:06 volumio-4 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Oct 16 15:06:06 volumio-4 systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Oct 16 15:06:06 volumio-4 systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 16 15:06:06 volumio-4 systemd[1]: Started ifup@eth0.service - ifup for eth0. Oct 16 15:06:06 volumio-4 systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Oct 16 15:06:06 volumio-4 systemd[1]: Starting networking.service - Raise network interfaces... Oct 16 15:06:06 volumio-4 systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Oct 16 15:06:06 volumio-4 systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Oct 16 15:06:06 volumio-4 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). Oct 16 15:06:06 volumio-4 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 16 15:06:06 volumio-4 systemd[1]: Received SIGRTMIN+20 from PID 317 (plymouthd). Oct 16 15:06:06 volumio-4 systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 876 (systemd-binfmt) Oct 16 15:06:06 volumio-4 systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Oct 16 15:06:06 volumio-4 systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Oct 16 15:06:06 volumio-4 systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Oct 16 15:06:06 volumio-4 systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Oct 16 15:06:06 volumio-4 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Oct 16 15:06:06 volumio-4 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 16 15:06:06 volumio-4 systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Oct 16 15:06:06 volumio-4 systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Oct 16 15:06:06 volumio-4 systemd[1]: Starting rpcbind.service - RPC bind portmap service... Oct 16 15:06:06 volumio-4 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 16 15:06:06 volumio-4 systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target rpc_pipefs.target. Oct 16 15:06:06 volumio-4 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). Oct 16 15:06:06 volumio-4 systemd[1]: Reached target nfs-client.target - NFS client services. Oct 16 15:06:06 volumio-4 sh[912]: dhcpcd-9.4.1 starting Oct 16 15:06:06 volumio-4 dhcpcd[912]: dhcpcd-9.4.1 starting Oct 16 15:06:06 volumio-4 sh[912]: DUID 00:01:00:01:2f:fb:4f:7f:2c:cf:67:8d:b7:8e Oct 16 15:06:06 volumio-4 dhcpcd[920]: DUID 00:01:00:01:2f:fb:4f:7f:2c:cf:67:8d:b7:8e Oct 16 15:06:06 volumio-4 dhcpcd[920]: dhcp_vendor: No such process Oct 16 15:06:06 volumio-4 sh[912]: dhcp_vendor: No such process Oct 16 15:06:06 volumio-4 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target sysinit.target - System Initialization. Oct 16 15:06:06 volumio-4 haveged[911]: haveged: command socket is listening at fd 3 Oct 16 15:06:06 volumio-4 systemd[1]: Started apt-daily.timer - Daily apt download activities. Oct 16 15:06:06 volumio-4 systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Oct 16 15:06:06 volumio-4 systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Oct 16 15:06:06 volumio-4 systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Oct 16 15:06:06 volumio-4 systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Oct 16 15:06:06 volumio-4 systemd[1]: Started man-db.timer - Daily man-db regeneration. Oct 16 15:06:06 volumio-4 systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Oct 16 15:06:06 volumio-4 systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Oct 16 15:06:06 volumio-4 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target timers.target - Timer Units. Oct 16 15:06:06 volumio-4 systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Oct 16 15:06:06 volumio-4 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 16 15:06:06 volumio-4 systemd[1]: Listening on lircd.socket. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target sockets.target - Socket Units. Oct 16 15:06:06 volumio-4 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). Oct 16 15:06:06 volumio-4 systemd[1]: Reached target basic.target - Basic System. Oct 16 15:06:06 volumio-4 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). Oct 16 15:06:06 volumio-4 kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 16 15:06:06 volumio-4 systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Oct 16 15:06:06 volumio-4 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 16 15:06:06 volumio-4 systemd[1]: Starting bluetooth.service - Bluetooth service... Oct 16 15:06:06 volumio-4 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 16 15:06:06 volumio-4 systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Oct 16 15:06:06 volumio-4 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). Oct 16 15:06:06 volumio-4 systemd[1]: Starting glamor-test.service - Check for glamor... Oct 16 15:06:06 volumio-4 systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Oct 16 15:06:06 volumio-4 systemd[1]: Started iptables.service - Volumio Iptables Module. Oct 16 15:06:06 volumio-4 systemd[1]: Starting rp1-test.service - Check for RP1 displays for Xorg... Oct 16 15:06:06 volumio-4 systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Oct 16 15:06:06 volumio-4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 15:06:06 volumio-4 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 16 15:06:06 volumio-4 systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Oct 16 15:06:06 volumio-4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:06:06 volumio-4 systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 16 15:06:06 volumio-4 systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Oct 16 15:06:06 volumio-4 systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Oct 16 15:06:06 volumio-4 systemd[1]: Starting wireless.service - Wireless Services... Oct 16 15:06:06 volumio-4 volumio_rfkill_unblock.sh[980]: Volumio WiFi Soft Blocked check script Oct 16 15:06:06 volumio-4 volumio_rfkill_unblock.sh[980]: Check if rfkill is available Oct 16 15:06:06 volumio-4 systemd[1]: Started rpcbind.service - RPC bind portmap service. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 16 15:06:06 volumio-4 volumio_rfkill_unblock.sh[980]: Check if rfkill listed devices are already unblocked Oct 16 15:06:06 volumio-4 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Oct 16 15:06:06 volumio-4 systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Oct 16 15:06:06 volumio-4 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). Oct 16 15:06:06 volumio-4 thd[968]: Unable to parse trigger line: Oct 16 15:06:06 volumio-4 thd[968]: Unable to parse trigger line: Oct 16 15:06:06 volumio-4 systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Oct 16 15:06:06 volumio-4 systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Oct 16 15:06:06 volumio-4 systemd[1]: e2scrub_reap.service: Deactivated successfully. Oct 16 15:06:06 volumio-4 systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Oct 16 15:06:06 volumio-4 alsactl[1005]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 16 15:06:06 volumio-4 alsactl[1005]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 16 15:06:06 volumio-4 alsactl[1005]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 16 15:06:06 volumio-4 systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target sound.target - Sound Card. Oct 16 15:06:06 volumio-4 volumio_rfkill_unblock.sh[980]: Wi-Fi is already unblocked. Oct 16 15:06:06 volumio-4 systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Oct 16 15:06:06 volumio-4 th-udev-rebind.sh[998]: Rebinding /dev/input/event0 to thd using --add... Oct 16 15:06:06 volumio-4 systemd-logind[965]: New seat seat0. Oct 16 15:06:06 volumio-4 systemd-logind[965]: Watching system buttons on /dev/input/event0 (pwr_button) Oct 16 15:06:06 volumio-4 systemd-logind[965]: Watching system buttons on /dev/input/event2 (vc4-hdmi-0) Oct 16 15:06:06 volumio-4 systemd-logind[965]: Watching system buttons on /dev/input/event4 (vc4-hdmi-1) Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Successfully dropped root privileges. Oct 16 15:06:06 volumio-4 avahi-daemon[945]: avahi-daemon 0.8 starting up. Oct 16 15:06:06 volumio-4 th-udev-rebind.sh[998]: Rebinding /dev/input/event1 to thd using --add... Oct 16 15:06:06 volumio-4 th-udev-rebind.sh[998]: Rebinding /dev/input/event2 to thd using --add... Oct 16 15:06:06 volumio-4 th-udev-rebind.sh[998]: Rebinding /dev/input/event3 to thd using --add... Oct 16 15:06:06 volumio-4 ifup[864]: ifup: waiting for lock on /run/network/ifstate.wlan0 Oct 16 15:06:06 volumio-4 th-udev-rebind.sh[998]: Rebinding /dev/input/event4 to thd using --add... Oct 16 15:06:06 volumio-4 th-udev-rebind.sh[998]: Rebinding /dev/input/event5 to thd using --add... Oct 16 15:06:06 volumio-4 rpi-eeprom-update[952]: WARNING: flashrom not found. Setting RPI_EEPROM_USE_FLASHROM to 0 Oct 16 15:06:06 volumio-4 sh[1033]: wlan0=wlan0 Oct 16 15:06:06 volumio-4 systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Oct 16 15:06:06 volumio-4 systemd[1]: th-udev-rebind.service: Deactivated successfully. Oct 16 15:06:06 volumio-4 systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Oct 16 15:06:06 volumio-4 bluetoothd[946]: Bluetooth daemon 5.83 Oct 16 15:06:06 volumio-4 dhcpcd[1023]: dhcpcd-9.4.1 starting Oct 16 15:06:06 volumio-4 dhcpcd[1067]: dev: loaded udev Oct 16 15:06:06 volumio-4 dhcpcd[1067]: DUID 00:01:00:01:2f:fb:4f:7f:2c:cf:67:8d:b7:8e Oct 16 15:06:06 volumio-4 dhcpcd[1067]: dhcp_vendor: No such process Oct 16 15:06:06 volumio-4 dhcpcd[1023]: dhcp_vendor: No such process Oct 16 15:06:06 volumio-4 systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Oct 16 15:06:06 volumio-4 systemd[1]: Finished networking.service - Raise network interfaces. Oct 16 15:06:06 volumio-4 kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Oct 16 15:06:06 volumio-4 kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Oct 16 15:06:06 volumio-4 sh[912]: eth0: waiting for carrier Oct 16 15:06:06 volumio-4 dhcpcd[920]: eth0: waiting for carrier Oct 16 15:06:06 volumio-4 kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Oct 16 15:06:06 volumio-4 systemd[1]: Finished glamor-test.service - Check for glamor. Oct 16 15:06:06 volumio-4 dhcpcd[1067]: eth0: waiting for carrier Oct 16 15:06:06 volumio-4 dhcpcd[1067]: wlan0: waiting for carrier Oct 16 15:06:06 volumio-4 loadcpufreq[990]: Loading cpufreq kernel modules...done (none). Oct 16 15:06:06 volumio-4 systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Successfully called chroot(). Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Successfully dropped remaining capabilities. Oct 16 15:06:06 volumio-4 systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Oct 16 15:06:06 volumio-4 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Loading service file /services/volumio.service. Oct 16 15:06:06 volumio-4 systemd[1]: Finished rp1-test.service - Check for RP1 displays for Xorg. Oct 16 15:06:06 volumio-4 dbus-daemon[947]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 16 15:06:06 volumio-4 systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Oct 16 15:06:06 volumio-4 systemd[1]: Started bluetooth.service - Bluetooth service. Oct 16 15:06:06 volumio-4 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 16 15:06:06 volumio-4 systemd[1]: Started systemd-logind.service - User Login Management. Oct 16 15:06:06 volumio-4 bluetoothd[946]: Starting SDP server Oct 16 15:06:06 volumio-4 bluetoothd[946]: Excluding (cli) sap Oct 16 15:06:06 volumio-4 bluetoothd[946]: Excluding (cli) midi Oct 16 15:06:06 volumio-4 bluetoothd[946]: Excluding (cli) battery Oct 16 15:06:06 volumio-4 systemd[1]: iptables.service: Deactivated successfully. Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 16 15:06:06 volumio-4 avahi-daemon[945]: New relevant interface lo.IPv4 for mDNS. Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Network interface enumeration completed. Oct 16 15:06:06 volumio-4 avahi-daemon[945]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 16 15:06:06 volumio-4 systemd[1]: Starting bluealsa.service - BlueALSA service... Oct 16 15:06:06 volumio-4 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 16 15:06:06 volumio-4 kernel: Bluetooth: BNEP filters: protocol multicast Oct 16 15:06:06 volumio-4 kernel: Bluetooth: BNEP socket layer initialized Oct 16 15:06:06 volumio-4 dbus-daemon[947]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=946 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 16 15:06:06 volumio-4 bluetoothd[946]: Bluetooth management interface 1.23 initialized Oct 16 15:06:06 volumio-4 bluetoothd[946]: Battery Provider Manager created Oct 16 15:06:06 volumio-4 kernel: Bluetooth: MGMT ver 1.23 Oct 16 15:06:06 volumio-4 kernel: NET: Registered PF_ALG protocol family Oct 16 15:06:06 volumio-4 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 16 15:06:06 volumio-4 cpufrequtils[1153]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 16 15:06:06 volumio-4 systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Oct 16 15:06:06 volumio-4 bluetoothd[946]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Oct 16 15:06:06 volumio-4 bluetoothd[946]: bap: Operation not supported (95) Oct 16 15:06:06 volumio-4 bluetoothd[946]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Oct 16 15:06:06 volumio-4 dbus-daemon[947]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 16 15:06:06 volumio-4 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 16 15:06:06 volumio-4 haveged[911]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Oct 16 15:06:06 volumio-4 haveged[911]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356 Oct 16 15:06:06 volumio-4 haveged[911]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99803 Oct 16 15:06:06 volumio-4 haveged[911]: haveged: fills: 0, generated: 0 Oct 16 15:06:06 volumio-4 systemd[1]: Started wpa_supplicant.service - WPA supplicant. Oct 16 15:06:06 volumio-4 wpa_supplicant[1157]: Successfully initialized wpa_supplicant Oct 16 15:06:06 volumio-4 systemd[1]: Reached target network.target - Network. Oct 16 15:06:06 volumio-4 systemd[1]: Reached target network-online.target - Network is Online. Oct 16 15:06:06 volumio-4 volumio-remote-updater[975]: Volumio OTA Client: 1.7.0 Oct 16 15:06:06 volumio-4 volumio-remote-updater[975]: Error: No active session Oct 16 15:06:06 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:06] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 16 15:06:06 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:06] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 16 15:06:06 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:06] [error] handle_connect error: Connection refused Oct 16 15:06:06 volumio-4 rpi-eeprom-update[952]: BOOTLOADER: up to date Oct 16 15:06:06 volumio-4 rpi-eeprom-update[952]: CURRENT: Thu May 8 14:13:17 UTC 2025 (1746713597) Oct 16 15:06:06 volumio-4 rpi-eeprom-update[952]: LATEST: Thu May 8 14:13:17 UTC 2025 (1746713597) Oct 16 15:06:06 volumio-4 rpi-eeprom-update[952]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2712/default) Oct 16 15:06:06 volumio-4 rpi-eeprom-update[952]: Use raspi-config to change the release. Oct 16 15:06:06 volumio-4 systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Oct 16 15:06:06 volumio-4 systemd[1]: Starting nmbd.service - Samba NMB Daemon... Oct 16 15:06:07 volumio-4 systemd[1]: Starting ntpsec.service - Network Time Service... Oct 16 15:06:07 volumio-4 systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Oct 16 15:06:07 volumio-4 systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Oct 16 15:06:07 volumio-4 systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Oct 16 15:06:07 volumio-4 sm-notify[1202]: Version 2.6.2 starting Oct 16 15:06:07 volumio-4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 16 15:06:07 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 15:06:07 volumio-4 upmpdcli[973]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:06:07 volumio-4 systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Oct 16 15:06:07 volumio-4 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 16 15:06:07 volumio-4 systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Oct 16 15:06:07 volumio-4 systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 16 15:06:07 volumio-4 systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Oct 16 15:06:07 volumio-4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:06:07 volumio-4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:06:07 volumio-4 systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Oct 16 15:06:07 volumio-4 systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Oct 16 15:06:07 volumio-4 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 16 15:06:07 volumio-4 systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Oct 16 15:06:07 volumio-4 ntpd[1211]: INIT: ntpd ntpsec-1.2.2: Starting Oct 16 15:06:07 volumio-4 ntpd[1211]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 16 15:06:07 volumio-4 ntp-systemd-wrapper[1211]: 2025-10-16T15:06:07 ntpd[1211]: INIT: ntpd ntpsec-1.2.2: Starting Oct 16 15:06:07 volumio-4 ntp-systemd-wrapper[1211]: 2025-10-16T15:06:07 ntpd[1211]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 16 15:06:07 volumio-4 systemd[1]: Started ntpsec.service - Network Time Service. Oct 16 15:06:07 volumio-4 welcome[1219]: Resolved ip:[0] Oct 16 15:06:07 volumio-4 ifplugd(eth0)[1245]: ifplugd 0.28 initializing. Oct 16 15:06:07 volumio-4 ifplugd(eth0)[1245]: Using interface eth0/2C:CF:67:8D:B7:8D with driver (version: 6.12.47-v8+) Oct 16 15:06:07 volumio-4 ifplugd(eth0)[1245]: Using detection mode: SIOCETHTOOL Oct 16 15:06:07 volumio-4 ifplugd(eth0)[1245]: Initialization complete, link beat not detected. Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: precision = 0.370 usec (-21) Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: successfully locked into RAM Oct 16 15:06:07 volumio-4 ntpd[1233]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Oct 16 15:06:07 volumio-4 ntpd[1233]: CONFIG: restrict nopeer ignored Oct 16 15:06:07 volumio-4 ntpd[1233]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 16 15:06:07 volumio-4 ntpd[1233]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: Using SO_TIMESTAMPNS(ns) Oct 16 15:06:07 volumio-4 ntpd[1233]: IO: Listen and drop on 0 v6wildcard [::]:123 Oct 16 15:06:07 volumio-4 ntpd[1233]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 16 15:06:07 volumio-4 ntpd[1233]: IO: Listen normally on 2 lo 127.0.0.1:123 Oct 16 15:06:07 volumio-4 ntpd[1233]: IO: Listening on routing socket on fd #19 for interface updates Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: This system has a 32-bit time_t. Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Oct 16 15:06:07 volumio-4 ntpd[1233]: INIT: Running with OpenSSL 3.0.17 1 Jul 2025, 30000110 Oct 16 15:06:07 volumio-4 ntpd[1233]: NTSc: Using system default root certificates. Oct 16 15:06:07 volumio-4 systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 16 15:06:07 volumio-4 ifplugd[1191]: Network Interface Plugging Daemon...start eth0...done. Oct 16 15:06:07 volumio-4 systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Oct 16 15:06:07 volumio-4 sshd[1249]: Server listening on 0.0.0.0 port 22. Oct 16 15:06:07 volumio-4 sshd[1249]: Server listening on :: port 22. Oct 16 15:06:07 volumio-4 systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Oct 16 15:06:07 volumio-4 ntpd[1233]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Oct 16 15:06:07 volumio-4 bluealsa[1160]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Oct 16 15:06:07 volumio-4 bluealsa[1160]: main.c:663: Starting main dispatching loop Oct 16 15:06:07 volumio-4 systemd[1]: Started bluealsa.service - BlueALSA service. Oct 16 15:06:07 volumio-4 systemd[1]: Reached target bluetooth.target - Bluetooth Support. Oct 16 15:06:07 volumio-4 bluealsa[1160]: main.c:118: Acquired D-Bus service name: org.bluealsa Oct 16 15:06:07 volumio-4 bluealsa[1160]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Oct 16 15:06:07 volumio-4 bluealsa[1160]: bluez.c:153: Registering media application: /org/bluez/hci0 Oct 16 15:06:07 volumio-4 bluealsa[1160]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Oct 16 15:06:07 volumio-4 bluealsa[1160]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Oct 16 15:06:07 volumio-4 bluealsa[1160]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Oct 16 15:06:07 volumio-4 bluetoothd[946]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 16 15:06:07 volumio-4 bluealsa[1160]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Oct 16 15:06:07 volumio-4 bluetoothd[946]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 16 15:06:07 volumio-4 bluetoothd[946]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 16 15:06:07 volumio-4 bluetoothd[946]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 16 15:06:07 volumio-4 avahi-daemon[945]: Server startup complete. Host name is volumio-4.local. Local service cookie is 169973731. Oct 16 15:06:07 volumio-4 systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Oct 16 15:06:07 volumio-4 systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Oct 16 15:06:07 volumio-4 wireless.js[983]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Oct 16 15:06:07 volumio-4 nmbd[1282]: [2025/10/16 15:06:07.748666, 0] ../../source3/nmbd/nmbd.c:901(main) Oct 16 15:06:07 volumio-4 wireless.js[983]: WIRELESS.JS: Wireless.js initializing wireless flow Oct 16 15:06:07 volumio-4 wireless.js[983]: WIRELESS.JS: Cleaning previous... Oct 16 15:06:07 volumio-4 nmbd[1282]: nmbd version 4.17.8-Raspbian started. Oct 16 15:06:07 volumio-4 nmbd[1282]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 16 15:06:07 volumio-4 nmbd[1282]: [2025/10/16 15:06:07.754611, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 16 15:06:07 volumio-4 nmbd[1282]: started asyncdns process 1290 Oct 16 15:06:07 volumio-4 nmbd[1282]: [2025/10/16 15:06:07.755390, 0] ../../lib/util/become_daemon.c:150(daemon_status) Oct 16 15:06:07 volumio-4 nmbd[1282]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 16 15:06:07 volumio-4 nmbd[1282]: [2025/10/16 15:06:07.755562, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Oct 16 15:06:07 volumio-4 nmbd[1282]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 16 15:06:07 volumio-4 sudo[1292]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Oct 16 15:06:07 volumio-4 sudo[1292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 15:06:07 volumio-4 sudo[1292]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:07 volumio-4 sudo[1294]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Oct 16 15:06:07 volumio-4 sudo[1294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 15:06:08 volumio-4 ntpd[1233]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:08 volumio-4 avahi-daemon[945]: Service "Volumio 4" (/services/volumio.service) successfully established. Oct 16 15:06:08 volumio-4 ntpd[1233]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 16 15:06:08 volumio-4 ntpd[1233]: DNS: dns_check: DNS error: -11, System error Oct 16 15:06:08 volumio-4 ntpd[1233]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Oct 16 15:06:08 volumio-4 sudo[1294]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:08 volumio-4 wireless.js[983]: WIRELESS.JS: Stopped aP Oct 16 15:06:08 volumio-4 sudo[1309]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 15:06:08 volumio-4 sudo[1309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:08 volumio-4 sudo[1309]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:08 volumio-4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 15:06:08 volumio-4 sudo[1311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 16 15:06:08 volumio-4 sudo[1311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:08 volumio-4 sudo[1311]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:08 volumio-4 sudo[1319]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 15:06:08 volumio-4 sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:08 volumio-4 sudo[1319]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:08 volumio-4 sudo[1321]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 16 15:06:08 volumio-4 sudo[1321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:09 volumio-4 ntpd[1233]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:09 volumio-4 ntpd[1233]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 16 15:06:09 volumio-4 ntpd[1233]: DNS: dns_check: DNS error: -11, System error Oct 16 15:06:09 volumio-4 ntpd[1233]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Oct 16 15:06:10 volumio-4 ntpd[1233]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:10 volumio-4 ntpd[1233]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 16 15:06:10 volumio-4 ntpd[1233]: DNS: dns_check: DNS error: -11, System error Oct 16 15:06:10 volumio-4 ntpd[1233]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Oct 16 15:06:10 volumio-4 systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 16 15:06:11 volumio-4 sudo[1321]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:11 volumio-4 wireless.js[983]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: DE Oct 16 15:06:11 volumio-4 sudo[1346]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 15:06:11 volumio-4 sudo[1346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:11 volumio-4 sudo[1346]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:11 volumio-4 sudo[1348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Oct 16 15:06:11 volumio-4 sudo[1348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:11 volumio-4 sudo[1348]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:11 volumio-4 wireless.js[983]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: DE Oct 16 15:06:11 volumio-4 wireless.js[983]: WIRELESS.JS: Start wireless flow Oct 16 15:06:11 volumio-4 wireless.js[983]: WIRELESS.JS: Stopped hotspot (if there).. Oct 16 15:06:11 volumio-4 sudo[1357]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Oct 16 15:06:11 volumio-4 sudo[1357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 15:06:11 volumio-4 sudo[1357]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:11 volumio-4 sudo[1359]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Oct 16 15:06:11 volumio-4 sudo[1359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 15:06:11 volumio-4 ntpd[1233]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:11 volumio-4 ntpd[1233]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 16 15:06:11 volumio-4 ntpd[1233]: DNS: dns_check: DNS error: -11, System error Oct 16 15:06:11 volumio-4 ntpd[1233]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Oct 16 15:06:11 volumio-4 sudo[1359]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:11 volumio-4 wireless.js[983]: WIRELESS.JS: DHCP IP fallback Oct 16 15:06:11 volumio-4 wireless.js[983]: WIRELESS.JS: Start ap Oct 16 15:06:11 volumio-4 wpa_supplicant[1365]: Successfully initialized wpa_supplicant Oct 16 15:06:11 volumio-4 sudo[1366]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 16 15:06:11 volumio-4 sudo[1366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 15:06:11 volumio-4 sudo[1366]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:11 volumio-4 dhcpcd[1067]: control command: /sbin/dhcpcd Oct 16 15:06:11 volumio-4 dhcpcd[1067]: control_free: No such file or directory Oct 16 15:06:11 volumio-4 dhcpcd[1067]: lo: IAID 00:00:00:00 Oct 16 15:06:11 volumio-4 dhcpcd[1067]: lo: ipv6_start: Operation not supported Oct 16 15:06:11 volumio-4 dhcpcd[1067]: lo: using static address 127.0.0.1/8 Oct 16 15:06:11 volumio-4 dhcpcd[1067]: lo: adding route to 127.0.0.0/8 Oct 16 15:06:11 volumio-4 systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Oct 16 15:06:11 volumio-4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 15:06:11 volumio-4 wpa_supplicant[1365]: nl80211: kernel reports: Registration to specific type not supported Oct 16 15:06:11 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:11] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 16 15:06:11 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:11] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 16 15:06:11 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:11] [error] handle_connect error: Connection refused Oct 16 15:06:12 volumio-4 bash[1206]: setdatetime-helper: all HTTPS Date fallbacks failed Oct 16 15:06:12 volumio-4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 16 15:06:12 volumio-4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 16 15:06:12 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:12 volumio-4 sudo[1393]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:12 volumio-4 sudo[1393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:12 volumio-4 sudo[1393]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:13 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:13 volumio-4 sudo[1397]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:13 volumio-4 sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:13 volumio-4 sudo[1397]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:14 volumio-4 wpa_supplicant[1389]: wlan0: Trying to associate with 30:68:93:52:88:77 (SSID='linkwifi' freq=5180 MHz) Oct 16 15:06:14 volumio-4 wpa_supplicant[1389]: wlan0: Associated with 30:68:93:52:88:77 Oct 16 15:06:14 volumio-4 wpa_supplicant[1389]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 16 15:06:14 volumio-4 wpa_supplicant[1389]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 16 15:06:14 volumio-4 wpa_supplicant[1389]: wlan0: WPA: Key negotiation completed with 30:68:93:52:88:77 [PTK=CCMP GTK=CCMP] Oct 16 15:06:14 volumio-4 wpa_supplicant[1389]: wlan0: CTRL-EVENT-CONNECTED - Connection to 30:68:93:52:88:77 completed [id=0 id_str=] Oct 16 15:06:14 volumio-4 dhcpcd[1067]: wlan0: carrier acquired Oct 16 15:06:14 volumio-4 dhcpcd[1067]: wlan0: connected to Access Point: linkwifi Oct 16 15:06:14 volumio-4 dhcpcd[1067]: wlan0: IAID 67:8d:b7:8e Oct 16 15:06:14 volumio-4 dhcpcd[1067]: wlan0: adding address fe80::2ecf:67ff:fe8d:b78e Oct 16 15:06:14 volumio-4 dhcpcd[1067]: ipv6_addaddr1: Permission denied Oct 16 15:06:14 volumio-4 dhcpcd[1067]: wlan0: probing address 192.168.88.191/24 Oct 16 15:06:14 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:14 volumio-4 sudo[1403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:14 volumio-4 sudo[1403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:14 volumio-4 sudo[1403]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:15 volumio-4 dhcpcd[1067]: wlan0: soliciting an IPv6 router Oct 16 15:06:15 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:15 volumio-4 sudo[1407]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:15 volumio-4 sudo[1407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:15 volumio-4 sudo[1407]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:16 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:16 volumio-4 sudo[1411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:16 volumio-4 sudo[1411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:16 volumio-4 sudo[1411]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:17 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:17 volumio-4 sudo[1415]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:17 volumio-4 sudo[1415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:17 volumio-4 sudo[1415]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:18 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:18 volumio-4 sudo[1419]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:18 volumio-4 sudo[1419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:18 volumio-4 sudo[1419]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:19 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:19] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 16 15:06:19 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:19] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 16 15:06:19 volumio-4 volumio-remote-updater[975]: [2025-10-16 15:06:19] [error] handle_connect error: Connection refused Oct 16 15:06:19 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:19 volumio-4 sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:19 volumio-4 sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:19 volumio-4 sudo[1423]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:20 volumio-4 dhcpcd[1067]: wlan0: using static address 192.168.88.191/24 Oct 16 15:06:20 volumio-4 avahi-daemon[945]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.88.191. Oct 16 15:06:20 volumio-4 avahi-daemon[945]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 15:06:20 volumio-4 avahi-daemon[945]: Registering new address record for 192.168.88.191 on wlan0.IPv4. Oct 16 15:06:20 volumio-4 dhcpcd[1067]: wlan0: adding route to 192.168.88.0/24 Oct 16 15:06:20 volumio-4 dhcpcd[1067]: wlan0: adding default route via 192.168.88.1 Oct 16 15:06:20 volumio-4 systemd[1]: welcome.service: Deactivated successfully. Oct 16 15:06:20 volumio-4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 16 15:06:20 volumio-4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 16 15:06:20 volumio-4 systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 16 15:06:20 volumio-4 welcome[1435]: Resolved ip:[1] 192.168.88.191 Oct 16 15:06:20 volumio-4 systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 16 15:06:20 volumio-4 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 16 15:06:20 volumio-4 systemd[1]: Started nmbd.service - Samba NMB Daemon. Oct 16 15:06:20 volumio-4 nmbd[1282]: [2025/10/16 15:06:20.298705, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 16 15:06:20 volumio-4 nmbd[1282]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.88.191 for name WORKGROUP<1d>. Oct 16 15:06:20 volumio-4 nmbd[1282]: This response was from IP 192.168.88.30, reporting an IP address of 192.168.88.30. Oct 16 15:06:20 volumio-4 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Oct 16 15:06:20 volumio-4 winbindd[1452]: [2025/10/16 15:06:20.422168, 0] ../../source3/winbindd/winbindd.c:1440(main) Oct 16 15:06:20 volumio-4 winbindd[1452]: winbindd version 4.17.8-Raspbian started. Oct 16 15:06:20 volumio-4 winbindd[1452]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 16 15:06:20 volumio-4 winbindd[1452]: [2025/10/16 15:06:20.426195, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Oct 16 15:06:20 volumio-4 winbindd[1452]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 16 15:06:20 volumio-4 systemd[1]: Started winbind.service - Samba Winbind Daemon. Oct 16 15:06:20 volumio-4 systemd[1]: Starting smbd.service - Samba SMB Daemon... Oct 16 15:06:20 volumio-4 smbd[1464]: [2025/10/16 15:06:20.586922, 0] ../../source3/smbd/server.c:1741(main) Oct 16 15:06:20 volumio-4 smbd[1464]: smbd version 4.17.8-Raspbian started. Oct 16 15:06:20 volumio-4 smbd[1464]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 16 15:06:20 volumio-4 systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 16 15:06:20 volumio-4 wireless.js[983]: WIRELESS.JS: trying... Oct 16 15:06:20 volumio-4 sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 15:06:20 volumio-4 sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:20 volumio-4 sudo[1480]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:20 volumio-4 wireless.js[983]: WIRELESS.JS: Connected to: ----linkwifi---- Oct 16 15:06:20 volumio-4 sudo[1483]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 15:06:20 volumio-4 sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 15:06:20 volumio-4 sudo[1483]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:20 volumio-4 wireless.js[983]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.88.191, ipV6 is undefined Oct 16 15:06:20 volumio-4 wireless.js[983]: WIRELESS.JS: It's done! AP Oct 16 15:06:20 volumio-4 wireless.js[983]: WIRELESS.JS: Restarting avahi-daemon... Oct 16 15:06:20 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 15:06:20 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 15:06:20 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 15:06:20 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.596s CPU time. Oct 16 15:06:20 volumio-4 avahi-daemon[945]: Got SIGTERM, quitting. Oct 16 15:06:20 volumio-4 systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 16 15:06:20 volumio-4 avahi-daemon[945]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.88.191. Oct 16 15:06:20 volumio-4 avahi-daemon[945]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 16 15:06:20 volumio-4 dbus-daemon[947]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.10' (uid=0 pid=1464 comm="/usr/sbin/smbd --foreground --no-process-group") Oct 16 15:06:20 volumio-4 avahi-daemon[945]: avahi-daemon 0.8 exiting. Oct 16 15:06:20 volumio-4 systemd[1]: avahi-daemon.service: Deactivated successfully. Oct 16 15:06:20 volumio-4 systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 16 15:06:20 volumio-4 wireless.js[983]: WIRELESS.JS: Notified systemd about wireless ready Oct 16 15:06:20 volumio-4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 15:06:20 volumio-4 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 16 15:06:20 volumio-4 systemd[1]: Started wireless.service - Wireless Services. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Process 945 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Successfully dropped root privileges. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: avahi-daemon 0.8 starting up. Oct 16 15:06:20 volumio-4 systemd[1]: Started volumio.service - Volumio Backend Module. Oct 16 15:06:20 volumio-4 dbus-daemon[947]: [system] Successfully activated service 'org.freedesktop.Avahi' Oct 16 15:06:20 volumio-4 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Successfully called chroot(). Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Successfully dropped remaining capabilities. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Loading service file /services/volumio.service. Oct 16 15:06:20 volumio-4 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.88.191. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: New relevant interface lo.IPv4 for mDNS. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Network interface enumeration completed. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Registering new address record for 192.168.88.191 on wlan0.IPv4. Oct 16 15:06:20 volumio-4 avahi-daemon[1492]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 16 15:06:20 volumio-4 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 16 15:06:20 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 15:06:20 volumio-4 systemd[1]: Received SIGRTMIN+21 from PID 317 (plymouthd). Oct 16 15:06:20 volumio-4 volumio-cpu-tweak[1496]: Setting RT Priority for mpd Oct 16 15:06:20 volumio-4 volumio-cpu-tweak[1511]: pid 35's current scheduling policy: SCHED_OTHER Oct 16 15:06:20 volumio-4 volumio-cpu-tweak[1511]: pid 35's current scheduling priority: 0 Oct 16 15:06:20 volumio-4 volumio-cpu-tweak[1496]: Setting MPD Affinity Oct 16 15:06:20 volumio-4 volumio-cpu-tweak[1525]: pid 3's current affinity mask: f Oct 16 15:06:20 volumio-4 volumio-cpu-tweak[1496]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 16 15:06:20 volumio-4 volumio-cpu-tweak[1496]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Oct 16 15:06:20 volumio-4 systemd[1]: Received SIGRTMIN+21 from PID 317 (plymouthd). Oct 16 15:06:20 volumio-4 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 16 15:06:20 volumio-4 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Oct 16 15:06:20 volumio-4 systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Oct 16 15:06:20 volumio-4 systemd[1]: Reached target getty.target - Login Prompts. Oct 16 15:06:20 volumio-4 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 16 15:06:20 volumio-4 systemd[1]: Reached target graphical.target - Graphical Interface. Oct 16 15:06:20 volumio-4 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 16 15:06:20 volumio-4 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 16 15:06:20 volumio-4 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 16 15:06:20 volumio-4 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 16 15:06:20 volumio-4 systemd[1]: Startup finished in 11.907s (kernel) + 17.167s (userspace) = 29.074s. Oct 16 15:06:21 volumio-4 ntpd[1233]: IO: Listen normally on 3 wlan0 192.168.88.191:123 Oct 16 15:06:21 volumio-4 ntpd[1233]: IO: new interface(s) found: waking up resolver Oct 16 15:06:21 volumio-4 ntpd[1233]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:21 volumio-4 ntpd[1233]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 16 15:06:21 volumio-4 ntpd[1233]: DNS: Pool taking: 194.28.110.28 Oct 16 15:06:21 volumio-4 ntpd[1233]: DNS: Pool taking: 217.91.44.17 Oct 16 15:06:21 volumio-4 ntpd[1233]: DNS: Pool taking: 185.252.140.126 Oct 16 15:06:21 volumio-4 ntpd[1233]: DNS: Pool taking: 81.0.124.200 Oct 16 15:06:21 volumio-4 ntpd[1233]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 16 15:06:21 volumio-4 volumio[1493]: info: ------------------------------------------- Oct 16 15:06:21 volumio-4 volumio[1493]: info: ----- Volumio3 ---- Oct 16 15:06:21 volumio-4 volumio[1493]: info: ------------------------------------------- Oct 16 15:06:21 volumio-4 volumio[1493]: info: ----- System startup ---- Oct 16 15:06:21 volumio-4 volumio[1493]: info: ------------------------------------------- Oct 16 15:06:21 volumio-4 avahi-daemon[1492]: Server startup complete. Host name is volumio-4.local. Local service cookie is 665798208. Oct 16 15:06:21 volumio-4 volumio[1493]: info: MYVOLUMIO Environment detected Oct 16 15:06:21 volumio-4 volumio[1493]: info: Plugin folders cleanup Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning into folder /volumio/app/plugins/ Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category audio_interface Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category miscellanea Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category music_service Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category plugins.json Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category system_controller Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category user_interface Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning into folder /data/plugins/ Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category music_service Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category system_controller Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category system_hardware Oct 16 15:06:21 volumio-4 volumio[1493]: info: Scanning category user_interface Oct 16 15:06:21 volumio-4 volumio[1493]: info: Plugin folders cleanup completed Oct 16 15:06:21 volumio-4 volumio[1493]: info: ------------------------------------------- Oct 16 15:06:21 volumio-4 volumio[1493]: info: ----- Core plugins startup ---- Oct 16 15:06:21 volumio-4 volumio[1493]: info: ------------------------------------------- Oct 16 15:06:21 volumio-4 volumio[1493]: info: Loading plugins from folder /volumio/app/plugins/ Oct 16 15:06:21 volumio-4 volumio[1493]: info: Adding plugin upnp to MyMusic Plugins Oct 16 15:06:21 volumio-4 volumio[1493]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 16 15:06:21 volumio-4 volumio[1493]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 16 15:06:21 volumio-4 volumio[1493]: info: Loading plugins from folder /data/plugins/ Oct 16 15:06:21 volumio-4 volumio[1493]: info: Loading plugin "system"... Oct 16 15:06:21 volumio-4 volumio[1493]: info: Loading plugin "appearance"... Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "network"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Refreshing Cached IP Addresses Oct 16 15:06:22 volumio-4 sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 15:06:22 volumio-4 sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:22 volumio-4 sudo[1547]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 94.198.159.16 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 103.149.168.180 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 37.152.14.161 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 45.32.4.67 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 2a02:8106:21:9400::6 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 2a03:8600::ff Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 2603:c020:8017:3eee::123 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: Pool taking: 2a02:168:420b:4::7b:12 Oct 16 15:06:22 volumio-4 ntpd[1233]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 16 15:06:22 volumio-4 sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 15:06:22 volumio-4 sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:22 volumio-4 sudo[1549]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "services"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "alsa_controller"... Oct 16 15:06:22 volumio-4 sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 16 15:06:22 volumio-4 sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:22 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "wizard"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "networkfs"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Starting Udev Watcher for removable devices Oct 16 15:06:22 volumio-4 sudo[1581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=carmel,password=alinco,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.88.148/Public/music /mnt/NAS/NAS Oct 16 15:06:22 volumio-4 sudo[1581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:22 volumio-4 volumio[1493]: info: Ignoring mount for partition: boot Oct 16 15:06:22 volumio-4 volumio[1493]: info: Ignoring mount for partition: volumio Oct 16 15:06:22 volumio-4 volumio[1493]: info: Ignoring mount for partition: volumio_data Oct 16 15:06:22 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "volumio_command_line_client"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "upnp"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: [1760619982399] Starting Upmpd Daemon Oct 16 15:06:22 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "my_music"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "mpd"... Oct 16 15:06:22 volumio-4 kernel: netfs: FS-Cache loaded Oct 16 15:06:22 volumio-4 kernel: Key type cifs.spnego registered Oct 16 15:06:22 volumio-4 kernel: Key type cifs.idmap registered Oct 16 15:06:22 volumio-4 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 16 15:06:22 volumio-4 kernel: CIFS: Attempting to mount //192.168.88.148/Public/music Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "upnp_browser"... Oct 16 15:06:22 volumio-4 avahi-daemon[1492]: Service "Volumio 4" (/services/volumio.service) successfully established. Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "alarm-clock"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "airplay_emulation"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Starting Shairport Sync Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "last_100"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "webradio"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "i2s_dacs"... Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "volumiodiscovery"... Oct 16 15:06:22 volumio-4 volumio[1493]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 15:06:22 volumio-4 volumio[1493]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:06:22 volumio-4 volumio[1493]: *** WARNING *** For more information see Oct 16 15:06:22 volumio-4 volumio[1493]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 15:06:22 volumio-4 volumio[1493]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:06:22 volumio-4 volumio[1493]: *** WARNING *** For more information see Oct 16 15:06:22 volumio-4 node[1493]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 15:06:22 volumio-4 node[1493]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:06:22 volumio-4 node[1493]: *** WARNING *** For more information see Oct 16 15:06:22 volumio-4 node[1493]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 15:06:22 volumio-4 node[1493]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 15:06:22 volumio-4 node[1493]: *** WARNING *** For more information see Oct 16 15:06:22 volumio-4 volumio[1493]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 16 15:06:22 volumio-4 volumio[1493]: info: Discovery: Started advertising with name: Volumio 4 Oct 16 15:06:22 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 15:06:22 volumio-4 volumio[1493]: info: Loading plugin "ytmusic"... Oct 16 15:06:23 volumio-4 ntpd[1233]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:23 volumio-4 ntpd[1233]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 16 15:06:23 volumio-4 ntpd[1233]: DNS: Pool taking: 128.140.109.119 Oct 16 15:06:23 volumio-4 ntpd[1233]: DNS: Pool taking: 176.31.251.158 Oct 16 15:06:23 volumio-4 ntpd[1233]: DNS: Pool taking: 83.243.68.157 Oct 16 15:06:23 volumio-4 ntpd[1233]: DNS: Pool taking: 89.111.54.85 Oct 16 15:06:23 volumio-4 ntpd[1233]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "now_playing"... Oct 16 15:06:24 volumio-4 ntpd[1233]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 15:06:24 volumio-4 ntpd[1233]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 16 15:06:24 volumio-4 ntpd[1233]: DNS: Pool taking: 193.57.144.50 Oct 16 15:06:24 volumio-4 ntpd[1233]: DNS: Pool taking: 85.24.237.71 Oct 16 15:06:24 volumio-4 ntpd[1233]: DNS: Pool taking: 85.163.168.227 Oct 16 15:06:24 volumio-4 ntpd[1233]: DNS: Pool taking: 95.216.138.141 Oct 16 15:06:24 volumio-4 ntpd[1233]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "outputs"... Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "albumart"... Oct 16 15:06:24 volumio-4 volumio[1493]: info: Plugin example_plugin is not enabled Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "inputs"... Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "updater_comm"... Oct 16 15:06:24 volumio-4 volumio[1493]: info: Plugin mpdemulation is not enabled Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "rest_api"... Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "websocket"... Oct 16 15:06:24 volumio-4 volumio[1493]: info: Starting Socket.io Server version 1.7.4 Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "backup_restore"... Oct 16 15:06:24 volumio-4 volumio[1602]: Forking 3 albumart workers Oct 16 15:06:24 volumio-4 volumio[1493]: info: Applying required configuration parameters for plugin backup_restore Oct 16 15:06:24 volumio-4 volumio[1493]: info: Loading plugin "gpio_control"... Oct 16 15:06:24 volumio-4 volumio[1493]: gpiox_arm32 V2.2.1 Oct 16 15:06:24 volumio-4 volumio[1613]: Starting albumart workers Oct 16 15:06:24 volumio-4 volumio[1612]: Starting albumart workers Oct 16 15:06:24 volumio-4 volumio[1614]: Starting albumart workers Oct 16 15:06:25 volumio-4 volumio[1493]: info: Applying required configuration parameters for plugin gpio_control Oct 16 15:06:25 volumio-4 volumio[1493]: info: Loading plugin "ir_controller"... Oct 16 15:06:25 volumio-4 volumio[1493]: info: Applying required configuration parameters for plugin ir_controller Oct 16 15:06:25 volumio-4 volumio[1493]: info: Loading plugin "rotaryencoder2"... Oct 16 15:06:25 volumio-4 volumio[1493]: info: Loading plugin "peppymeterbasic"... Oct 16 15:06:25 volumio-4 volumio[1493]: info: Plugin peppyspectrum is not enabled Oct 16 15:06:25 volumio-4 volumio[1493]: info: Loading plugin "touch_display"... Oct 16 15:06:25 volumio-4 volumio[1493]: info: Applying required configuration parameters for plugin touch_display Oct 16 15:06:25 volumio-4 volumio[1493]: info: Loading i18n strings for locale en Oct 16 15:06:25 volumio-4 volumio[1493]: Updating browse sources language Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::initPlayerControls Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: Express server listening on port 3000 Oct 16 15:06:25 volumio-4 volumio[1493]: [Metrics] WebUI: 4s 284.84ms Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::resetVolumioState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::getcurrentVolume Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 15:06:25 volumio-4 volumio[1493]: info: Volumio Network Manager: Network status updated: 2 Oct 16 15:06:25 volumio-4 volumio[1493]: verbose: New Socket.io Connection to 192.168.88.191 from 192.168.88.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 16 15:06:25 volumio-4 volumio[1493]: info: VolumeController:: Volume=37 Mute =false Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::pushState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CorePlayQueue::getTrack 0 Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioPushState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::updateTrackBlock Oct 16 15:06:25 volumio-4 volumio[1493]: info: CorePlayQueue::getTrackBlock Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:06:25 volumio-4 volumio[1493]: info: Reloading queue from file Oct 16 15:06:25 volumio-4 volumio[1493]: verbose: New Socket.io Connection to 192.168.88.191 from 192.168.88.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 16 15:06:25 volumio-4 volumio[1493]: info: Setting Device type: Raspberry PI Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::setRepeat true single undefined Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::pushState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CorePlayQueue::getTrack 0 Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioPushState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::setRandom false Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::pushState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CorePlayQueue::getTrack 0 Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioPushState Oct 16 15:06:25 volumio-4 volumio[1493]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 16 15:06:25 volumio-4 volumio[1493]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 16 15:06:25 volumio-4 volumio[1493]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Oct 16 15:06:25 volumio-4 volumio[1493]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 16 15:06:25 volumio-4 volumio[1493]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 16 15:06:25 volumio-4 volumio[1493]: info: VolumeController:: Volume=37 Mute =false Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreStateMachine::pushState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CorePlayQueue::getTrack 0 Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioPushState Oct 16 15:06:25 volumio-4 volumio[1493]: info: Discovery: adding 8c7e1a76-7eff-4a97-8a00-0e4b4cf16710 Oct 16 15:06:25 volumio-4 volumio[1493]: info: Discovery: Found device Volumio 4 Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioGetState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CorePlayQueue::getTrack 0 Oct 16 15:06:25 volumio-4 volumio[1493]: info: Discovery: this is already registered, 8c7e1a76-7eff-4a97-8a00-0e4b4cf16710 Oct 16 15:06:25 volumio-4 volumio[1493]: info: Discovery: Found device Volumio 4 Oct 16 15:06:25 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioGetState Oct 16 15:06:25 volumio-4 volumio[1493]: info: CorePlayQueue::getTrack 0 Oct 16 15:06:25 volumio-4 sudo[1561]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:27 volumio-4 sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 15:06:27 volumio-4 sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:27 volumio-4 sudo[1677]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:27 volumio-4 sudo[1679]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 15:06:27 volumio-4 sudo[1679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 15:06:27 volumio-4 sudo[1679]: pam_unix(sudo:session): session closed for user root Oct 16 15:06:27 volumio-4 volumio[1493]: verbose: New Socket.io Connection to 192.168.88.191 from 192.168.88.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 16 15:06:27 volumio-4 volumio[1493]: info: CoreCommandRouter::volumioGetState Oct 16 15:06:27 volumio-4 volumio[1493]: info: CorePlayQueue::getTrack 0 Oct 16 15:06:27 volumio-4 volumio[1493]: info: Listing playlists Oct 16 15:06:27 volumio-4 volumio[1493]: info: Listing playlists Oct 16 15:06:27 volumio-4 volumio[1493]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 15:06:27 volumio-4 volumio[1493]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:06:27 volumio-4 volumio[1493]: TypeError: Cannot read properties of undefined (reading 'logger') Oct 16 15:06:27 volumio-4 volumio[1493]: at YTMusicContext.getLogger (/data/plugins/music_service/ytmusic/dist/lib/YTMusicContext.js:65:80) Oct 16 15:06:27 volumio-4 volumio[1493]: at /data/plugins/music_service/ytmusic/dist/index.js:140:38 Oct 16 15:06:27 volumio-4 volumio[1493]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 16 15:06:27 volumio-4 volumio[1493]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:06:27 volumio-4 sudo[1696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-16 15:05' Oct 16 15:06:27 volumio-4 sudo[1696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"